builder: autoland_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-061 starttime: 1475079860.16 results: success (0) buildid: 20160928063005 builduid: ef7005bf99b04e00b2e18a2779f5f0cd revision: 4b65f0d1008a9abd3dc47252e939be93f725b6fe ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-28 09:24:20.158011) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-28 09:24:20.158525) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-28 09:24:20.158855) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-28 09:24:20.188785) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 09:24:20.189081) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=516f8d7ca085b4d087ab0a71000001a4-1475079860.723353-1385619047 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-28 09:24:21-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.5M=0s 2016-09-28 09:24:21 (46.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.113257 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 09:24:20.316236) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 09:24:20.316592) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=516f8d7ca085b4d087ab0a71000001a4-1475079860.723353-1385619047 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018232 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 09:24:20.366513) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-28 09:24:20.366893) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 4b65f0d1008a9abd3dc47252e939be93f725b6fe --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 4b65f0d1008a9abd3dc47252e939be93f725b6fe --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=516f8d7ca085b4d087ab0a71000001a4-1475079860.723353-1385619047 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-28 09:24:21,783 truncating revision to first 12 chars 2016-09-28 09:24:21,783 Setting DEBUG logging. 2016-09-28 09:24:21,783 attempt 1/10 2016-09-28 09:24:21,783 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/4b65f0d1008a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-28 09:24:23,378 unpacking tar archive at: autoland-4b65f0d1008a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.962543 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-28 09:24:22.343703) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-28 09:24:22.344128) ========= script_repo_revision: 4b65f0d1008a9abd3dc47252e939be93f725b6fe ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-28 09:24:22.344604) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-28 09:24:22.344940) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-28 09:24:22.354448) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-09-28 09:24:22.354772) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=516f8d7ca085b4d087ab0a71000001a4-1475079860.723353-1385619047 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:24:23 INFO - MultiFileLogger online at 20160928 09:24:23 in /builds/slave/test 09:24:23 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 09:24:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:24:23 INFO - {'append_to_log': False, 09:24:23 INFO - 'base_work_dir': '/builds/slave/test', 09:24:23 INFO - 'blob_upload_branch': 'Autoland', 09:24:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:24:23 INFO - 'branch': 'Autoland', 09:24:23 INFO - 'buildbot_json_path': 'buildprops.json', 09:24:23 INFO - 'config_files': ('talos/linux_config.py',), 09:24:23 INFO - 'default_actions': ('clobber', 09:24:23 INFO - 'read-buildbot-config', 09:24:23 INFO - 'download-and-extract', 09:24:23 INFO - 'populate-webroot', 09:24:23 INFO - 'create-virtualenv', 09:24:23 INFO - 'install', 09:24:23 INFO - 'run-tests'), 09:24:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:24:23 INFO - 'download_minidump_stackwalk': True, 09:24:23 INFO - 'download_symbols': 'ondemand', 09:24:23 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:24:23 INFO - 'tooltool.py': '/tools/tooltool.py', 09:24:23 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:24:23 INFO - '/tools/misc-python/virtualenv.py')}, 09:24:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:24:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:24:23 INFO - 'installer_path': 'installer.exe', 09:24:23 INFO - 'log_level': 'info', 09:24:23 INFO - 'log_name': 'talos', 09:24:23 INFO - 'log_to_console': True, 09:24:23 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:24:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:24:23 INFO - 'opt_config_files': (), 09:24:23 INFO - 'pip_index': False, 09:24:23 INFO - 'sps_profile': False, 09:24:23 INFO - 'sps_profile_interval': 0, 09:24:23 INFO - 'suite': 'dromaeojs-e10s', 09:24:23 INFO - 'system_bits': '32', 09:24:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:24:23 INFO - 'title': 'talos-linux64-ix-061', 09:24:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:24:23 INFO - 'use_talos_json': True, 09:24:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:24:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:24:23 INFO - 'work_dir': 'build'} 09:24:23 INFO - [mozharness: 2016-09-28 16:24:23.873605Z] Running clobber step. 09:24:23 INFO - Running pre-action listener: _resource_record_pre_action 09:24:23 INFO - Running main action method: clobber 09:24:23 INFO - rmtree: /builds/slave/test/build 09:24:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:24:24 INFO - Running post-action listener: _resource_record_post_action 09:24:24 INFO - [mozharness: 2016-09-28 16:24:24.847026Z] Finished clobber step (success) 09:24:24 INFO - [mozharness: 2016-09-28 16:24:24.847126Z] Running read-buildbot-config step. 09:24:24 INFO - Running pre-action listener: _resource_record_pre_action 09:24:24 INFO - Running main action method: read_buildbot_config 09:24:24 INFO - Using buildbot properties: 09:24:24 INFO - { 09:24:24 INFO - "project": "", 09:24:24 INFO - "product": "firefox", 09:24:24 INFO - "script_repo_revision": "production", 09:24:24 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs-e10s", 09:24:24 INFO - "repository": "", 09:24:24 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland pgo talos dromaeojs-e10s", 09:24:24 INFO - "buildid": "20160928063005", 09:24:24 INFO - "pgo_build": "True", 09:24:24 INFO - "basedir": "/builds/slave/test", 09:24:24 INFO - "buildnumber": 291, 09:24:24 INFO - "slavename": "talos-linux64-ix-061", 09:24:24 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 09:24:24 INFO - "platform": "ubuntu64_hw", 09:24:24 INFO - "scheduler": "tests-autoland-linux64-pgo-talos", 09:24:24 INFO - "branch": "autoland", 09:24:24 INFO - "repo_path": "integration/autoland", 09:24:24 INFO - "revision": "4b65f0d1008a9abd3dc47252e939be93f725b6fe", 09:24:24 INFO - "stage_platform": "linux64-pgo", 09:24:24 INFO - "builduid": "ef7005bf99b04e00b2e18a2779f5f0cd", 09:24:24 INFO - "slavebuilddir": "test" 09:24:24 INFO - } 09:24:24 INFO - Found installer url https://queue.taskcluster.net/v1/task/Xn_FpBo4QWKWV-sIXe4uWA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2. 09:24:24 INFO - Running post-action listener: _resource_record_post_action 09:24:24 INFO - [mozharness: 2016-09-28 16:24:24.849549Z] Finished read-buildbot-config step (success) 09:24:24 INFO - [mozharness: 2016-09-28 16:24:24.849637Z] Running download-and-extract step. 09:24:24 INFO - Running pre-action listener: _resource_record_pre_action 09:24:24 INFO - Running main action method: download_and_extract 09:24:24 INFO - mkdir: /builds/slave/test/build/tests 09:24:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:24 INFO - trying https://queue.taskcluster.net/v1/task/Xn_FpBo4QWKWV-sIXe4uWA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 09:24:24 INFO - Downloading https://queue.taskcluster.net/v1/task/Xn_FpBo4QWKWV-sIXe4uWA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 09:24:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Xn_FpBo4QWKWV-sIXe4uWA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 09:24:25 INFO - Downloaded 1361 bytes. 09:24:25 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 09:24:25 INFO - Using the following test package requirements: 09:24:25 INFO - {u'common': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 09:24:25 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 09:24:25 INFO - u'firefox-52.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:24:25 INFO - u'gtest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 09:24:25 INFO - u'firefox-52.0a1.en-US.linux-x86_64.gtest.tests.zip'], 09:24:25 INFO - u'jittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 09:24:25 INFO - u'jsshell-linux-x86_64.zip'], 09:24:25 INFO - u'mochitest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 09:24:25 INFO - u'firefox-52.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:24:25 INFO - u'mozbase': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 09:24:25 INFO - u'reftest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 09:24:25 INFO - u'firefox-52.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:24:25 INFO - u'talos': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 09:24:25 INFO - u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:24:25 INFO - u'web-platform': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 09:24:25 INFO - u'firefox-52.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:24:25 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 09:24:25 INFO - u'firefox-52.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:24:25 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:24:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Xn_FpBo4QWKWV-sIXe4uWA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 09:24:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Xn_FpBo4QWKWV-sIXe4uWA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:24:25 INFO - Fetch https://queue.taskcluster.net/v1/task/Xn_FpBo4QWKWV-sIXe4uWA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 09:24:25 INFO - Expected file size: 21984854 09:24:25 INFO - Content-Encoding None 09:24:25 INFO - Content-Type application/zip 09:24:25 INFO - Http code 200 09:24:30 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:24:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Xn_FpBo4QWKWV-sIXe4uWA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 09:24:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Xn_FpBo4QWKWV-sIXe4uWA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:24:30 INFO - Fetch https://queue.taskcluster.net/v1/task/Xn_FpBo4QWKWV-sIXe4uWA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 09:24:30 INFO - Expected file size: 21984854 09:24:30 INFO - Content-Encoding None 09:24:30 INFO - Content-Type application/zip 09:24:30 INFO - Http code 200 09:24:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Xn_FpBo4QWKWV-sIXe4uWA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip 09:24:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Xn_FpBo4QWKWV-sIXe4uWA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:24:35 INFO - Fetch https://queue.taskcluster.net/v1/task/Xn_FpBo4QWKWV-sIXe4uWA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip into memory 09:24:36 INFO - Expected file size: 14414057 09:24:36 INFO - Content-Encoding None 09:24:36 INFO - Content-Type application/zip 09:24:36 INFO - Http code 200 09:24:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:37 INFO - trying https://queue.taskcluster.net/v1/task/Xn_FpBo4QWKWV-sIXe4uWA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 09:24:37 INFO - Downloading https://queue.taskcluster.net/v1/task/Xn_FpBo4QWKWV-sIXe4uWA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 09:24:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Xn_FpBo4QWKWV-sIXe4uWA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:24:38 INFO - Downloaded 59701404 bytes. 09:24:38 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Xn_FpBo4QWKWV-sIXe4uWA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 09:24:38 INFO - mkdir: /builds/slave/test/properties 09:24:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:24:38 INFO - Writing to file /builds/slave/test/properties/build_url 09:24:38 INFO - Contents: 09:24:38 INFO - build_url:https://queue.taskcluster.net/v1/task/Xn_FpBo4QWKWV-sIXe4uWA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 09:24:39 INFO - Running post-action listener: _resource_record_post_action 09:24:39 INFO - Running post-action listener: set_extra_try_arguments 09:24:39 INFO - [mozharness: 2016-09-28 16:24:39.731727Z] Finished download-and-extract step (success) 09:24:39 INFO - [mozharness: 2016-09-28 16:24:39.731966Z] Running populate-webroot step. 09:24:39 INFO - Running pre-action listener: _resource_record_pre_action 09:24:39 INFO - Running main action method: populate_webroot 09:24:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:24:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:24:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:24:39 INFO - '--tppagecycles', 09:24:39 INFO - '7'], 09:24:39 INFO - 'tests': ['tsvgm']}}, 09:24:39 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:24:39 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:24:39 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:24:39 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:24:39 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:24:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:24:39 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:24:39 INFO - 'talos_options': ['--disable-e10s'], 09:24:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:24:39 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:24:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:24:39 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:24:39 INFO - 'talos_options': ['--disable-e10s'], 09:24:39 INFO - 'tests': ['damp', 'tps']}, 09:24:39 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:24:39 INFO - 'tests': ['damp', 'tps']}, 09:24:39 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:24:39 INFO - 'tests': ['dromaeo_dom']}, 09:24:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:24:39 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:24:39 INFO - 'tests': ['basic_compositor_video']}, 09:24:39 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:24:39 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:24:39 INFO - 'tests': ['a11yr', 09:24:39 INFO - 'ts_paint', 09:24:39 INFO - 'tpaint', 09:24:39 INFO - 'sessionrestore', 09:24:39 INFO - 'sessionrestore_no_auto_restore', 09:24:39 INFO - 'tabpaint']}, 09:24:39 INFO - 'other-e10s': {'tests': ['a11yr', 09:24:39 INFO - 'ts_paint', 09:24:39 INFO - 'tpaint', 09:24:39 INFO - 'sessionrestore', 09:24:39 INFO - 'sessionrestore_no_auto_restore', 09:24:39 INFO - 'tabpaint']}, 09:24:39 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:24:39 INFO - 'ts_paint', 09:24:39 INFO - 'tpaint', 09:24:39 INFO - 'sessionrestore', 09:24:39 INFO - 'sessionrestore_no_auto_restore', 09:24:39 INFO - 'tabpaint']}, 09:24:39 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:24:39 INFO - 'ts_paint', 09:24:39 INFO - 'tpaint', 09:24:39 INFO - 'sessionrestore', 09:24:39 INFO - 'sessionrestore_no_auto_restore', 09:24:39 INFO - 'tabpaint']}, 09:24:39 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:24:39 INFO - 'tests': ['a11yr', 09:24:39 INFO - 'ts_paint', 09:24:39 INFO - 'tpaint', 09:24:39 INFO - 'sessionrestore', 09:24:39 INFO - 'sessionrestore_no_auto_restore', 09:24:39 INFO - 'tabpaint']}, 09:24:39 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:24:39 INFO - 'tests': ['a11yr', 09:24:39 INFO - 'ts_paint', 09:24:39 INFO - 'tpaint', 09:24:39 INFO - 'sessionrestore', 09:24:39 INFO - 'sessionrestore_no_auto_restore', 09:24:39 INFO - 'tabpaint']}, 09:24:39 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:24:39 INFO - 'tests': ['tsvgx', 09:24:39 INFO - 'tsvgr_opacity', 09:24:39 INFO - 'tart', 09:24:39 INFO - 'tscrollx', 09:24:39 INFO - 'cart']}, 09:24:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:24:39 INFO - 'tsvgr_opacity', 09:24:39 INFO - 'tart', 09:24:39 INFO - 'tscrollx', 09:24:39 INFO - 'cart']}, 09:24:39 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:24:39 INFO - 'talos_options': ['--disable-e10s'], 09:24:39 INFO - 'tests': ['tp5o']}, 09:24:39 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:24:39 INFO - 'tests': ['tp5o']}, 09:24:39 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:24:39 INFO - 'talos_options': ['--disable-e10s', 09:24:39 INFO - '--xperf_path', 09:24:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:24:39 INFO - 'tests': ['tp5n']}, 09:24:39 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:24:39 INFO - 'talos_options': ['--xperf_path', 09:24:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:24:39 INFO - 'tests': ['tp5n']}}, 09:24:39 INFO - 'talos.zip': {'path': '', 09:24:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:24:39 INFO - Running post-action listener: _resource_record_post_action 09:24:39 INFO - [mozharness: 2016-09-28 16:24:39.754193Z] Finished populate-webroot step (success) 09:24:39 INFO - [mozharness: 2016-09-28 16:24:39.754281Z] Running create-virtualenv step. 09:24:39 INFO - Running pre-action listener: _resource_record_pre_action 09:24:39 INFO - Running main action method: create_virtualenv 09:24:39 INFO - Creating virtualenv /builds/slave/test/build/venv 09:24:39 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:24:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:24:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:24:39 INFO - Using real prefix '/usr' 09:24:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:24:40 INFO - Installing distribute.............................................................................................................................................................................................done. 09:24:42 INFO - Installing pip.................done. 09:24:42 INFO - Return code: 0 09:24:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:24:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:24:42 INFO - Reading from file tmpfile_stdout 09:24:42 INFO - Output received: 09:24:42 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:24:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:24:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f59494f2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a138a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ae0790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1add930>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ade830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1adecc0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1475079860.723353-1385619047', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:24:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:24:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:24:42 INFO - Using env: {'DISPLAY': ':0', 09:24:42 INFO - 'HOME': '/home/cltbld', 09:24:42 INFO - 'LANG': 'en_US.UTF-8', 09:24:42 INFO - 'LANGUAGE': 'en_US:en', 09:24:42 INFO - 'LOGNAME': 'cltbld', 09:24:42 INFO - 'MAIL': '/var/mail/cltbld', 09:24:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:42 INFO - 'MOZ_NO_REMOTE': '1', 09:24:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:24:42 INFO - 'NO_EM_RESTART': '1', 09:24:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:24:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:42 INFO - 'PWD': '/builds/slave/test', 09:24:42 INFO - 'SHELL': '/bin/bash', 09:24:42 INFO - 'SHLVL': '1', 09:24:42 INFO - 'TERM': 'linux', 09:24:42 INFO - 'TMOUT': '86400', 09:24:42 INFO - 'USER': 'cltbld', 09:24:42 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1475079860.723353-1385619047', 09:24:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:42 INFO - '_': '/tools/buildbot/bin/python'} 09:24:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:42 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:24:42 INFO - Cleaning up... 09:24:42 INFO - Return code: 0 09:24:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:24:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f59494f2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a138a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ae0790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1add930>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ade830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1adecc0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1475079860.723353-1385619047', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:24:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:24:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:24:42 INFO - Using env: {'DISPLAY': ':0', 09:24:42 INFO - 'HOME': '/home/cltbld', 09:24:42 INFO - 'LANG': 'en_US.UTF-8', 09:24:42 INFO - 'LANGUAGE': 'en_US:en', 09:24:42 INFO - 'LOGNAME': 'cltbld', 09:24:42 INFO - 'MAIL': '/var/mail/cltbld', 09:24:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:42 INFO - 'MOZ_NO_REMOTE': '1', 09:24:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:24:42 INFO - 'NO_EM_RESTART': '1', 09:24:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:24:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:42 INFO - 'PWD': '/builds/slave/test', 09:24:42 INFO - 'SHELL': '/bin/bash', 09:24:42 INFO - 'SHLVL': '1', 09:24:42 INFO - 'TERM': 'linux', 09:24:42 INFO - 'TMOUT': '86400', 09:24:42 INFO - 'USER': 'cltbld', 09:24:42 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1475079860.723353-1385619047', 09:24:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:42 INFO - '_': '/tools/buildbot/bin/python'} 09:24:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:42 INFO - Downloading/unpacking psutil>=3.1.1 09:24:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:24:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:24:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:24:44 INFO - Installing collected packages: psutil 09:24:44 INFO - Running setup.py install for psutil 09:24:44 INFO - building 'psutil._psutil_linux' extension 09:24:44 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 09:24:45 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 09:24:45 INFO - building 'psutil._psutil_posix' extension 09:24:45 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 09:24:45 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 09:24:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:24:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:24:45 INFO - Successfully installed psutil 09:24:45 INFO - Cleaning up... 09:24:45 INFO - Return code: 0 09:24:45 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:24:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f59494f2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a138a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ae0790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1add930>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ade830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1adecc0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1475079860.723353-1385619047', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:24:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:24:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:24:45 INFO - Using env: {'DISPLAY': ':0', 09:24:45 INFO - 'HOME': '/home/cltbld', 09:24:45 INFO - 'LANG': 'en_US.UTF-8', 09:24:45 INFO - 'LANGUAGE': 'en_US:en', 09:24:45 INFO - 'LOGNAME': 'cltbld', 09:24:45 INFO - 'MAIL': '/var/mail/cltbld', 09:24:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:45 INFO - 'MOZ_NO_REMOTE': '1', 09:24:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:24:45 INFO - 'NO_EM_RESTART': '1', 09:24:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:24:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:45 INFO - 'PWD': '/builds/slave/test', 09:24:45 INFO - 'SHELL': '/bin/bash', 09:24:45 INFO - 'SHLVL': '1', 09:24:45 INFO - 'TERM': 'linux', 09:24:45 INFO - 'TMOUT': '86400', 09:24:45 INFO - 'USER': 'cltbld', 09:24:45 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1475079860.723353-1385619047', 09:24:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:45 INFO - '_': '/tools/buildbot/bin/python'} 09:24:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:45 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:24:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:24:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:24:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:24:47 INFO - Installing collected packages: mozsystemmonitor 09:24:47 INFO - Running setup.py install for mozsystemmonitor 09:24:47 INFO - Successfully installed mozsystemmonitor 09:24:47 INFO - Cleaning up... 09:24:47 INFO - Return code: 0 09:24:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:24:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f59494f2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a138a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ae0790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1add930>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ade830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1adecc0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1475079860.723353-1385619047', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:24:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:24:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:24:47 INFO - Using env: {'DISPLAY': ':0', 09:24:47 INFO - 'HOME': '/home/cltbld', 09:24:47 INFO - 'LANG': 'en_US.UTF-8', 09:24:47 INFO - 'LANGUAGE': 'en_US:en', 09:24:47 INFO - 'LOGNAME': 'cltbld', 09:24:47 INFO - 'MAIL': '/var/mail/cltbld', 09:24:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:47 INFO - 'MOZ_NO_REMOTE': '1', 09:24:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:24:47 INFO - 'NO_EM_RESTART': '1', 09:24:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:24:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:47 INFO - 'PWD': '/builds/slave/test', 09:24:47 INFO - 'SHELL': '/bin/bash', 09:24:47 INFO - 'SHLVL': '1', 09:24:47 INFO - 'TERM': 'linux', 09:24:47 INFO - 'TMOUT': '86400', 09:24:47 INFO - 'USER': 'cltbld', 09:24:47 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1475079860.723353-1385619047', 09:24:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:47 INFO - '_': '/tools/buildbot/bin/python'} 09:24:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:47 INFO - Downloading/unpacking jsonschema==2.5.1 09:24:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:24:49 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:24:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:50 INFO - Downloading functools32-3.2.3-2.tar.gz 09:24:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:24:50 INFO - warning: no files found matching '*.txt' 09:24:50 INFO - no previously-included directories found matching 'build' 09:24:50 INFO - no previously-included directories found matching 'dist' 09:24:50 INFO - no previously-included directories found matching '.git*' 09:24:50 INFO - Installing collected packages: jsonschema, functools32 09:24:50 INFO - Running setup.py install for functools32 09:24:50 INFO - warning: no files found matching '*.txt' 09:24:50 INFO - no previously-included directories found matching 'build' 09:24:50 INFO - no previously-included directories found matching 'dist' 09:24:50 INFO - no previously-included directories found matching '.git*' 09:24:50 INFO - Successfully installed jsonschema functools32 09:24:50 INFO - Cleaning up... 09:24:50 INFO - Return code: 0 09:24:50 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:24:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f59494f2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a138a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ae0790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1add930>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ade830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1adecc0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1475079860.723353-1385619047', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:24:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:24:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:24:50 INFO - Using env: {'DISPLAY': ':0', 09:24:50 INFO - 'HOME': '/home/cltbld', 09:24:50 INFO - 'LANG': 'en_US.UTF-8', 09:24:50 INFO - 'LANGUAGE': 'en_US:en', 09:24:50 INFO - 'LOGNAME': 'cltbld', 09:24:50 INFO - 'MAIL': '/var/mail/cltbld', 09:24:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:50 INFO - 'MOZ_NO_REMOTE': '1', 09:24:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:24:50 INFO - 'NO_EM_RESTART': '1', 09:24:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:24:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:50 INFO - 'PWD': '/builds/slave/test', 09:24:50 INFO - 'SHELL': '/bin/bash', 09:24:50 INFO - 'SHLVL': '1', 09:24:50 INFO - 'TERM': 'linux', 09:24:50 INFO - 'TMOUT': '86400', 09:24:50 INFO - 'USER': 'cltbld', 09:24:50 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1475079860.723353-1385619047', 09:24:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:50 INFO - '_': '/tools/buildbot/bin/python'} 09:24:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:50 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:24:50 INFO - Cleaning up... 09:24:50 INFO - Return code: 0 09:24:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:24:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f59494f2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a138a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ae0790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1add930>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ade830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1adecc0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1475079860.723353-1385619047', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:24:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:24:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:24:50 INFO - Using env: {'DISPLAY': ':0', 09:24:50 INFO - 'HOME': '/home/cltbld', 09:24:50 INFO - 'LANG': 'en_US.UTF-8', 09:24:50 INFO - 'LANGUAGE': 'en_US:en', 09:24:50 INFO - 'LOGNAME': 'cltbld', 09:24:50 INFO - 'MAIL': '/var/mail/cltbld', 09:24:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:50 INFO - 'MOZ_NO_REMOTE': '1', 09:24:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:24:50 INFO - 'NO_EM_RESTART': '1', 09:24:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:24:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:50 INFO - 'PWD': '/builds/slave/test', 09:24:50 INFO - 'SHELL': '/bin/bash', 09:24:50 INFO - 'SHLVL': '1', 09:24:50 INFO - 'TERM': 'linux', 09:24:50 INFO - 'TMOUT': '86400', 09:24:50 INFO - 'USER': 'cltbld', 09:24:50 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1475079860.723353-1385619047', 09:24:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:50 INFO - '_': '/tools/buildbot/bin/python'} 09:24:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:50 INFO - Downloading/unpacking blobuploader==1.2.4 09:24:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:52 INFO - Downloading blobuploader-1.2.4.tar.gz 09:24:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:24:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:24:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:24:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:24:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:53 INFO - Downloading docopt-0.6.1.tar.gz 09:24:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:24:53 INFO - Installing collected packages: blobuploader, requests, docopt 09:24:53 INFO - Running setup.py install for blobuploader 09:24:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:24:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:24:53 INFO - Running setup.py install for requests 09:24:53 INFO - Running setup.py install for docopt 09:24:53 INFO - Successfully installed blobuploader requests docopt 09:24:53 INFO - Cleaning up... 09:24:54 INFO - Return code: 0 09:24:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:24:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f59494f2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a138a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ae0790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1add930>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ade830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1adecc0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1475079860.723353-1385619047', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:24:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:24:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:24:54 INFO - Using env: {'DISPLAY': ':0', 09:24:54 INFO - 'HOME': '/home/cltbld', 09:24:54 INFO - 'LANG': 'en_US.UTF-8', 09:24:54 INFO - 'LANGUAGE': 'en_US:en', 09:24:54 INFO - 'LOGNAME': 'cltbld', 09:24:54 INFO - 'MAIL': '/var/mail/cltbld', 09:24:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:54 INFO - 'MOZ_NO_REMOTE': '1', 09:24:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:24:54 INFO - 'NO_EM_RESTART': '1', 09:24:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:24:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:54 INFO - 'PWD': '/builds/slave/test', 09:24:54 INFO - 'SHELL': '/bin/bash', 09:24:54 INFO - 'SHLVL': '1', 09:24:54 INFO - 'TERM': 'linux', 09:24:54 INFO - 'TMOUT': '86400', 09:24:54 INFO - 'USER': 'cltbld', 09:24:54 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1475079860.723353-1385619047', 09:24:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:54 INFO - '_': '/tools/buildbot/bin/python'} 09:24:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:24:54 INFO - Running setup.py (path:/tmp/pip-gp_L15-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:24:54 INFO - Running setup.py (path:/tmp/pip-3Lv69K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:24:54 INFO - Running setup.py (path:/tmp/pip-2A3Fr_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:24:54 INFO - Running setup.py (path:/tmp/pip-MkjtUV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:24:54 INFO - Running setup.py (path:/tmp/pip-ZVX9uD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:24:54 INFO - Running setup.py (path:/tmp/pip-04G9dc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:24:54 INFO - Running setup.py (path:/tmp/pip-SjTvgN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:24:54 INFO - Running setup.py (path:/tmp/pip-8u4ptd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:24:54 INFO - Running setup.py (path:/tmp/pip-DOvn0J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:24:54 INFO - Running setup.py (path:/tmp/pip-uUJu5v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:24:54 INFO - Running setup.py (path:/tmp/pip-dPX499-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:24:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:24:55 INFO - Running setup.py (path:/tmp/pip-skq3Qy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:24:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:24:55 INFO - Running setup.py (path:/tmp/pip-GimWAG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:24:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:24:55 INFO - Running setup.py (path:/tmp/pip-SFmhqw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:24:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:24:55 INFO - Running setup.py (path:/tmp/pip-j0Z5Cb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:24:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:24:55 INFO - Running setup.py (path:/tmp/pip-5ieHhR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:24:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:24:55 INFO - Running setup.py (path:/tmp/pip-0pw7mx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:24:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:24:55 INFO - Running setup.py install for manifestparser 09:24:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:24:55 INFO - Running setup.py install for mozcrash 09:24:55 INFO - Running setup.py install for mozdebug 09:24:55 INFO - Running setup.py install for mozdevice 09:24:55 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:24:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:24:55 INFO - Running setup.py install for mozfile 09:24:56 INFO - Running setup.py install for mozhttpd 09:24:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:24:56 INFO - Running setup.py install for mozinfo 09:24:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:24:56 INFO - Running setup.py install for mozInstall 09:24:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:24:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:24:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:24:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:24:56 INFO - Running setup.py install for mozleak 09:24:56 INFO - Running setup.py install for mozlog 09:24:56 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:24:56 INFO - Running setup.py install for moznetwork 09:24:56 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:24:56 INFO - Running setup.py install for mozprocess 09:24:56 INFO - Running setup.py install for mozprofile 09:24:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:24:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:24:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:24:56 INFO - Running setup.py install for mozrunner 09:24:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:24:56 INFO - Running setup.py install for mozscreenshot 09:24:57 INFO - Running setup.py install for moztest 09:24:57 INFO - Running setup.py install for mozversion 09:24:57 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:24:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:24:57 INFO - Cleaning up... 09:24:57 INFO - Return code: 0 09:24:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:24:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f59494f2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a138a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ae0790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1add930>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ade830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1adecc0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1475079860.723353-1385619047', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:24:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:24:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:24:57 INFO - Using env: {'DISPLAY': ':0', 09:24:57 INFO - 'HOME': '/home/cltbld', 09:24:57 INFO - 'LANG': 'en_US.UTF-8', 09:24:57 INFO - 'LANGUAGE': 'en_US:en', 09:24:57 INFO - 'LOGNAME': 'cltbld', 09:24:57 INFO - 'MAIL': '/var/mail/cltbld', 09:24:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:57 INFO - 'MOZ_NO_REMOTE': '1', 09:24:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:24:57 INFO - 'NO_EM_RESTART': '1', 09:24:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:24:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:57 INFO - 'PWD': '/builds/slave/test', 09:24:57 INFO - 'SHELL': '/bin/bash', 09:24:57 INFO - 'SHLVL': '1', 09:24:57 INFO - 'TERM': 'linux', 09:24:57 INFO - 'TMOUT': '86400', 09:24:57 INFO - 'USER': 'cltbld', 09:24:57 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1475079860.723353-1385619047', 09:24:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:57 INFO - '_': '/tools/buildbot/bin/python'} 09:24:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:24:57 INFO - Running setup.py (path:/tmp/pip-gITy9c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:24:57 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:24:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:24:57 INFO - Running setup.py (path:/tmp/pip-vo5hC0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:24:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:24:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:24:57 INFO - Running setup.py (path:/tmp/pip-1iTfce-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:24:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:24:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:24:57 INFO - Running setup.py (path:/tmp/pip-8EV8mv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:24:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:24:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:24:57 INFO - Running setup.py (path:/tmp/pip-WB0piY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:24:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:24:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:24:57 INFO - Running setup.py (path:/tmp/pip-6GHLkJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:24:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:24:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:24:57 INFO - Running setup.py (path:/tmp/pip-Rjn7hq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:24:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:24:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:24:58 INFO - Running setup.py (path:/tmp/pip-1TP_bS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:24:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:24:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:24:58 INFO - Running setup.py (path:/tmp/pip-JKXyKu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:24:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:24:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:24:58 INFO - Running setup.py (path:/tmp/pip-O198oD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:24:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:24:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:24:58 INFO - Running setup.py (path:/tmp/pip-ePYX7Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:24:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:24:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:24:58 INFO - Running setup.py (path:/tmp/pip-lgy1Ab-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:24:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:24:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:24:58 INFO - Running setup.py (path:/tmp/pip-fVTvey-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:24:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:24:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:24:58 INFO - Running setup.py (path:/tmp/pip-yjGmJ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:24:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:24:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:24:58 INFO - Running setup.py (path:/tmp/pip-USgThP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:24:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:24:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:24:58 INFO - Running setup.py (path:/tmp/pip-oPoT8t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:24:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:24:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:24:58 INFO - Running setup.py (path:/tmp/pip-4R0DZH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:24:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:24:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:24:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:24:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:24:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:24:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:24:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:24:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:25:00 INFO - Downloading blessings-1.6.tar.gz 09:25:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:25:00 INFO - Installing collected packages: blessings 09:25:00 INFO - Running setup.py install for blessings 09:25:00 INFO - Successfully installed blessings 09:25:00 INFO - Cleaning up... 09:25:00 INFO - Return code: 0 09:25:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:25:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:25:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:25:01 INFO - Reading from file tmpfile_stdout 09:25:01 INFO - Current package versions: 09:25:01 INFO - argparse == 1.2.1 09:25:01 INFO - blessings == 1.6 09:25:01 INFO - blobuploader == 1.2.4 09:25:01 INFO - docopt == 0.6.1 09:25:01 INFO - functools32 == 3.2.3-2 09:25:01 INFO - jsonschema == 2.5.1 09:25:01 INFO - manifestparser == 1.1 09:25:01 INFO - mozInstall == 1.12 09:25:01 INFO - mozcrash == 0.17 09:25:01 INFO - mozdebug == 0.1 09:25:01 INFO - mozdevice == 0.48 09:25:01 INFO - mozfile == 1.2 09:25:01 INFO - mozhttpd == 0.7 09:25:01 INFO - mozinfo == 0.9 09:25:01 INFO - mozleak == 0.1 09:25:01 INFO - mozlog == 3.4 09:25:01 INFO - moznetwork == 0.27 09:25:01 INFO - mozprocess == 0.23 09:25:01 INFO - mozprofile == 0.28 09:25:01 INFO - mozrunner == 6.12 09:25:01 INFO - mozscreenshot == 0.1 09:25:01 INFO - mozsystemmonitor == 0.3 09:25:01 INFO - moztest == 0.7 09:25:01 INFO - mozversion == 1.4 09:25:01 INFO - psutil == 3.1.1 09:25:01 INFO - requests == 1.2.3 09:25:01 INFO - wsgiref == 0.1.2 09:25:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:25:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:25:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:25:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:25:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:25:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:25:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:25:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f59494f2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a138a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ae0790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1add930>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ade830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1adecc0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1475079860.723353-1385619047', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:25:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:25:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:25:01 INFO - Using env: {'DISPLAY': ':0', 09:25:01 INFO - 'HOME': '/home/cltbld', 09:25:01 INFO - 'LANG': 'en_US.UTF-8', 09:25:01 INFO - 'LANGUAGE': 'en_US:en', 09:25:01 INFO - 'LOGNAME': 'cltbld', 09:25:01 INFO - 'MAIL': '/var/mail/cltbld', 09:25:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:25:01 INFO - 'MOZ_NO_REMOTE': '1', 09:25:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:25:01 INFO - 'NO_EM_RESTART': '1', 09:25:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:25:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:25:01 INFO - 'PWD': '/builds/slave/test', 09:25:01 INFO - 'SHELL': '/bin/bash', 09:25:01 INFO - 'SHLVL': '1', 09:25:01 INFO - 'TERM': 'linux', 09:25:01 INFO - 'TMOUT': '86400', 09:25:01 INFO - 'USER': 'cltbld', 09:25:01 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1475079860.723353-1385619047', 09:25:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:25:01 INFO - '_': '/tools/buildbot/bin/python'} 09:25:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:25:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:25:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:25:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:25:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:25:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:25:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:25:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:25:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:25:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:25:01 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:25:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:25:01 INFO - Cleaning up... 09:25:01 INFO - Return code: 0 09:25:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:25:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:25:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:25:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:25:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:25:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:25:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:25:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f59494f2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a138a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ae0790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1add930>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ade830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1adecc0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1475079860.723353-1385619047', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:25:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:25:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:25:01 INFO - Using env: {'DISPLAY': ':0', 09:25:01 INFO - 'HOME': '/home/cltbld', 09:25:01 INFO - 'LANG': 'en_US.UTF-8', 09:25:01 INFO - 'LANGUAGE': 'en_US:en', 09:25:01 INFO - 'LOGNAME': 'cltbld', 09:25:01 INFO - 'MAIL': '/var/mail/cltbld', 09:25:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:25:01 INFO - 'MOZ_NO_REMOTE': '1', 09:25:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:25:01 INFO - 'NO_EM_RESTART': '1', 09:25:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:25:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:25:01 INFO - 'PWD': '/builds/slave/test', 09:25:01 INFO - 'SHELL': '/bin/bash', 09:25:01 INFO - 'SHLVL': '1', 09:25:01 INFO - 'TERM': 'linux', 09:25:01 INFO - 'TMOUT': '86400', 09:25:01 INFO - 'USER': 'cltbld', 09:25:01 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1475079860.723353-1385619047', 09:25:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:25:01 INFO - '_': '/tools/buildbot/bin/python'} 09:25:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:25:01 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:25:01 INFO - Cleaning up... 09:25:01 INFO - Return code: 0 09:25:01 INFO - Running post-action listener: _resource_record_post_action 09:25:01 INFO - Running post-action listener: _start_resource_monitoring 09:25:01 INFO - Starting resource monitoring. 09:25:01 INFO - [mozharness: 2016-09-28 16:25:01.603760Z] Finished create-virtualenv step (success) 09:25:01 INFO - [mozharness: 2016-09-28 16:25:01.604265Z] Running install step. 09:25:01 INFO - Running pre-action listener: _resource_record_pre_action 09:25:01 INFO - Running main action method: install 09:25:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:25:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:25:01 INFO - Reading from file tmpfile_stdout 09:25:01 INFO - Detecting whether we're running mozinstall >=1.0... 09:25:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:25:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:25:01 INFO - Reading from file tmpfile_stdout 09:25:01 INFO - Output received: 09:25:01 INFO - Usage: mozinstall [options] installer 09:25:01 INFO - Options: 09:25:01 INFO - -h, --help show this help message and exit 09:25:01 INFO - -d DEST, --destination=DEST 09:25:01 INFO - Directory to install application into. [default: 09:25:01 INFO - "/builds/slave/test"] 09:25:01 INFO - --app=APP Application being installed. [default: firefox] 09:25:01 INFO - mkdir: /builds/slave/test/build/application 09:25:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:25:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 09:25:18 INFO - Reading from file tmpfile_stdout 09:25:18 INFO - Output received: 09:25:18 INFO - /builds/slave/test/build/application/firefox/firefox 09:25:18 INFO - Running post-action listener: _resource_record_post_action 09:25:18 INFO - [mozharness: 2016-09-28 16:25:18.926384Z] Finished install step (success) 09:25:18 INFO - [mozharness: 2016-09-28 16:25:18.926629Z] Running run-tests step. 09:25:18 INFO - Running pre-action listener: _resource_record_pre_action 09:25:18 INFO - Running main action method: run_tests 09:25:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:25:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:25:18 INFO - Python 2.7.3 09:25:18 INFO - Return code: 0 09:25:18 INFO - grabbing minidump binary from tooltool 09:25:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:25:18 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1add930>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ade830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1adecc0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:25:18 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:25:18 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 09:25:18 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:25:18 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:25:19 INFO - Return code: 0 09:25:19 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:25:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:25:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:25:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:25:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:25:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Xn_FpBo4QWKWV-sIXe4uWA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-061', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:25:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Xn_FpBo4QWKWV-sIXe4uWA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-061 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:25:19 INFO - Using env: {'DISPLAY': ':0', 09:25:19 INFO - 'HOME': '/home/cltbld', 09:25:19 INFO - 'LANG': 'en_US.UTF-8', 09:25:19 INFO - 'LANGUAGE': 'en_US:en', 09:25:19 INFO - 'LOGNAME': 'cltbld', 09:25:19 INFO - 'MAIL': '/var/mail/cltbld', 09:25:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:25:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:25:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:25:19 INFO - 'MOZ_NO_REMOTE': '1', 09:25:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:25:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:25:19 INFO - 'NO_EM_RESTART': '1', 09:25:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:25:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:25:19 INFO - 'PWD': '/builds/slave/test', 09:25:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:25:19 INFO - 'SHELL': '/bin/bash', 09:25:19 INFO - 'SHLVL': '1', 09:25:19 INFO - 'TERM': 'linux', 09:25:19 INFO - 'TMOUT': '86400', 09:25:19 INFO - 'USER': 'cltbld', 09:25:19 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1475079860.723353-1385619047', 09:25:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:25:19 INFO - '_': '/tools/buildbot/bin/python'} 09:25:19 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Xn_FpBo4QWKWV-sIXe4uWA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-061', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:25:19 INFO - mozversion application_buildid: 20160928063005 09:25:19 INFO - mozversion application_changeset: 4b65f0d1008a9abd3dc47252e939be93f725b6fe 09:25:19 INFO - mozversion application_display_name: Nightly 09:25:19 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:25:19 INFO - mozversion application_name: Firefox 09:25:19 INFO - mozversion application_remotingname: firefox 09:25:19 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 09:25:19 INFO - mozversion application_vendor: Mozilla 09:25:19 INFO - mozversion application_version: 52.0a1 09:25:19 INFO - mozversion platform_buildid: 20160928063005 09:25:19 INFO - mozversion platform_changeset: 4b65f0d1008a9abd3dc47252e939be93f725b6fe 09:25:19 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 09:25:19 INFO - mozversion platform_version: 52.0a1 09:25:19 INFO - using testdate: 1475079919 09:25:19 INFO - actual date: 1475079919 09:25:19 INFO - starting webserver on 'localhost:58235' 09:25:19 INFO - SUITE-START | Running 2 tests 09:25:19 INFO - TEST-START | dromaeo_css 09:25:19 INFO - operating with platform_type : linux_ 09:25:19 INFO - Initialising browser for dromaeo_css test... 09:25:19 INFO - TEST-INFO | started process 1478 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpuCNGKF/profile http://localhost:58235/getInfo.html) 09:25:24 INFO - PROCESS | 1478 | __metrics Screen width/height:1600/1200 09:25:24 INFO - PROCESS | 1478 | colorDepth:24 09:25:24 INFO - PROCESS | 1478 | Browser inner width/height: 1024/697 09:25:24 INFO - PROCESS | 1478 | __metrics 09:25:24 ERROR - PROCESS | 1478 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3067: TypeError: this.tabs is undefined 09:25:25 INFO - TEST-INFO | 1478: exit 0 09:25:25 INFO - Browser initialized. 09:25:25 INFO - Running cycle 1/1 for dromaeo_css test... 09:25:25 INFO - TEST-INFO | started process 1601 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpuCNGKF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:25:37 INFO - PROCESS | 1601 | RSS: Main: 185311232 09:25:37 INFO - PROCESS | 1601 | 09:26:27 INFO - PROCESS | 1601 | 1475079987568 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:28:37 INFO - PROCESS | 1601 | Cycle 1(1): loaded http://localhost:58235/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58235/tests/dromaeo/cssquery-ext.html) 09:28:38 INFO - PROCESS | 1601 | RSS: Main: 196374528 09:28:38 INFO - PROCESS | 1601 | 09:31:40 INFO - PROCESS | 1601 | Cycle 1(1): loaded http://localhost:58235/tests/dromaeo/cssquery-ext.html (next: http://localhost:58235/tests/dromaeo/cssquery-jquery.html) 09:31:41 INFO - PROCESS | 1601 | RSS: Main: 196698112 09:31:41 INFO - PROCESS | 1601 | 09:34:42 INFO - PROCESS | 1601 | Cycle 1(1): loaded http://localhost:58235/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58235/tests/dromaeo/cssquery-mootools.html) 09:34:43 INFO - PROCESS | 1601 | RSS: Main: 197795840 09:34:43 INFO - PROCESS | 1601 | 09:37:45 INFO - PROCESS | 1601 | Cycle 1(1): loaded http://localhost:58235/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58235/tests/dromaeo/cssquery-prototype.html) 09:37:45 INFO - PROCESS | 1601 | RSS: Main: 194867200 09:37:45 INFO - PROCESS | 1601 | 09:40:48 INFO - PROCESS | 1601 | Cycle 1(1): loaded http://localhost:58235/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58235/tests/dromaeo/cssquery-yui.html) 09:40:49 INFO - PROCESS | 1601 | RSS: Main: 198152192 09:40:49 INFO - PROCESS | 1601 | 09:43:51 INFO - PROCESS | 1601 | Cycle 1(1): loaded http://localhost:58235/tests/dromaeo/cssquery-yui.html (next: http://localhost:58235/tests/dromaeo/cssquery-dojo.html) 09:43:51 INFO - PROCESS | 1601 | RSS: Main: 198860800 09:43:51 INFO - PROCESS | 1601 | 09:43:51 INFO - PROCESS | 1601 | __start_tp_report 09:43:51 INFO - PROCESS | 1601 | _x_x_mozilla_page_load 09:43:51 INFO - PROCESS | 1601 | _x_x_mozilla_page_load_details 09:43:51 INFO - PROCESS | 1601 | |i|pagename|runs| 09:43:51 INFO - PROCESS | 1601 | |0;dojo.html;3106.052653940548;3113.3150706844485;3137.0745630039137;3141.56018157458;3149.338638885834;5275.525202731754;5422.295101636787;5452.754626041828;5462.781488740446;5464.23500709901;5285.8942821143555;5361.865953351168;5417.052015897222;5446.400895901447;5449.754761035753;4872.951270487285;4921.483244259355;4968.701877887338;5006.97496512517;5047.26814611881;4965.627577931651;5024.0705469488075;5027.446980832124;5048.924266136011;5095.668781529207;4834.105690447279;4881.804727810883;4977.178765503706;4977.253411988195;5069.493050694938;4880.121578115938;4923.433805112422;4964.801407943678;4982.352294201767;5041.521055499722;4935.111679897617;4944.90110197794;4950.356453661037;4971.304017437562;4985.700857948535;5278.155495120801;5395.244665746799;5443.292371991655;5448.210009548593;5452.700101494416;5683;5771.769129234825;5800.854978625526;5807.82576522705;5929.762809487615;5865.032269675498;5889.4993925515955;5890.646561206341;5896.469317761379;5907.113932910055;5476.479734425223;5483.643563168461;5493.505584497374;5497.065498865202;5498.202760599731;5789.507891829242;5848.912266316009;5983.581149319593;6026.819195424145;6043.486303664238;5728.255326807488;5785.652860828363;5795.449432303924;5797.652140871562;5829.504492118217;5671.064274394761;5829.125631155276;5866.061430171152;5889.558283128783;5892.263467066617;5652.63257888236;5781.653100813965;5793.478586927113;5805.88388232233;5895.204147440127;5852.063669812895;5854.560907931922;5868.09044598088;5878.470937615635;5881.38245484218;4510.526394728572;4533.52397998212;4534;4534.88662783433;4582.243929751554;3904.316744569689;3910.8044597770568;3982.78094704794;4010.8195131219163;4031.9395209071886;5533.418991005967;5595.7481913313995;5633.8309850704545;5636.915446268309;5688.175214593944;5840.415958404211;5870.501007414332;5904.291485021825;5909.143174239797;5909.320428150818;5486.259354987105;5496.862578435571;5508.586855985737;5560.582956278296;5606.131049687305;5025.698458092526;5030.84907452777;5032.672876263105;5055.87360315995;5079.466656001141;6247.312795592481;6267.843303917439;6267.874642507124;6267.905981410283;6269.686515674198;5728.312602487562;5771.480566749013;5896.734646940812;6009.969950150221;6028.2766068072115;6097.5731698780655;6186.257649082139;6197.349278325711;6199;6207.068939659087;5850.970745146247;5861.677607731445;5865.648061116347;5865.94134058654;5868.530517558671;5695.943040569541;5702.258706368146;5913.733881975408;5990.940090599038;5996.130561068708;5704.144378343116;5728.713564321684;5740.253767010262;5762.366139724711;5806;5750.13747937796;5759.7408116634015;5778.537716982715;5782.826515204551;5789.102689083199;5497.47773961489;5530.80642177538;5625.296837895263;5736.5410767139365;5739.253896993364;5757.740901659352;5841.50347220491;5842.561807864342;5903.350631430453;5907.497862681548;5596.944030559643;5660.886782264414;5674;5679.375268720355;5681.034224181982 09:43:51 INFO - PROCESS | 1601 | |1;ext.html;558.606182641238;573.9282589676291;577.465844094213;580.5587753307486;619.4548797058587;37391.43912841306;41440;41469.585304147025;41493.58506414935;41512.37731434027;41000.17999640005;41333;41483.17033659325;41633.58366416335;41659.16681666365;8589.441686290387;8737.43206691563;8751.299896008304;8793.296536277083;8838.204561589455;8557;8651.48091114532;8677.69628063018;8689.739307820775;8713.390062695615;8604.827903441927;8728.738137855875;8749.95625021874;8777.034526202113;8800.603972821238;8644.956775216147;8665.783355416102;8684.8697269541;8731;8743.4753914765;8637.438566493158;8661.696840610612;8678.479291242546;8699.565021748951;8731.47611143327;40551.188976220605;40955;41046;41072.58927410747;41117.79441102805;3078.1996680863135;3444.0356700124007;3633;3854.7687138771485;4109.630133287988;31090.378192436252;31316.37367252642;31363.686363136534;31489;31659.84170079135;3987.521497420299;4001.919961600752;4048.7246517347235;4050.0077481017047;4114.897127571805;244357;247560;338475;343739;344603.9999999975;54186.72906635442;54487.727561362735;54525.45474545283;54583.727081365134;54637.45362546323;34983.475247871305;36059.75462208414;36230.27539449196;37825.81087094547;37889.81055094762;11247.100231981422;11294.322340659586;11310.830337544943;11322;11376.544938202545;10516.316439431412;10721.463926803628;10727.356358618508;10754.56981720738;10788.298760580537;18059.367922122925;18149.455516334532;18235.17941692627;18250.726239106425;18273.817261827477;6759.290274521203;6901.201642144445;7043.577385356895;7063.964680176567;7163.1404231491515;887.2857349833317;972.3048020665258;1005.2008653120787;1010.9090181883665;1143.3140115930375;30217.848910755743;30864.845675771478;31027.379452410827;31340.529892051636;31511;1049.7428130108171;1052.5368837711383;1073.4471747050275;1078.417654466595;1082.4371326909963;16124.435644752377;16571.254293556576;16573.50279491593;16592.336306547844;16611.750823737653;143890;144293.27853360667;144409.27795360956;144759;520535;55288.72355638276;55916.16125758117;56015.43984560184;56040;56132.94803467707;1251.055453132879;1253.7617852607975;1268.6511209417483;1288.072587736828;1292.1084451728277;29640.407191856473;30314.696853031186;30375.696243037288;30605.846970765004;30617.387652247275;43252.56747432611;43808.12383752371;43819.12361752811;49576;49614;433651;434204;434955;435422;435937;27487.725122748518;27941.72058279391;27942.580861287504;28017.859910700317;28080.71919280863;45816.08367832691;46035;46265;46694.299585506924;47352.7632361836;37152;38657.806710966266;39119.41320880246;39122.804385977885;39551.80224098861;37846.566083765276;38536.80731596324;38808;40580.797096015514;41049.79475102606;39370.6062939367;40009.39985900272;40045.39931900966;40112;40390.79804601076;33501.49747753737;36371;36502.26995460129;36618.63381366152;37048.25903481861;28216.85891570529;28575.714242857306;28700;28844.855775721826;28846.278843028253 09:43:51 INFO - PROCESS | 1601 | |2;jquery.html;402.19469202313866;404.81985516445195;409.5269963192513;411.09558970265425;426.53507676632466;17847.82152178478;19398.612027759453;19408.126634301454;19436;19465.416037518862;16718.916405417986;16951.66096678066;17200.913995430037;17695.91152044238;17844.643107137883;5409.9459005409935;5526.309211348582;5634.943650563503;5866.794662186824;5919.852003699899;5790.7104644767805;5866.120081987693;5875.294964604253;5884.911726324087;5900.616459930112;5622.690752008638;5743.597948143632;5757.68332741699;5771.220885180489;5827.475527202551;5702.515286200678;5764;5765.798197063083;5767.192593036978;5794.333651630053;4976.676516026447;5157.200633901752;5184.792608295664;5198.974005129988;5230.738463076869;22116.33650990473;22730.20444284442;22822.429439263986;22840.086396544117;23040.53918921612;2323.639835825433;2396.101461951768;2827.3921106962102;3066.708662677042;3074.0777766669908;9585.952070239604;9753.21974242059;9959.502024898726;11080.556777728962;15061.17163556004;1818.3908390689135;1905.2664724081328;1933;1944.474991752219;1957.9510512237166;78359;81481.18518814795;81536;82396.58801705953;82491.58754206251;10840.831833633305;11004.22970392081;11041.282316649553;11111.777764444665;11671.88328116708;15462.536123916301;15475.845241547666;15476.613084672974;15488.457903973307;15539.223038848013;7375.926240737631;7430.702771889064;7514.736984205527;7540.472166948261;7807.570583617956;6547.672616369163;6892.379685828299;7115.501914865909;7127.679254433563;7206;4329.718568293114;4397.846075387346;4707.8587642370785;4777.378940737682;4906.11689895815;1923.4325873867242;1944.0862794486568;1998.490384951857;2000.5398758285676;2011.1452632631074;899.7750562359411;993.6323560282742;998.605550807427;1002.6891663584312;1030.7680771826429;18844.34044808425;19126.426207213528;19129;19292.710609340873;19566.804331956784;934.8968217503411;947.5972711597543;954.2080073538946;956.9712908612753;1031.128696251666;9874.456904870158;10472;10758.408287544104;10769.730756731144;10924.96212859777;388020;394775;402100;404912;406389.45689616125;84458.5777071123;87664.1233587656;89434;89793;90231;990.3760630802692;992.3648864726581;999.5402115027024;1000.0849222960909;1006.627547807316;16469.505914822093;16825.747613786047;16917.57706057309;17028.914855425643;17458.47624571265;27778.305542361602;31858.20354491156;40284.59715402809;41026.38460423036;42160.57839421566;56444.71777641086;57092.429075708715;57474.13788793255;57879;57969.71015144897;7438.995735575844;7555.848883022419;7805.18045605226;7932.722354717557;8065.112837587978;9164.404313719588;11770.764584708086;11912.761744764883;12042.13346931227;12587.559435419716;10455;10687.412192329186;10853.511591978538;10935.125189984941;11510.36692981894;10779.245452818228;10900;10946.343219406861;10956.945215273872;11085.334879907232;10570.2600817942;10668.8933110671;10915.508802103766;11042.779144417227;11565.306081635128;7666.929928919049;7891.684332626631;7930.801729956566;7971.242731940456;8064.112947575879;10221.59113635476;10754.83867742;10766.300190487907;10836.891631083588;11042.558297668325 09:43:51 INFO - PROCESS | 1601 | |3;mootools.html;1235.7775600391926;1311.4491913396373;1346.9359206227082;1733.505950804021;1762.7884653841534;8011;8261.710840120597;8605.784855378612;9273.350865439414;9275.165235128838;8529.53087580183;8854.955725221382;8857.379983401164;9071.092890710926;9082.364234503588;2408.301592538162;2418.7218469875925;2439.377958620549;2440.511897620483;2444.6699695541142;2290.438842483594;2301.539692061586;2320.431494283903;2330.0679728108807;2406.602910519762;2382.690250267463;2383.8212134089927;2392.6769886065417;2404.3989002749313;2404.483036147228;2266.6939963104933;2269.047000259895;2276.692646492727;2282.132789539981;2305.896234669444;2257.1874125314876;2284.885755712224;2294.128231272106;2295.885205739723;2312.5606134834497;9003.5048072356;9101.40840845343;9119.81760364789;9119.863202051974;9119.954400228024;5086.109930762102;5090.5927525798215;5159.638825282232;5215.348081489814;5244.134717771563;3449.534312867756;3512.244867353506;3584.731145164123;3593.574337322297;3644.234710710754;1145.9255148415327;1177.146568737667;1181;1192.2786714038054;1210.806270996645;12712.491500339975;12742.426590803436;12748.553800616934;12749.171303865312;12751.171173873669;4577.107464044546;4775.785089670973;4848.296996935424;4849.903001940012;4940.110780059624;10498.212634052477;10501.527431265611;10520.26358154937;10535.157187424988;10613.310134841211;5299.284596579412;5321.547668448148;5322.866928326823;5323.733813309319;5326.440723723953;4647.62818974488;4775.6418268629295;4818.927716084191;4852.393450818648;4910.533499317559;3658.725595580289;3675.1363429594026;3708.165662725919;3717.665410113103;3721.4789929409903;1214.168294718121;1221.5785553983862;1245.7570773699042;1249.7000719827358;1261.9558315458914;788.3220430429826;869.6304070769897;909.713440266314;919.4529255093208;936.5410948635256;5864.149698293723;5905.350411454737;6003.969980150158;6223.937760622426;6329.936700633027;938.4369378372921;942.8444306689456;967.434051080126;989.8861630912537;1009.3187098708489;3900.239453306578;4164.916701665949;4195.832166713297;4243.087736136746;4280.122574872144;11082.22424430282;11408.600698975495;11478.713032174262;11481;11497;11228.382438965935;11268.830967535494;11322.490487927898;11450.255733377304;11452.656420307405;8076.55578943152;8109.229623185788;8375.74872753806;8385.245327920395;8431.367647426467;5658.292713410824;5772.422757724194;5773.3072031356505;5869.325027621708;5920.851978700567;10394.428306443218;10454.215933805148;10511.159107271247;10520.736981575521;10550.261481696207;10157.441340726331;10322.174226061747;10607.575696972035;10736.624218152327;10877.836832447678;6709.194896612437;6933.7226510939;7234.059572255573;7434.442416818868;7504.699812007459;8827.558622069;8839.46963182211;8880.245179159585;8896.5996530155;8918.598663060307;7643.770686879402;7702.691892324468;7718.6140692964;7900.960495197487;7943.205679432011;7562.281583249583;7579.696812127674;7590;7698.34564062061;7703.499272547267;6970.79087627372;7136.250693677089;7147.213806481387;7157.749478768426;7224.024756657994;5081.568066714222;5122.231665250242;5135.794568217229;5194.324737784215;5307.4692530746615;5018.473060328759;5091.414487334004;5191.662541934762;5260.658057226268;5429.131338985743 09:43:51 INFO - PROCESS | 1601 | |4;prototype.html;336.6498841205147;347.93563190809704;353.80237894726343;355.7420869869345;357.15176455917197;5541.722913854306;5551.1673249012665;5576.944230557699;5595.020871347519;5610;5495.917561236584;5507.614466987313;5525.204308599701;5529.474699903502;5561.193626924104;3770.4532842737785;4055;4128.793560321987;4140.544540100586;4259.382389553512;4263.676296418459;4279.737477444161;4302.634276086536;4304.160688665712;4329.220740266751;4225.450691410112;4264.680148988823;4286.228478873802;4287.699861009731;4288.549702281262;4015.377616469452;4227.767472788995;4283.935740963888;4288.892777680552;4303.440552728141;3734.0104872208885;3745.7377983541164;3751.3247615429214;3757.605451427615;3765.284595926765;5337.973310133463;5407.972960135214;5417.783288668448;5431.158170483582;5443.782248710047;4812.614990800727;4914.336564563774;4920.532549407801;4926.901461970776;4943.961768028718;5681.52280407093;5703.201551782754;5743.942560574383;5754.058469948057;5782.739776710058;4085.5301640311445;4090.386442033689;4113.197926404352;4131.7191670582215;4152.937705934413;5798.811243695074;6018.729157187937;6024;6026.2825094848195;6033.306169790487;4190.434291370687;4208.915821683549;4213.0099426634715;4226.4928208615165;4237.936430953539;4253.681743449827;4266.679999000087;4275.315949448135;4278.914421711548;4281.721688090264;4651.418572678415;4896.2655601660035;4915.95084049155;4922.237053256751;4948.268106162815;5786.797462088806;5792.333881603669;5796.362400135981;5808.3901190375245;5835.153902684087;1474.9896321019921;1492.4701730885597;1492.4925525321444;1494.9551513454614;1496.6931778985281;919.6735159018585;958.6453012385323;962.9614815407446;963.0321526865455;976.1946394224651;4925.768557860535;4944.975275123673;4991.351124353883;5000.199968005102;5021.397432308073;5135.820246291362;5150.690958542499;5177.301064356339;5211.713355765393;5226.451222621646;4694.741789201558;4906.8773280668265;4919.4834542373255;5007.474215207423;5009.699418034929;2764.8940423830627;2771.3071732066983;2778.6103950602464;2794.944101117966;2810.718928107173;5353.92149514031;5399.6490228135835;5447.836564903059;5459.962607104717;5476.411840566191;5082.788986790758;5219.552609776281;5267.631265811357;5280.656757310762;5359.196120581944;5990.580659353804;6143.815685529441;6153.20029386628;6167;6277.7175027123885;6008.699565021819;6015.4586087252355;6033.849153771015;6041.426064523863;6059.666718330534;5797.2173756542325;5856.267966504187;5881.176635271151;5940.762369505172;5962.344142144448;6171.84570385744;6172.629642221481;6191.907121393273;6209.472194863487;6235.594686345373;4272.188284225987;4296.474751463311;4300.634446072119;4309.07354918694;4328.891777705582;5820.970895145497;5916.792912248051;6006.888725585781;6020.277566692025;6042.939570604413;5785.392533783892;5805.187273781589;5824.94175058244;5828.358880523224;5840.912386314294;5699.800506982236;5713.514351280188;5760.654360738202;5765.855853603525;5826.416753696531;5673.177389278614;5716.742746576331;5735.54115670737;5739.847557166063;5753.1370294456165;5814.215080964016;5832.708364581838;5835.920354734388;5896.410358964069;5902.911456328245;5489.313835770528;5611.775528978795;5667.781426993216;5670.971645141913;5673.716314184357 09:43:51 INFO - PROCESS | 1601 | |5;yui.html;606.8755905039467;717.9425645948321;721.97119105275;742.858856817205;751.1436961863477;14504.927475362634;14544.781828272584;14846.406143754237;16015.27931243094;16025.759613605805;14121.858781412182;14334.64163395913;14354.066985645952;14390.136591804472;14452.783208251858;773.1109224391938;776.425445170574;792.8850316704074;794.737736546941;794.9443538952276;798.6286376834771;800.5516910530122;803.8593246181924;805.1867613710136;805.7421625079977;831.6714897615439;834.5159807311744;834.8330333933237;835.3818174550819;835.4945258118842;816.8243827577099;827.1067247372883;827.3629305434812;827.4042689263721;829.8962629671291;595.8986972214691;599.2629066248495;604.5737754882797;604.7611193578512;606.8391876152823;14971;15041.924790376088;15185.164815935119;15212.46756363522;15232.847671523254;14311.141331520143;14627.341769620392;14780.926095369454;14956.925215373962;15275.847241527554;10900.890991090146;10979.1765617579;11239.325640461517;11516.539338426453;11586.13104017202;4554.180247555439;4625.375574297461;4642.442906851199;4695.60087392572;4711.7879695413785;79082.83668653246;79277.60361198215;79499;82212.17787822164;83018.58490707568;10981.725456863642;10988.780224395467;11711.76576468483;11743.88256117445;11763.647090587296;21823.345299641038;21844.89077554602;21847.08440600884;22065.33803985883;22102.778972210395;8055.556944368116;8389.035260944947;8502.914970850212;8537.317014638815;8820.514871681991;4639.118567472169;4648.628109751212;4664.416947881515;4713.481517033124;4733.26634371666;3410.9488357674654;3485.8431370588382;3514.5079688843584;3527.788332699995;3536.398812201932;622.6575383539035;624.2134910013416;628.2210059526154;642.9292777794439;648.222133439874;572.1789232451446;574.3136951343105;594.4115325827399;595.7051259626512;606.5087279303779;8564.828703425897;8819.779505512286;8934.955325223462;9139.634414623473;9342;604.9879002419927;614.532954954229;622.6233128957007;627.3099590450468;632.772202007277;4021.7788021658503;4051.736637118578;4188.246115699142;4257.4252475915355;4378.43080399546;113870.43064784788;125560;125883;127295;127610.36194818966;66837.6658116716;91965;104935.47532262442;105003;107165;60437.69781151066;66178.66910665415;74740.25259747442;76027.23972760313;88804.5559772197;8380.329573634217;8516.105808889977;8595.484270943763;8940.619096642728;9294.581743821409;51909.74045129901;52320.738396307774;52393;52681.736591316796;53194.73402632962;46155.07689846252;47464;52816;78935;79102.20897790948;12362.752744944872;12423.192492487959;12556.686082848002;12563.309018004098;12968.675783105498;13920.930395348363;13922.303884805922;14299;14345.354459049577;14380.209088500233;12147.149699521302;12214.57248996281;12229.938850306047;12318.568850090203;12355.196912200678;13332.870661835315;13708.108972916729;13916.776979558714;14075.718485630025;14135.64660883445;11662.825057624312;12124.757504849676;12244.142909996215;12475.295586144754;12779.61661150167;10183.541740621838;10383.636572719919;10770.138388929023;11310.717232068935;11412.429378531206;9828.606855725977;10397.58409663605;10447.477626118816;10521.263511554424;10589.099926506333 09:43:51 INFO - PROCESS | 1601 | __end_tp_report 09:43:51 INFO - PROCESS | 1601 | __start_cc_report 09:43:51 INFO - PROCESS | 1601 | _x_x_mozilla_cycle_collect,289 09:43:51 INFO - PROCESS | 1601 | __end_cc_report 09:43:51 INFO - PROCESS | 1601 | __startTimestamp1475081031844__endTimestamp 09:43:51 INFO - PROCESS | 1601 | 09:43:51 INFO - PROCESS | 1601 | ------- Summary: start ------- 09:43:51 INFO - PROCESS | 1601 | Number of tests: 6 09:43:51 INFO - PROCESS | 1601 | 09:43:51 INFO - PROCESS | 1601 | [#0] dojo.html Cycles:165 Average:5450.94 Median:5679.38 stddev:637.30 (11.2%) stddev-sans-first:612.13 09:43:51 INFO - PROCESS | 1601 | Values: 3106.1 3113.3 3137.1 3141.6 3149.3 5275.5 5422.3 5452.8 5462.8 5464.2 5285.9 5361.9 5417.1 5446.4 5449.8 4873.0 4921.5 4968.7 5007.0 5047.3 4965.6 5024.1 5027.4 5048.9 5095.7 4834.1 4881.8 4977.2 4977.3 5069.5 4880.1 4923.4 4964.8 4982.4 5041.5 4935.1 4944.9 4950.4 4971.3 4985.7 5278.2 5395.2 5443.3 5448.2 5452.7 5683.0 5771.8 5800.9 5807.8 5929.8 5865.0 5889.5 5890.6 5896.5 5907.1 5476.5 5483.6 5493.5 5497.1 5498.2 5789.5 5848.9 5983.6 6026.8 6043.5 5728.3 5785.7 5795.4 5797.7 5829.5 5671.1 5829.1 5866.1 5889.6 5892.3 5652.6 5781.7 5793.5 5805.9 5895.2 5852.1 5854.6 5868.1 5878.5 5881.4 4510.5 4533.5 4534.0 4534.9 4582.2 3904.3 3910.8 3982.8 4010.8 4031.9 5533.4 5595.7 5633.8 5636.9 5688.2 5840.4 5870.5 5904.3 5909.1 5909.3 5486.3 5496.9 5508.6 5560.6 5606.1 5025.7 5030.8 5032.7 5055.9 5079.5 6247.3 6267.8 6267.9 6267.9 6269.7 5728.3 5771.5 5896.7 6010.0 6028.3 6097.6 6186.3 6197.3 6199.0 6207.1 5851.0 5861.7 5865.6 5865.9 5868.5 5695.9 5702.3 5913.7 5990.9 5996.1 5704.1 5728.7 5740.3 5762.4 5806.0 5750.1 5759.7 5778.5 5782.8 5789.1 5497.5 5530.8 5625.3 5736.5 5739.3 5757.7 5841.5 5842.6 5903.4 5907.5 5596.9 5660.9 5674.0 5679.4 5681.0 09:43:51 INFO - PROCESS | 1601 | 09:43:51 INFO - PROCESS | 1601 | [#1] ext.html Cycles:180 Average:48407.68 Median:21769.66 stddev:91320.95 (419.5%) stddev-sans-first:91506.46 09:43:51 INFO - PROCESS | 1601 | Values: 558.6 573.9 577.5 580.6 619.5 37391.4 41440.0 41469.6 41493.6 41512.4 41000.2 41333.0 41483.2 41633.6 41659.2 8589.4 8737.4 8751.3 8793.3 8838.2 8557.0 8651.5 8677.7 8689.7 8713.4 8604.8 8728.7 8750.0 8777.0 8800.6 8645.0 8665.8 8684.9 8731.0 8743.5 8637.4 8661.7 8678.5 8699.6 8731.5 40551.2 40955.0 41046.0 41072.6 41117.8 3078.2 3444.0 3633.0 3854.8 4109.6 31090.4 31316.4 31363.7 31489.0 31659.8 3987.5 4001.9 4048.7 4050.0 4114.9 244357.0 247560.0 338475.0 343739.0 344604.0 54186.7 54487.7 54525.5 54583.7 54637.5 34983.5 36059.8 36230.3 37825.8 37889.8 11247.1 11294.3 11310.8 11322.0 11376.5 10516.3 10721.5 10727.4 10754.6 10788.3 18059.4 18149.5 18235.2 18250.7 18273.8 6759.3 6901.2 7043.6 7064.0 7163.1 887.3 972.3 1005.2 1010.9 1143.3 30217.8 30864.8 31027.4 31340.5 31511.0 1049.7 1052.5 1073.4 1078.4 1082.4 16124.4 16571.3 16573.5 16592.3 16611.8 143890.0 144293.3 144409.3 144759.0 520535.0 55288.7 55916.2 56015.4 56040.0 56132.9 1251.1 1253.8 1268.7 1288.1 1292.1 29640.4 30314.7 30375.7 30605.8 30617.4 43252.6 43808.1 43819.1 49576.0 49614.0 433651.0 434204.0 434955.0 435422.0 435937.0 27487.7 27941.7 27942.6 28017.9 28080.7 45816.1 46035.0 46265.0 46694.3 47352.8 37152.0 38657.8 39119.4 39122.8 39551.8 37846.6 38536.8 38808.0 40580.8 41049.8 39370.6 40009.4 40045.4 40112.0 40390.8 33501.5 36371.0 36502.3 36618.6 37048.3 28216.9 28575.7 28700.0 28844.9 28846.3 09:43:51 INFO - PROCESS | 1601 | 09:43:51 INFO - PROCESS | 1601 | [#2] jquery.html Cycles:180 Average:25952.05 Median:221192.30 stddev:66378.87 (30.0%) stddev-sans-first:66537.36 09:43:51 INFO - PROCESS | 1601 | Values: 402.2 404.8 409.5 411.1 426.5 17847.8 19398.6 19408.1 19436.0 19465.4 16718.9 16951.7 17200.9 17695.9 17844.6 5409.9 5526.3 5634.9 5866.8 5919.9 5790.7 5866.1 5875.3 5884.9 5900.6 5622.7 5743.6 5757.7 5771.2 5827.5 5702.5 5764.0 5765.8 5767.2 5794.3 4976.7 5157.2 5184.8 5199.0 5230.7 22116.3 22730.2 22822.4 22840.1 23040.5 2323.6 2396.1 2827.4 3066.7 3074.1 9586.0 9753.2 9959.5 11080.6 15061.2 1818.4 1905.3 1933.0 1944.5 1958.0 78359.0 81481.2 81536.0 82396.6 82491.6 10840.8 11004.2 11041.3 11111.8 11671.9 15462.5 15475.8 15476.6 15488.5 15539.2 7375.9 7430.7 7514.7 7540.5 7807.6 6547.7 6892.4 7115.5 7127.7 7206.0 4329.7 4397.8 4707.9 4777.4 4906.1 1923.4 1944.1 1998.5 2000.5 2011.1 899.8 993.6 998.6 1002.7 1030.8 18844.3 19126.4 19129.0 19292.7 19566.8 934.9 947.6 954.2 957.0 1031.1 9874.5 10472.0 10758.4 10769.7 10925.0 388020.0 394775.0 402100.0 404912.0 406389.5 84458.6 87664.1 89434.0 89793.0 90231.0 990.4 992.4 999.5 1000.1 1006.6 16469.5 16825.7 16917.6 17028.9 17458.5 27778.3 31858.2 40284.6 41026.4 42160.6 56444.7 57092.4 57474.1 57879.0 57969.7 7439.0 7555.8 7805.2 7932.7 8065.1 9164.4 11770.8 11912.8 12042.1 12587.6 10455.0 10687.4 10853.5 10935.1 11510.4 10779.2 10900.0 10946.3 10956.9 11085.3 10570.3 10668.9 10915.5 11042.8 11565.3 7666.9 7891.7 7930.8 7971.2 8064.1 10221.6 10754.8 10766.3 10836.9 11042.6 09:43:51 INFO - PROCESS | 1601 | 09:43:51 INFO - PROCESS | 1601 | [#3] mootools.html Cycles:180 Average:5881.47 Median:4833.61 stddev:3409.86 (70.5%) stddev-sans-first:3401.55 09:43:51 INFO - PROCESS | 1601 | Values: 1235.8 1311.4 1346.9 1733.5 1762.8 8011.0 8261.7 8605.8 9273.4 9275.2 8529.5 8855.0 8857.4 9071.1 9082.4 2408.3 2418.7 2439.4 2440.5 2444.7 2290.4 2301.5 2320.4 2330.1 2406.6 2382.7 2383.8 2392.7 2404.4 2404.5 2266.7 2269.0 2276.7 2282.1 2305.9 2257.2 2284.9 2294.1 2295.9 2312.6 9003.5 9101.4 9119.8 9119.9 9120.0 5086.1 5090.6 5159.6 5215.3 5244.1 3449.5 3512.2 3584.7 3593.6 3644.2 1145.9 1177.1 1181.0 1192.3 1210.8 12712.5 12742.4 12748.6 12749.2 12751.2 4577.1 4775.8 4848.3 4849.9 4940.1 10498.2 10501.5 10520.3 10535.2 10613.3 5299.3 5321.5 5322.9 5323.7 5326.4 4647.6 4775.6 4818.9 4852.4 4910.5 3658.7 3675.1 3708.2 3717.7 3721.5 1214.2 1221.6 1245.8 1249.7 1262.0 788.3 869.6 909.7 919.5 936.5 5864.1 5905.4 6004.0 6223.9 6329.9 938.4 942.8 967.4 989.9 1009.3 3900.2 4164.9 4195.8 4243.1 4280.1 11082.2 11408.6 11478.7 11481.0 11497.0 11228.4 11268.8 11322.5 11450.3 11452.7 8076.6 8109.2 8375.7 8385.2 8431.4 5658.3 5772.4 5773.3 5869.3 5920.9 10394.4 10454.2 10511.2 10520.7 10550.3 10157.4 10322.2 10607.6 10736.6 10877.8 6709.2 6933.7 7234.1 7434.4 7504.7 8827.6 8839.5 8880.2 8896.6 8918.6 7643.8 7702.7 7718.6 7901.0 7943.2 7562.3 7579.7 7590.0 7698.3 7703.5 6970.8 7136.3 7147.2 7157.7 7224.0 5081.6 5122.2 5135.8 5194.3 5307.5 5018.5 5091.4 5191.7 5260.7 5429.1 09:43:51 INFO - PROCESS | 1601 | 09:43:51 INFO - PROCESS | 1601 | [#4] prototype.html Cycles:180 Average:4767.24 Median:5356.56 stddev:1412.97 (26.4%) stddev-sans-first:1377.25 09:43:51 INFO - PROCESS | 1601 | Values: 336.6 347.9 353.8 355.7 357.2 5541.7 5551.2 5576.9 5595.0 5610.0 5495.9 5507.6 5525.2 5529.5 5561.2 3770.5 4055.0 4128.8 4140.5 4259.4 4263.7 4279.7 4302.6 4304.2 4329.2 4225.5 4264.7 4286.2 4287.7 4288.5 4015.4 4227.8 4283.9 4288.9 4303.4 3734.0 3745.7 3751.3 3757.6 3765.3 5338.0 5408.0 5417.8 5431.2 5443.8 4812.6 4914.3 4920.5 4926.9 4944.0 5681.5 5703.2 5743.9 5754.1 5782.7 4085.5 4090.4 4113.2 4131.7 4152.9 5798.8 6018.7 6024.0 6026.3 6033.3 4190.4 4208.9 4213.0 4226.5 4237.9 4253.7 4266.7 4275.3 4278.9 4281.7 4651.4 4896.3 4916.0 4922.2 4948.3 5786.8 5792.3 5796.4 5808.4 5835.2 1475.0 1492.5 1492.5 1495.0 1496.7 919.7 958.6 963.0 963.0 976.2 4925.8 4945.0 4991.4 5000.2 5021.4 5135.8 5150.7 5177.3 5211.7 5226.5 4694.7 4906.9 4919.5 5007.5 5009.7 2764.9 2771.3 2778.6 2794.9 2810.7 5353.9 5399.6 5447.8 5460.0 5476.4 5082.8 5219.6 5267.6 5280.7 5359.2 5990.6 6143.8 6153.2 6167.0 6277.7 6008.7 6015.5 6033.8 6041.4 6059.7 5797.2 5856.3 5881.2 5940.8 5962.3 6171.8 6172.6 6191.9 6209.5 6235.6 4272.2 4296.5 4300.6 4309.1 4328.9 5821.0 5916.8 6006.9 6020.3 6042.9 5785.4 5805.2 5824.9 5828.4 5840.9 5699.8 5713.5 5760.7 5765.9 5826.4 5673.2 5716.7 5735.5 5739.8 5753.1 5814.2 5832.7 5835.9 5896.4 5902.9 5489.3 5611.8 5667.8 5671.0 5673.7 09:43:51 INFO - PROCESS | 1601 | 09:43:51 INFO - PROCESS | 1601 | [#5] yui.html Cycles:180 Average:20176.73 Median:4640.78 stddev:29756.68 (641.2%) stddev-sans-first:29803.88 09:43:51 INFO - PROCESS | 1601 | Values: 606.9 717.9 722.0 742.9 751.1 14504.9 14544.8 14846.4 16015.3 16025.8 14121.9 14334.6 14354.1 14390.1 14452.8 773.1 776.4 792.9 794.7 794.9 798.6 800.6 803.9 805.2 805.7 831.7 834.5 834.8 835.4 835.5 816.8 827.1 827.4 827.4 829.9 595.9 599.3 604.6 604.8 606.8 14971.0 15041.9 15185.2 15212.5 15232.8 14311.1 14627.3 14780.9 14956.9 15275.8 10900.9 10979.2 11239.3 11516.5 11586.1 4554.2 4625.4 4642.4 4695.6 4711.8 79082.8 79277.6 79499.0 82212.2 83018.6 10981.7 10988.8 11711.8 11743.9 11763.6 21823.3 21844.9 21847.1 22065.3 22102.8 8055.6 8389.0 8502.9 8537.3 8820.5 4639.1 4648.6 4664.4 4713.5 4733.3 3410.9 3485.8 3514.5 3527.8 3536.4 622.7 624.2 628.2 642.9 648.2 572.2 574.3 594.4 595.7 606.5 8564.8 8819.8 8935.0 9139.6 9342.0 605.0 614.5 622.6 627.3 632.8 4021.8 4051.7 4188.2 4257.4 4378.4 113870.4 125560.0 125883.0 127295.0 127610.4 66837.7 91965.0 104935.5 105003.0 107165.0 60437.7 66178.7 74740.3 76027.2 88804.6 8380.3 8516.1 8595.5 8940.6 9294.6 51909.7 52320.7 52393.0 52681.7 53194.7 46155.1 47464.0 52816.0 78935.0 79102.2 12362.8 12423.2 12556.7 12563.3 12968.7 13920.9 13922.3 14299.0 14345.4 14380.2 12147.1 12214.6 12229.9 12318.6 12355.2 13332.9 13708.1 13916.8 14075.7 14135.6 11662.8 12124.8 12244.1 12475.3 12779.6 10183.5 10383.6 10770.1 11310.7 11412.4 9828.6 10397.6 10447.5 10521.3 10589.1 09:43:51 INFO - PROCESS | 1601 | -------- Summary: end -------- 09:43:51 INFO - PROCESS | 1601 | 09:43:52 INFO - TEST-INFO | 1601: exit 0 09:43:52 INFO - TEST-OK | dromaeo_css | took 1113693ms 09:43:52 INFO - TEST-START | kraken 09:43:52 INFO - operating with platform_type : linux_ 09:43:52 INFO - Initialising browser for kraken test... 09:43:52 INFO - TEST-INFO | started process 1954 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6Wx_6J/profile http://localhost:58235/getInfo.html) 09:43:58 INFO - PROCESS | 1954 | __metrics Screen width/height:1600/1200 09:43:58 INFO - PROCESS | 1954 | colorDepth:24 09:43:58 INFO - PROCESS | 1954 | Browser inner width/height: 1024/697 09:43:58 INFO - PROCESS | 1954 | __metrics 09:43:58 ERROR - PROCESS | 1954 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3067: TypeError: this.tabs is undefined 09:43:59 INFO - TEST-INFO | 1954: exit 0 09:43:59 INFO - Browser initialized. 09:43:59 INFO - Running cycle 1/1 for kraken test... 09:43:59 INFO - TEST-INFO | started process 2074 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6Wx_6J/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:44:11 INFO - PROCESS | 2074 | RSS: Main: 184975360 09:44:11 INFO - PROCESS | 2074 | 09:44:14 INFO - PROCESS | 2074 | Cycle 1(1): loaded http://localhost:58235/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58235/tests/kraken/driver.html?testName=audio-beat-detection) 09:44:15 INFO - PROCESS | 2074 | RSS: Main: 198586368 09:44:15 INFO - PROCESS | 2074 | 09:44:17 INFO - PROCESS | 2074 | Cycle 1(1): loaded http://localhost:58235/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58235/tests/kraken/driver.html?testName=audio-dft) 09:44:18 INFO - PROCESS | 2074 | RSS: Main: 196374528 09:44:18 INFO - PROCESS | 2074 | 09:44:21 INFO - PROCESS | 2074 | Cycle 1(1): loaded http://localhost:58235/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58235/tests/kraken/driver.html?testName=audio-fft) 09:44:22 INFO - PROCESS | 2074 | RSS: Main: 192172032 09:44:22 INFO - PROCESS | 2074 | 09:44:24 INFO - PROCESS | 2074 | Cycle 1(1): loaded http://localhost:58235/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58235/tests/kraken/driver.html?testName=audio-oscillator) 09:44:24 INFO - PROCESS | 2074 | RSS: Main: 192999424 09:44:24 INFO - PROCESS | 2074 | 09:44:26 INFO - PROCESS | 2074 | Cycle 1(1): loaded http://localhost:58235/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58235/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:44:27 INFO - PROCESS | 2074 | RSS: Main: 193576960 09:44:27 INFO - PROCESS | 2074 | 09:44:30 INFO - PROCESS | 2074 | Cycle 1(1): loaded http://localhost:58235/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58235/tests/kraken/driver.html?testName=imaging-darkroom) 09:44:31 INFO - PROCESS | 2074 | RSS: Main: 194117632 09:44:31 INFO - PROCESS | 2074 | 09:44:34 INFO - PROCESS | 2074 | Cycle 1(1): loaded http://localhost:58235/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58235/tests/kraken/driver.html?testName=imaging-desaturate) 09:44:35 INFO - PROCESS | 2074 | RSS: Main: 191643648 09:44:35 INFO - PROCESS | 2074 | 09:44:38 INFO - PROCESS | 2074 | Cycle 1(1): loaded http://localhost:58235/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58235/tests/kraken/driver.html?testName=json-parse-financial) 09:44:38 INFO - PROCESS | 2074 | RSS: Main: 193150976 09:44:38 INFO - PROCESS | 2074 | 09:44:40 INFO - PROCESS | 2074 | Cycle 1(1): loaded http://localhost:58235/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58235/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:44:41 INFO - PROCESS | 2074 | RSS: Main: 193187840 09:44:41 INFO - PROCESS | 2074 | 09:44:42 INFO - PROCESS | 2074 | Cycle 1(1): loaded http://localhost:58235/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58235/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:44:43 INFO - PROCESS | 2074 | RSS: Main: 193146880 09:44:43 INFO - PROCESS | 2074 | 09:44:45 INFO - PROCESS | 2074 | Cycle 1(1): loaded http://localhost:58235/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58235/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:44:46 INFO - PROCESS | 2074 | RSS: Main: 193568768 09:44:46 INFO - PROCESS | 2074 | 09:44:48 INFO - PROCESS | 2074 | Cycle 1(1): loaded http://localhost:58235/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:44:49 INFO - PROCESS | 2074 | RSS: Main: 193683456 09:44:49 INFO - PROCESS | 2074 | 09:44:52 INFO - PROCESS | 2074 | Cycle 1(1): loaded http://localhost:58235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:44:53 INFO - PROCESS | 2074 | RSS: Main: 194048000 09:44:53 INFO - PROCESS | 2074 | 09:44:54 INFO - PROCESS | 2074 | Cycle 1(1): loaded http://localhost:58235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58235/tests/kraken/driver.html?testName=ai-astar) 09:44:55 INFO - PROCESS | 2074 | RSS: Main: 195366912 09:44:55 INFO - PROCESS | 2074 | 09:44:55 INFO - PROCESS | 2074 | __start_tp_report 09:44:55 INFO - PROCESS | 2074 | _x_x_mozilla_page_load 09:44:55 INFO - PROCESS | 2074 | _x_x_mozilla_page_load_details 09:44:55 INFO - PROCESS | 2074 | |i|pagename|runs| 09:44:55 INFO - PROCESS | 2074 | |0;ai-astar;104;112;112;112;114;113;114;113;115;113 09:44:55 INFO - PROCESS | 2074 | |1;audio-beat-detection;147;124;120;175;120;120;122;122;155;120 09:44:55 INFO - PROCESS | 2074 | |2;audio-dft;162;164;163;167;169;163;163;164;164;162 09:44:55 INFO - PROCESS | 2074 | |3;audio-fft;76;76;78;113;77;79;78;78;116;77 09:44:55 INFO - PROCESS | 2074 | |4;audio-oscillator;82;83;115;84;83;84;111;84;88;105 09:44:55 INFO - PROCESS | 2074 | |5;imaging-gaussian-blur;118;118;117;117;120;118;117;118;119;117 09:44:55 INFO - PROCESS | 2074 | |6;imaging-darkroom;148;150;150;148;148;147;147;148;147;146 09:44:55 INFO - PROCESS | 2074 | |7;imaging-desaturate;97;97;96;95;96;96;96;95;95;95 09:44:55 INFO - PROCESS | 2074 | |8;json-parse-financial;53;52;52;53;53;55;56;52;54;53 09:44:55 INFO - PROCESS | 2074 | |9;json-stringify-tinderbox;62;62;61;61;61;61;64;61;63;63 09:44:55 INFO - PROCESS | 2074 | |10;stanford-crypto-aes;80;82;85;79;80;80;81;79;87;83 09:44:55 INFO - PROCESS | 2074 | |11;stanford-crypto-ccm;139;140;127;130;136;127;137;140;169;124 09:44:55 INFO - PROCESS | 2074 | |12;stanford-crypto-pbkdf2;162;168;164;161;163;161;161;159;164;164 09:44:55 INFO - PROCESS | 2074 | |13;stanford-crypto-sha256-iterative;66;66;67;65;73;63;67;63;68;68 09:44:55 INFO - PROCESS | 2074 | __end_tp_report 09:44:55 INFO - PROCESS | 2074 | __start_cc_report 09:44:55 INFO - PROCESS | 2074 | _x_x_mozilla_cycle_collect,754 09:44:55 INFO - PROCESS | 2074 | __end_cc_report 09:44:55 INFO - PROCESS | 2074 | __startTimestamp1475081095029__endTimestamp 09:44:55 INFO - PROCESS | 2074 | 09:44:55 INFO - PROCESS | 2074 | ------- Summary: start ------- 09:44:55 INFO - PROCESS | 2074 | Number of tests: 14 09:44:55 INFO - PROCESS | 2074 | 09:44:55 INFO - PROCESS | 2074 | [#0] ai-astar Cycles:10 Average:112.20 Median:113.00 stddev:3.05 (2.7%) stddev-sans-first:1.05 09:44:55 INFO - PROCESS | 2074 | Values: 104.0 112.0 112.0 112.0 114.0 113.0 114.0 113.0 115.0 113.0 09:44:55 INFO - PROCESS | 2074 | 09:44:55 INFO - PROCESS | 2074 | [#1] audio-beat-detection Cycles:10 Average:132.50 Median:123.00 stddev:19.55 (15.9%) stddev-sans-first:20.02 09:44:55 INFO - PROCESS | 2074 | Values: 147.0 124.0 120.0 175.0 120.0 120.0 122.0 122.0 155.0 120.0 09:44:55 INFO - PROCESS | 2074 | 09:44:55 INFO - PROCESS | 2074 | [#2] audio-dft Cycles:10 Average:164.10 Median:164.00 stddev:2.23 (1.4%) stddev-sans-first:2.24 09:44:55 INFO - PROCESS | 2074 | Values: 162.0 164.0 163.0 167.0 169.0 163.0 163.0 164.0 164.0 162.0 09:44:55 INFO - PROCESS | 2074 | 09:44:55 INFO - PROCESS | 2074 | [#3] audio-fft Cycles:10 Average:84.80 Median:77.50 stddev:15.70 (20.3%) stddev-sans-first:16.32 09:44:55 INFO - PROCESS | 2074 | Values: 76.0 76.0 78.0 113.0 77.0 79.0 78.0 78.0 116.0 77.0 09:44:55 INFO - PROCESS | 2074 | 09:44:55 INFO - PROCESS | 2074 | [#4] audio-oscillator Cycles:10 Average:91.90 Median:83.50 stddev:13.03 (15.6%) stddev-sans-first:13.32 09:44:55 INFO - PROCESS | 2074 | Values: 82.0 83.0 115.0 84.0 83.0 84.0 111.0 84.0 88.0 105.0 09:44:55 INFO - PROCESS | 2074 | 09:44:55 INFO - PROCESS | 2074 | [#5] imaging-gaussian-blur Cycles:10 Average:117.90 Median:118.00 stddev:0.99 (0.8%) stddev-sans-first:1.05 09:44:55 INFO - PROCESS | 2074 | Values: 118.0 118.0 117.0 117.0 120.0 118.0 117.0 118.0 119.0 117.0 09:44:55 INFO - PROCESS | 2074 | 09:44:55 INFO - PROCESS | 2074 | [#6] imaging-darkroom Cycles:10 Average:147.90 Median:148.00 stddev:1.29 (0.9%) stddev-sans-first:1.36 09:44:55 INFO - PROCESS | 2074 | Values: 148.0 150.0 150.0 148.0 148.0 147.0 147.0 148.0 147.0 146.0 09:44:55 INFO - PROCESS | 2074 | 09:44:55 INFO - PROCESS | 2074 | [#7] imaging-desaturate Cycles:10 Average:95.80 Median:96.00 stddev:0.79 (0.8%) stddev-sans-first:0.71 09:44:55 INFO - PROCESS | 2074 | Values: 97.0 97.0 96.0 95.0 96.0 96.0 96.0 95.0 95.0 95.0 09:44:55 INFO - PROCESS | 2074 | 09:44:55 INFO - PROCESS | 2074 | [#8] json-parse-financial Cycles:10 Average:53.30 Median:53.00 stddev:1.34 (2.5%) stddev-sans-first:1.41 09:44:55 INFO - PROCESS | 2074 | Values: 53.0 52.0 52.0 53.0 53.0 55.0 56.0 52.0 54.0 53.0 09:44:55 INFO - PROCESS | 2074 | 09:44:55 INFO - PROCESS | 2074 | [#9] json-stringify-tinderbox Cycles:10 Average:61.90 Median:62.00 stddev:1.10 (1.8%) stddev-sans-first:1.17 09:44:55 INFO - PROCESS | 2074 | Values: 62.0 62.0 61.0 61.0 61.0 61.0 64.0 61.0 63.0 63.0 09:44:55 INFO - PROCESS | 2074 | 09:44:55 INFO - PROCESS | 2074 | [#10] stanford-crypto-aes Cycles:10 Average:81.60 Median:81.50 stddev:2.67 (3.3%) stddev-sans-first:2.77 09:44:55 INFO - PROCESS | 2074 | Values: 80.0 82.0 85.0 79.0 80.0 80.0 81.0 79.0 87.0 83.0 09:44:55 INFO - PROCESS | 2074 | 09:44:55 INFO - PROCESS | 2074 | [#11] stanford-crypto-ccm Cycles:10 Average:136.90 Median:138.00 stddev:12.76 (9.2%) stddev-sans-first:13.51 09:44:55 INFO - PROCESS | 2074 | Values: 139.0 140.0 127.0 130.0 136.0 127.0 137.0 140.0 169.0 124.0 09:44:55 INFO - PROCESS | 2074 | 09:44:55 INFO - PROCESS | 2074 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.70 Median:163.50 stddev:2.50 (1.5%) stddev-sans-first:2.64 09:44:55 INFO - PROCESS | 2074 | Values: 162.0 168.0 164.0 161.0 163.0 161.0 161.0 159.0 164.0 164.0 09:44:55 INFO - PROCESS | 2074 | 09:44:55 INFO - PROCESS | 2074 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.60 Median:67.00 stddev:2.88 (4.3%) stddev-sans-first:3.04 09:44:55 INFO - PROCESS | 2074 | Values: 66.0 66.0 67.0 65.0 73.0 63.0 67.0 63.0 68.0 68.0 09:44:55 INFO - PROCESS | 2074 | -------- Summary: end -------- 09:44:55 INFO - PROCESS | 2074 | 09:44:55 INFO - PROCESS | 2074 | 09:44:55 INFO - PROCESS | 2074 | ###!!! [Parent][OnMaybeDequeueOne] Error: Channel error: cannot send/recv 09:44:55 INFO - PROCESS | 2074 | 09:44:55 INFO - PROCESS | 2074 | 09:44:55 INFO - PROCESS | 2074 | ###!!! [Parent][OnMaybeDequeueOne] Error: Channel error: cannot send/recv 09:44:55 INFO - PROCESS | 2074 | 09:44:55 INFO - PROCESS | 2074 | 09:44:55 INFO - PROCESS | 2074 | ###!!! [Parent][OnMaybeDequeueOne] Error: Channel error: cannot send/recv 09:44:55 INFO - PROCESS | 2074 | 09:44:55 INFO - PROCESS | 2074 | 09:44:55 INFO - PROCESS | 2074 | ###!!! [Parent][MessageChannel] Error: (msgtype=0x2000B,name=PAPZ::Msg_Destroy) Channel error: cannot send/recv 09:44:55 INFO - PROCESS | 2074 | 09:44:55 INFO - PROCESS | 2074 | 09:44:55 INFO - PROCESS | 2074 | ###!!! [Parent][OnMaybeDequeueOne] Error: Channel error: cannot send/recv 09:44:55 INFO - PROCESS | 2074 | 09:44:56 INFO - TEST-INFO | 2074: exit 0 09:44:56 INFO - TEST-OK | kraken | took 63248ms 09:44:56 INFO - SUITE-END | took 1176s 09:44:56 INFO - Completed test suite (00:19:36) 09:44:56 INFO - javascript benchmark 09:44:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7125.921528439339, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3106.052653940548, 3113.3150706844485, 3137.0745630039137, 3141.56018157458, 3149.338638885834, 5275.525202731754, 5422.295101636787, 5452.754626041828, 5462.781488740446, 5464.23500709901, 5285.8942821143555, 5361.865953351168, 5417.052015897222, 5446.400895901447, 5449.754761035753, 4872.951270487285, 4921.483244259355, 4968.701877887338, 5006.97496512517, 5047.26814611881, 4965.627577931651, 5024.0705469488075, 5027.446980832124, 5048.924266136011, 5095.668781529207, 4834.105690447279, 4881.804727810883, 4977.178765503706, 4977.253411988195, 5069.493050694938, 4880.121578115938, 4923.433805112422, 4964.801407943678, 4982.352294201767, 5041.521055499722, 4935.111679897617, 4944.90110197794, 4950.356453661037, 4971.304017437562, 4985.700857948535, 5278.155495120801, 5395.244665746799, 5443.292371991655, 5448.210009548593, 5452.700101494416, 5683.0, 5771.769129234825, 5800.854978625526, 5807.82576522705, 5929.762809487615, 5865.032269675498, 5889.4993925515955, 5890.646561206341, 5896.469317761379, 5907.113932910055, 5476.479734425223, 5483.643563168461, 5493.505584497374, 5497.065498865202, 5498.202760599731, 5789.507891829242, 5848.912266316009, 5983.581149319593, 6026.819195424145, 6043.486303664238, 5728.255326807488, 5785.652860828363, 5795.449432303924, 5797.652140871562, 5829.504492118217, 5671.064274394761, 5829.125631155276, 5866.061430171152, 5889.558283128783, 5892.263467066617, 5652.63257888236, 5781.653100813965, 5793.478586927113, 5805.88388232233, 5895.204147440127, 5852.063669812895, 5854.560907931922, 5868.09044598088, 5878.470937615635, 5881.38245484218, 4510.526394728572, 4533.52397998212, 4534.0, 4534.88662783433, 4582.243929751554, 3904.316744569689, 3910.8044597770568, 3982.78094704794, 4010.8195131219163, 4031.9395209071886, 5533.418991005967, 5595.7481913313995, 5633.8309850704545, 5636.915446268309, 5688.175214593944, 5840.415958404211, 5870.501007414332, 5904.291485021825, 5909.143174239797, 5909.320428150818, 5486.259354987105, 5496.862578435571, 5508.586855985737, 5560.582956278296, 5606.131049687305, 5025.698458092526, 5030.84907452777, 5032.672876263105, 5055.87360315995, 5079.466656001141, 6247.312795592481, 6267.843303917439, 6267.874642507124, 6267.905981410283, 6269.686515674198, 5728.312602487562, 5771.480566749013, 5896.734646940812, 6009.969950150221, 6028.2766068072115, 6097.5731698780655, 6186.257649082139, 6197.349278325711, 6199.0, 6207.068939659087, 5850.970745146247, 5861.677607731445, 5865.648061116347, 5865.94134058654, 5868.530517558671, 5695.943040569541, 5702.258706368146, 5913.733881975408, 5990.940090599038, 5996.130561068708, 5704.144378343116, 5728.713564321684, 5740.253767010262, 5762.366139724711, 5806.0, 5750.13747937796, 5759.7408116634015, 5778.537716982715, 5782.826515204551, 5789.102689083199, 5497.47773961489, 5530.80642177538, 5625.296837895263, 5736.5410767139365, 5739.253896993364, 5757.740901659352, 5841.50347220491, 5842.561807864342, 5903.350631430453, 5907.497862681548, 5596.944030559643, 5660.886782264414, 5674.0, 5679.375268720355, 5681.034224181982], "value": 5406.743027009139, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [558.606182641238, 573.9282589676291, 577.465844094213, 580.5587753307486, 619.4548797058587, 37391.43912841306, 41440.0, 41469.585304147025, 41493.58506414935, 41512.37731434027, 41000.17999640005, 41333.0, 41483.17033659325, 41633.58366416335, 41659.16681666365, 8589.441686290387, 8737.43206691563, 8751.299896008304, 8793.296536277083, 8838.204561589455, 8557.0, 8651.48091114532, 8677.69628063018, 8689.739307820775, 8713.390062695615, 8604.827903441927, 8728.738137855875, 8749.95625021874, 8777.034526202113, 8800.603972821238, 8644.956775216147, 8665.783355416102, 8684.8697269541, 8731.0, 8743.4753914765, 8637.438566493158, 8661.696840610612, 8678.479291242546, 8699.565021748951, 8731.47611143327, 40551.188976220605, 40955.0, 41046.0, 41072.58927410747, 41117.79441102805, 3078.1996680863135, 3444.0356700124007, 3633.0, 3854.7687138771485, 4109.630133287988, 31090.378192436252, 31316.37367252642, 31363.686363136534, 31489.0, 31659.84170079135, 3987.521497420299, 4001.919961600752, 4048.7246517347235, 4050.0077481017047, 4114.897127571805, 244357.0, 247560.0, 338475.0, 343739.0, 344603.9999999975, 54186.72906635442, 54487.727561362735, 54525.45474545283, 54583.727081365134, 54637.45362546323, 34983.475247871305, 36059.75462208414, 36230.27539449196, 37825.81087094547, 37889.81055094762, 11247.100231981422, 11294.322340659586, 11310.830337544943, 11322.0, 11376.544938202545, 10516.316439431412, 10721.463926803628, 10727.356358618508, 10754.56981720738, 10788.298760580537, 18059.367922122925, 18149.455516334532, 18235.17941692627, 18250.726239106425, 18273.817261827477, 6759.290274521203, 6901.201642144445, 7043.577385356895, 7063.964680176567, 7163.1404231491515, 887.2857349833317, 972.3048020665258, 1005.2008653120787, 1010.9090181883665, 1143.3140115930375, 30217.848910755743, 30864.845675771478, 31027.379452410827, 31340.529892051636, 31511.0, 1049.7428130108171, 1052.5368837711383, 1073.4471747050275, 1078.417654466595, 1082.4371326909963, 16124.435644752377, 16571.254293556576, 16573.50279491593, 16592.336306547844, 16611.750823737653, 143890.0, 144293.27853360667, 144409.27795360956, 144759.0, 520535.0, 55288.72355638276, 55916.16125758117, 56015.43984560184, 56040.0, 56132.94803467707, 1251.055453132879, 1253.7617852607975, 1268.6511209417483, 1288.072587736828, 1292.1084451728277, 29640.407191856473, 30314.696853031186, 30375.696243037288, 30605.846970765004, 30617.387652247275, 43252.56747432611, 43808.12383752371, 43819.12361752811, 49576.0, 49614.0, 433651.0, 434204.0, 434955.0, 435422.0, 435937.0, 27487.725122748518, 27941.72058279391, 27942.580861287504, 28017.859910700317, 28080.71919280863, 45816.08367832691, 46035.0, 46265.0, 46694.299585506924, 47352.7632361836, 37152.0, 38657.806710966266, 39119.41320880246, 39122.804385977885, 39551.80224098861, 37846.566083765276, 38536.80731596324, 38808.0, 40580.797096015514, 41049.79475102606, 39370.6062939367, 40009.39985900272, 40045.39931900966, 40112.0, 40390.79804601076, 33501.49747753737, 36371.0, 36502.26995460129, 36618.63381366152, 37048.25903481861, 28216.85891570529, 28575.714242857306, 28700.0, 28844.855775721826, 28846.278843028253], "value": 18431.276719830665, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [402.19469202313866, 404.81985516445195, 409.5269963192513, 411.09558970265425, 426.53507676632466, 17847.82152178478, 19398.612027759453, 19408.126634301454, 19436.0, 19465.416037518862, 16718.916405417986, 16951.66096678066, 17200.913995430037, 17695.91152044238, 17844.643107137883, 5409.9459005409935, 5526.309211348582, 5634.943650563503, 5866.794662186824, 5919.852003699899, 5790.7104644767805, 5866.120081987693, 5875.294964604253, 5884.911726324087, 5900.616459930112, 5622.690752008638, 5743.597948143632, 5757.68332741699, 5771.220885180489, 5827.475527202551, 5702.515286200678, 5764.0, 5765.798197063083, 5767.192593036978, 5794.333651630053, 4976.676516026447, 5157.200633901752, 5184.792608295664, 5198.974005129988, 5230.738463076869, 22116.33650990473, 22730.20444284442, 22822.429439263986, 22840.086396544117, 23040.53918921612, 2323.639835825433, 2396.101461951768, 2827.3921106962102, 3066.708662677042, 3074.0777766669908, 9585.952070239604, 9753.21974242059, 9959.502024898726, 11080.556777728962, 15061.17163556004, 1818.3908390689135, 1905.2664724081328, 1933.0, 1944.474991752219, 1957.9510512237166, 78359.0, 81481.18518814795, 81536.0, 82396.58801705953, 82491.58754206251, 10840.831833633305, 11004.22970392081, 11041.282316649553, 11111.777764444665, 11671.88328116708, 15462.536123916301, 15475.845241547666, 15476.613084672974, 15488.457903973307, 15539.223038848013, 7375.926240737631, 7430.702771889064, 7514.736984205527, 7540.472166948261, 7807.570583617956, 6547.672616369163, 6892.379685828299, 7115.501914865909, 7127.679254433563, 7206.0, 4329.718568293114, 4397.846075387346, 4707.8587642370785, 4777.378940737682, 4906.11689895815, 1923.4325873867242, 1944.0862794486568, 1998.490384951857, 2000.5398758285676, 2011.1452632631074, 899.7750562359411, 993.6323560282742, 998.605550807427, 1002.6891663584312, 1030.7680771826429, 18844.34044808425, 19126.426207213528, 19129.0, 19292.710609340873, 19566.804331956784, 934.8968217503411, 947.5972711597543, 954.2080073538946, 956.9712908612753, 1031.128696251666, 9874.456904870158, 10472.0, 10758.408287544104, 10769.730756731144, 10924.96212859777, 388020.0, 394775.0, 402100.0, 404912.0, 406389.45689616125, 84458.5777071123, 87664.1233587656, 89434.0, 89793.0, 90231.0, 990.3760630802692, 992.3648864726581, 999.5402115027024, 1000.0849222960909, 1006.627547807316, 16469.505914822093, 16825.747613786047, 16917.57706057309, 17028.914855425643, 17458.47624571265, 27778.305542361602, 31858.20354491156, 40284.59715402809, 41026.38460423036, 42160.57839421566, 56444.71777641086, 57092.429075708715, 57474.13788793255, 57879.0, 57969.71015144897, 7438.995735575844, 7555.848883022419, 7805.18045605226, 7932.722354717557, 8065.112837587978, 9164.404313719588, 11770.764584708086, 11912.761744764883, 12042.13346931227, 12587.559435419716, 10455.0, 10687.412192329186, 10853.511591978538, 10935.125189984941, 11510.36692981894, 10779.245452818228, 10900.0, 10946.343219406861, 10956.945215273872, 11085.334879907232, 10570.2600817942, 10668.8933110671, 10915.508802103766, 11042.779144417227, 11565.306081635128, 7666.929928919049, 7891.684332626631, 7930.801729956566, 7971.242731940456, 8064.112947575879, 10221.59113635476, 10754.83867742, 10766.300190487907, 10836.891631083588, 11042.558297668325], "value": 8919.546891222635, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1235.7775600391926, 1311.4491913396373, 1346.9359206227082, 1733.505950804021, 1762.7884653841534, 8011.0, 8261.710840120597, 8605.784855378612, 9273.350865439414, 9275.165235128838, 8529.53087580183, 8854.955725221382, 8857.379983401164, 9071.092890710926, 9082.364234503588, 2408.301592538162, 2418.7218469875925, 2439.377958620549, 2440.511897620483, 2444.6699695541142, 2290.438842483594, 2301.539692061586, 2320.431494283903, 2330.0679728108807, 2406.602910519762, 2382.690250267463, 2383.8212134089927, 2392.6769886065417, 2404.3989002749313, 2404.483036147228, 2266.6939963104933, 2269.047000259895, 2276.692646492727, 2282.132789539981, 2305.896234669444, 2257.1874125314876, 2284.885755712224, 2294.128231272106, 2295.885205739723, 2312.5606134834497, 9003.5048072356, 9101.40840845343, 9119.81760364789, 9119.863202051974, 9119.954400228024, 5086.109930762102, 5090.5927525798215, 5159.638825282232, 5215.348081489814, 5244.134717771563, 3449.534312867756, 3512.244867353506, 3584.731145164123, 3593.574337322297, 3644.234710710754, 1145.9255148415327, 1177.146568737667, 1181.0, 1192.2786714038054, 1210.806270996645, 12712.491500339975, 12742.426590803436, 12748.553800616934, 12749.171303865312, 12751.171173873669, 4577.107464044546, 4775.785089670973, 4848.296996935424, 4849.903001940012, 4940.110780059624, 10498.212634052477, 10501.527431265611, 10520.26358154937, 10535.157187424988, 10613.310134841211, 5299.284596579412, 5321.547668448148, 5322.866928326823, 5323.733813309319, 5326.440723723953, 4647.62818974488, 4775.6418268629295, 4818.927716084191, 4852.393450818648, 4910.533499317559, 3658.725595580289, 3675.1363429594026, 3708.165662725919, 3717.665410113103, 3721.4789929409903, 1214.168294718121, 1221.5785553983862, 1245.7570773699042, 1249.7000719827358, 1261.9558315458914, 788.3220430429826, 869.6304070769897, 909.713440266314, 919.4529255093208, 936.5410948635256, 5864.149698293723, 5905.350411454737, 6003.969980150158, 6223.937760622426, 6329.936700633027, 938.4369378372921, 942.8444306689456, 967.434051080126, 989.8861630912537, 1009.3187098708489, 3900.239453306578, 4164.916701665949, 4195.832166713297, 4243.087736136746, 4280.122574872144, 11082.22424430282, 11408.600698975495, 11478.713032174262, 11481.0, 11497.0, 11228.382438965935, 11268.830967535494, 11322.490487927898, 11450.255733377304, 11452.656420307405, 8076.55578943152, 8109.229623185788, 8375.74872753806, 8385.245327920395, 8431.367647426467, 5658.292713410824, 5772.422757724194, 5773.3072031356505, 5869.325027621708, 5920.851978700567, 10394.428306443218, 10454.215933805148, 10511.159107271247, 10520.736981575521, 10550.261481696207, 10157.441340726331, 10322.174226061747, 10607.575696972035, 10736.624218152327, 10877.836832447678, 6709.194896612437, 6933.7226510939, 7234.059572255573, 7434.442416818868, 7504.699812007459, 8827.558622069, 8839.46963182211, 8880.245179159585, 8896.5996530155, 8918.598663060307, 7643.770686879402, 7702.691892324468, 7718.6140692964, 7900.960495197487, 7943.205679432011, 7562.281583249583, 7579.696812127674, 7590.0, 7698.34564062061, 7703.499272547267, 6970.79087627372, 7136.250693677089, 7147.213806481387, 7157.749478768426, 7224.024756657994, 5081.568066714222, 5122.231665250242, 5135.794568217229, 5194.324737784215, 5307.4692530746615, 5018.473060328759, 5091.414487334004, 5191.662541934762, 5260.658057226268, 5429.131338985743], "value": 4674.010633417838, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [336.6498841205147, 347.93563190809704, 353.80237894726343, 355.7420869869345, 357.15176455917197, 5541.722913854306, 5551.1673249012665, 5576.944230557699, 5595.020871347519, 5610.0, 5495.917561236584, 5507.614466987313, 5525.204308599701, 5529.474699903502, 5561.193626924104, 3770.4532842737785, 4055.0, 4128.793560321987, 4140.544540100586, 4259.382389553512, 4263.676296418459, 4279.737477444161, 4302.634276086536, 4304.160688665712, 4329.220740266751, 4225.450691410112, 4264.680148988823, 4286.228478873802, 4287.699861009731, 4288.549702281262, 4015.377616469452, 4227.767472788995, 4283.935740963888, 4288.892777680552, 4303.440552728141, 3734.0104872208885, 3745.7377983541164, 3751.3247615429214, 3757.605451427615, 3765.284595926765, 5337.973310133463, 5407.972960135214, 5417.783288668448, 5431.158170483582, 5443.782248710047, 4812.614990800727, 4914.336564563774, 4920.532549407801, 4926.901461970776, 4943.961768028718, 5681.52280407093, 5703.201551782754, 5743.942560574383, 5754.058469948057, 5782.739776710058, 4085.5301640311445, 4090.386442033689, 4113.197926404352, 4131.7191670582215, 4152.937705934413, 5798.811243695074, 6018.729157187937, 6024.0, 6026.2825094848195, 6033.306169790487, 4190.434291370687, 4208.915821683549, 4213.0099426634715, 4226.4928208615165, 4237.936430953539, 4253.681743449827, 4266.679999000087, 4275.315949448135, 4278.914421711548, 4281.721688090264, 4651.418572678415, 4896.2655601660035, 4915.95084049155, 4922.237053256751, 4948.268106162815, 5786.797462088806, 5792.333881603669, 5796.362400135981, 5808.3901190375245, 5835.153902684087, 1474.9896321019921, 1492.4701730885597, 1492.4925525321444, 1494.9551513454614, 1496.6931778985281, 919.6735159018585, 958.6453012385323, 962.9614815407446, 963.0321526865455, 976.1946394224651, 4925.768557860535, 4944.975275123673, 4991.351124353883, 5000.199968005102, 5021.397432308073, 5135.820246291362, 5150.690958542499, 5177.301064356339, 5211.713355765393, 5226.451222621646, 4694.741789201558, 4906.8773280668265, 4919.4834542373255, 5007.474215207423, 5009.699418034929, 2764.8940423830627, 2771.3071732066983, 2778.6103950602464, 2794.944101117966, 2810.718928107173, 5353.92149514031, 5399.6490228135835, 5447.836564903059, 5459.962607104717, 5476.411840566191, 5082.788986790758, 5219.552609776281, 5267.631265811357, 5280.656757310762, 5359.196120581944, 5990.580659353804, 6143.815685529441, 6153.20029386628, 6167.0, 6277.7175027123885, 6008.699565021819, 6015.4586087252355, 6033.849153771015, 6041.426064523863, 6059.666718330534, 5797.2173756542325, 5856.267966504187, 5881.176635271151, 5940.762369505172, 5962.344142144448, 6171.84570385744, 6172.629642221481, 6191.907121393273, 6209.472194863487, 6235.594686345373, 4272.188284225987, 4296.474751463311, 4300.634446072119, 4309.07354918694, 4328.891777705582, 5820.970895145497, 5916.792912248051, 6006.888725585781, 6020.277566692025, 6042.939570604413, 5785.392533783892, 5805.187273781589, 5824.94175058244, 5828.358880523224, 5840.912386314294, 5699.800506982236, 5713.514351280188, 5760.654360738202, 5765.855853603525, 5826.416753696531, 5673.177389278614, 5716.742746576331, 5735.54115670737, 5739.847557166063, 5753.1370294456165, 5814.215080964016, 5832.708364581838, 5835.920354734388, 5896.410358964069, 5902.911456328245, 5489.313835770528, 5611.775528978795, 5667.781426993216, 5670.971645141913, 5673.716314184357], "value": 4321.05918371584, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [606.8755905039467, 717.9425645948321, 721.97119105275, 742.858856817205, 751.1436961863477, 14504.927475362634, 14544.781828272584, 14846.406143754237, 16015.27931243094, 16025.759613605805, 14121.858781412182, 14334.64163395913, 14354.066985645952, 14390.136591804472, 14452.783208251858, 773.1109224391938, 776.425445170574, 792.8850316704074, 794.737736546941, 794.9443538952276, 798.6286376834771, 800.5516910530122, 803.8593246181924, 805.1867613710136, 805.7421625079977, 831.6714897615439, 834.5159807311744, 834.8330333933237, 835.3818174550819, 835.4945258118842, 816.8243827577099, 827.1067247372883, 827.3629305434812, 827.4042689263721, 829.8962629671291, 595.8986972214691, 599.2629066248495, 604.5737754882797, 604.7611193578512, 606.8391876152823, 14971.0, 15041.924790376088, 15185.164815935119, 15212.46756363522, 15232.847671523254, 14311.141331520143, 14627.341769620392, 14780.926095369454, 14956.925215373962, 15275.847241527554, 10900.890991090146, 10979.1765617579, 11239.325640461517, 11516.539338426453, 11586.13104017202, 4554.180247555439, 4625.375574297461, 4642.442906851199, 4695.60087392572, 4711.7879695413785, 79082.83668653246, 79277.60361198215, 79499.0, 82212.17787822164, 83018.58490707568, 10981.725456863642, 10988.780224395467, 11711.76576468483, 11743.88256117445, 11763.647090587296, 21823.345299641038, 21844.89077554602, 21847.08440600884, 22065.33803985883, 22102.778972210395, 8055.556944368116, 8389.035260944947, 8502.914970850212, 8537.317014638815, 8820.514871681991, 4639.118567472169, 4648.628109751212, 4664.416947881515, 4713.481517033124, 4733.26634371666, 3410.9488357674654, 3485.8431370588382, 3514.5079688843584, 3527.788332699995, 3536.398812201932, 622.6575383539035, 624.2134910013416, 628.2210059526154, 642.9292777794439, 648.222133439874, 572.1789232451446, 574.3136951343105, 594.4115325827399, 595.7051259626512, 606.5087279303779, 8564.828703425897, 8819.779505512286, 8934.955325223462, 9139.634414623473, 9342.0, 604.9879002419927, 614.532954954229, 622.6233128957007, 627.3099590450468, 632.772202007277, 4021.7788021658503, 4051.736637118578, 4188.246115699142, 4257.4252475915355, 4378.43080399546, 113870.43064784788, 125560.0, 125883.0, 127295.0, 127610.36194818966, 66837.6658116716, 91965.0, 104935.47532262442, 105003.0, 107165.0, 60437.69781151066, 66178.66910665415, 74740.25259747442, 76027.23972760313, 88804.5559772197, 8380.329573634217, 8516.105808889977, 8595.484270943763, 8940.619096642728, 9294.581743821409, 51909.74045129901, 52320.738396307774, 52393.0, 52681.736591316796, 53194.73402632962, 46155.07689846252, 47464.0, 52816.0, 78935.0, 79102.20897790948, 12362.752744944872, 12423.192492487959, 12556.686082848002, 12563.309018004098, 12968.675783105498, 13920.930395348363, 13922.303884805922, 14299.0, 14345.354459049577, 14380.209088500233, 12147.149699521302, 12214.57248996281, 12229.938850306047, 12318.568850090203, 12355.196912200678, 13332.870661835315, 13708.108972916729, 13916.776979558714, 14075.718485630025, 14135.64660883445, 11662.825057624312, 12124.757504849676, 12244.142909996215, 12475.295586144754, 12779.61661150167, 10183.541740621838, 10383.636572719919, 10770.138388929023, 11310.717232068935, 11412.429378531206, 9828.606855725977, 10397.58409663605, 10447.477626118816, 10521.263511554424, 10589.099926506333], "value": 7292.76961192426, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1510.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 112.0, 112.0, 112.0, 114.0, 113.0, 114.0, 113.0, 115.0, 113.0], "value": 112.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 124.0, 120.0, 175.0, 120.0, 120.0, 122.0, 122.0, 155.0, 120.0], "value": 132.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 164.0, 163.0, 167.0, 169.0, 163.0, 163.0, 164.0, 164.0, 162.0], "value": 164.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 78.0, 113.0, 77.0, 79.0, 78.0, 78.0, 116.0, 77.0], "value": 84.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 115.0, 84.0, 83.0, 84.0, 111.0, 84.0, 88.0, 105.0], "value": 91.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 117.0, 117.0, 120.0, 118.0, 117.0, 118.0, 119.0, 117.0], "value": 117.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 150.0, 150.0, 148.0, 148.0, 147.0, 147.0, 148.0, 147.0, 146.0], "value": 147.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 97.0, 96.0, 95.0, 96.0, 96.0, 96.0, 95.0, 95.0, 95.0], "value": 95.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 52.0, 52.0, 53.0, 53.0, 55.0, 56.0, 52.0, 54.0, 53.0], "value": 53.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 61.0, 61.0, 61.0, 61.0, 64.0, 61.0, 63.0, 63.0], "value": 61.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 82.0, 85.0, 79.0, 80.0, 80.0, 81.0, 79.0, 87.0, 83.0], "value": 81.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 140.0, 127.0, 130.0, 136.0, 127.0, 137.0, 140.0, 169.0, 124.0], "value": 136.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 168.0, 164.0, 161.0, 163.0, 161.0, 161.0, 159.0, 164.0, 164.0], "value": 162.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 67.0, 65.0, 73.0, 63.0, 67.0, 63.0, 68.0, 68.0], "value": 66.6, "unit": "score"}]}]} 09:44:56 INFO - Return code: 0 09:44:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:44:56 ERROR - # TBPL SUCCESS # 09:44:56 INFO - Running post-action listener: _resource_record_post_action 09:44:56 INFO - [mozharness: 2016-09-28 16:44:56.323115Z] Finished run-tests step (success) 09:44:56 INFO - Running post-run listener: _resource_record_post_run 09:44:56 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 61440; Write bytes: 392876032; Read time: 236; Write time: 175336 09:44:56 INFO - TinderboxPrint: CPU usage
13.3% 09:44:56 INFO - TinderboxPrint: I/O read bytes / time
61,440 / 236 09:44:56 INFO - TinderboxPrint: I/O write bytes / time
392,876,032 / 175,336 09:44:56 INFO - TinderboxPrint: CPU idle
8,248.2 (86.6%) 09:44:56 INFO - TinderboxPrint: CPU user
1,209.6 (12.7%) 09:44:56 INFO - TinderboxPrint: Swap in / out
0 / 0 09:44:56 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 4096; Write bytes: 103936000; Read time: 116; Write time: 48304 09:44:56 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 4096; Write bytes: 288940032; Read time: 96; Write time: 127032 09:44:56 INFO - Running post-run listener: _upload_blobber_files 09:44:56 INFO - Blob upload gear active. 09:44:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:44:56 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:44:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:44:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:44:57 INFO - (blobuploader) - INFO - Open directory for files ... 09:44:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:44:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:44:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:44:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:44:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:44:57 INFO - (blobuploader) - INFO - Done attempting. 09:44:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:44:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:44:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:45:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:45:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:45:03 INFO - (blobuploader) - INFO - Done attempting. 09:45:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:45:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:45:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:45:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:45:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:45:04 INFO - (blobuploader) - INFO - Done attempting. 09:45:04 INFO - (blobuploader) - INFO - Iteration through files over. 09:45:04 INFO - Return code: 0 09:45:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:45:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:45:04 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/caf0afa7a42a4172341b3fbb1cfcac16815c195a60214b253b35a5805df5e3ee7caf3ea8846c2fcb77662910e491f40e3f270be2d893a981bcc9b3b1d6082f6f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b0d9f7358b01094fa249f5036527878e71f5af713cde3aba430b3ceda28d999dd64a2b6a18851b2779e669a81326e56e5809e51d9ea3b5d2bdcdd81cf74b92f7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fb5129c11a93a8b8d35844564dfb68704569e4ff88bcba363e14171fb533cf350b0e17df07177aa403f7da8d990f31d02db10fc7c20d7e68249a1ed3adcf3563"} 09:45:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:45:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:45:04 INFO - Contents: 09:45:04 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/caf0afa7a42a4172341b3fbb1cfcac16815c195a60214b253b35a5805df5e3ee7caf3ea8846c2fcb77662910e491f40e3f270be2d893a981bcc9b3b1d6082f6f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b0d9f7358b01094fa249f5036527878e71f5af713cde3aba430b3ceda28d999dd64a2b6a18851b2779e669a81326e56e5809e51d9ea3b5d2bdcdd81cf74b92f7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fb5129c11a93a8b8d35844564dfb68704569e4ff88bcba363e14171fb533cf350b0e17df07177aa403f7da8d990f31d02db10fc7c20d7e68249a1ed3adcf3563"} 09:45:04 INFO - Running post-run listener: copy_logs_to_upload_dir 09:45:04 INFO - Copying logs to upload dir... 09:45:04 INFO - mkdir: /builds/slave/test/build/upload/logs 09:45:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1240.942782 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-09-28 09:45:03.306538) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-28 09:45:03.310108) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=516f8d7ca085b4d087ab0a71000001a4-1475079860.723353-1385619047 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/caf0afa7a42a4172341b3fbb1cfcac16815c195a60214b253b35a5805df5e3ee7caf3ea8846c2fcb77662910e491f40e3f270be2d893a981bcc9b3b1d6082f6f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b0d9f7358b01094fa249f5036527878e71f5af713cde3aba430b3ceda28d999dd64a2b6a18851b2779e669a81326e56e5809e51d9ea3b5d2bdcdd81cf74b92f7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fb5129c11a93a8b8d35844564dfb68704569e4ff88bcba363e14171fb533cf350b0e17df07177aa403f7da8d990f31d02db10fc7c20d7e68249a1ed3adcf3563"} build_url:https://queue.taskcluster.net/v1/task/Xn_FpBo4QWKWV-sIXe4uWA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011432 build_url: 'https://queue.taskcluster.net/v1/task/Xn_FpBo4QWKWV-sIXe4uWA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/caf0afa7a42a4172341b3fbb1cfcac16815c195a60214b253b35a5805df5e3ee7caf3ea8846c2fcb77662910e491f40e3f270be2d893a981bcc9b3b1d6082f6f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b0d9f7358b01094fa249f5036527878e71f5af713cde3aba430b3ceda28d999dd64a2b6a18851b2779e669a81326e56e5809e51d9ea3b5d2bdcdd81cf74b92f7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fb5129c11a93a8b8d35844564dfb68704569e4ff88bcba363e14171fb533cf350b0e17df07177aa403f7da8d990f31d02db10fc7c20d7e68249a1ed3adcf3563"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-28 09:45:03.336229) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 09:45:03.336607) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=516f8d7ca085b4d087ab0a71000001a4-1475079860.723353-1385619047 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004515 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 09:45:03.418858) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-28 09:45:03.419223) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-28 09:45:03.419559) ========= ========= Total master_lag: 0.16 =========