builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-005 starttime: 1472208515.07 results: success (0) buildid: 20160826004001 builduid: 20a7bb9844bb44a1ba79b1b355c54ac9 revision: 21fd3ea62d8ccb941ac7e14d85034c5799073635 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-26 03:48:35.066173) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-26 03:48:35.066655) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-26 03:48:35.066933) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-26 03:48:35.081480) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 03:48:35.081856) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1472208514.291038-700744219 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-26 03:48:35-- 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% 219M=0s 2016-08-26 03:48:35 (219 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.089414 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 03:48:35.184997) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 03:48:35.185316) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1472208514.291038-700744219 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020307 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 03:48:35.234933) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-26 03:48:35.235308) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 21fd3ea62d8ccb941ac7e14d85034c5799073635 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 21fd3ea62d8ccb941ac7e14d85034c5799073635 --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=7fc86f70bae03e018da0b1d8000001c4-1472208514.291038-700744219 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-26 03:48:35,276 truncating revision to first 12 chars 2016-08-26 03:48:35,276 Setting DEBUG logging. 2016-08-26 03:48:35,276 attempt 1/10 2016-08-26 03:48:35,276 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/21fd3ea62d8c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-26 03:48:36,784 unpacking tar archive at: mozilla-aurora-21fd3ea62d8c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.816250 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-26 03:48:37.064893) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-26 03:48:37.065231) ========= script_repo_revision: 21fd3ea62d8ccb941ac7e14d85034c5799073635 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-26 03:48:37.065647) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-26 03:48:37.065914) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-26 03:48:37.076387) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 56 secs) (at 2016-08-26 03:48:37.076710) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=7fc86f70bae03e018da0b1d8000001c4-1472208514.291038-700744219 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:48:37 INFO - MultiFileLogger online at 20160826 03:48:37 in /builds/slave/test 03:48:37 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 03:48:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:48:37 INFO - {'append_to_log': False, 03:48:37 INFO - 'base_work_dir': '/builds/slave/test', 03:48:37 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:48:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:48:37 INFO - 'branch': 'Mozilla-Aurora', 03:48:37 INFO - 'buildbot_json_path': 'buildprops.json', 03:48:37 INFO - 'config_files': ('talos/linux_config.py',), 03:48:37 INFO - 'default_actions': ('clobber', 03:48:37 INFO - 'read-buildbot-config', 03:48:37 INFO - 'download-and-extract', 03:48:37 INFO - 'populate-webroot', 03:48:37 INFO - 'create-virtualenv', 03:48:37 INFO - 'install', 03:48:37 INFO - 'run-tests'), 03:48:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:48:37 INFO - 'download_minidump_stackwalk': True, 03:48:37 INFO - 'download_symbols': 'ondemand', 03:48:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:48:37 INFO - 'tooltool.py': '/tools/tooltool.py', 03:48:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:48:37 INFO - '/tools/misc-python/virtualenv.py')}, 03:48:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:48:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:48:37 INFO - 'installer_path': 'installer.exe', 03:48:37 INFO - 'log_level': 'info', 03:48:37 INFO - 'log_name': 'talos', 03:48:37 INFO - 'log_to_console': True, 03:48:37 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:48:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:48:37 INFO - 'opt_config_files': (), 03:48:37 INFO - 'pip_index': False, 03:48:37 INFO - 'sps_profile': False, 03:48:37 INFO - 'sps_profile_interval': 0, 03:48:37 INFO - 'suite': 'dromaeojs', 03:48:37 INFO - 'system_bits': '32', 03:48:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:48:37 INFO - 'title': 'talos-linux64-ix-005', 03:48:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:48:37 INFO - 'use_talos_json': True, 03:48:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:48:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:48:37 INFO - 'work_dir': 'build'} 03:48:37 INFO - [mozharness: 2016-08-26 10:48:37.210420Z] Running clobber step. 03:48:37 INFO - Running pre-action listener: _resource_record_pre_action 03:48:37 INFO - Running main action method: clobber 03:48:37 INFO - rmtree: /builds/slave/test/build 03:48:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:48:38 INFO - Running post-action listener: _resource_record_post_action 03:48:38 INFO - [mozharness: 2016-08-26 10:48:38.169424Z] Finished clobber step (success) 03:48:38 INFO - [mozharness: 2016-08-26 10:48:38.169525Z] Running read-buildbot-config step. 03:48:38 INFO - Running pre-action listener: _resource_record_pre_action 03:48:38 INFO - Running main action method: read_buildbot_config 03:48:38 INFO - Using buildbot properties: 03:48:38 INFO - { 03:48:38 INFO - "project": "", 03:48:38 INFO - "product": "firefox", 03:48:38 INFO - "script_repo_revision": "production", 03:48:38 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 03:48:38 INFO - "repository": "", 03:48:38 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 03:48:38 INFO - "buildid": "20160826004001", 03:48:38 INFO - "stage_platform": "linux64-pgo", 03:48:38 INFO - "basedir": "/builds/slave/test", 03:48:38 INFO - "nightly_build": "True", 03:48:38 INFO - "buildnumber": 495, 03:48:38 INFO - "slavename": "talos-linux64-ix-005", 03:48:38 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 03:48:38 INFO - "platform": "ubuntu64_hw", 03:48:38 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 03:48:38 INFO - "branch": "mozilla-aurora", 03:48:38 INFO - "repo_path": "releases/mozilla-aurora", 03:48:38 INFO - "revision": "21fd3ea62d8ccb941ac7e14d85034c5799073635", 03:48:38 INFO - "pgo_build": "True", 03:48:38 INFO - "builduid": "20a7bb9844bb44a1ba79b1b355c54ac9", 03:48:38 INFO - "slavebuilddir": "test" 03:48:38 INFO - } 03:48:38 INFO - Found installer url https://queue.taskcluster.net/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 03:48:38 INFO - Running post-action listener: _resource_record_post_action 03:48:38 INFO - [mozharness: 2016-08-26 10:48:38.172048Z] Finished read-buildbot-config step (success) 03:48:38 INFO - [mozharness: 2016-08-26 10:48:38.172136Z] Running download-and-extract step. 03:48:38 INFO - Running pre-action listener: _resource_record_pre_action 03:48:38 INFO - Running main action method: download_and_extract 03:48:38 INFO - mkdir: /builds/slave/test/build/tests 03:48:38 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:48:38 INFO - https://queue.taskcluster.net/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 03:48:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:48:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:48:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:48:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 03:48:38 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:48:38 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:48:38 INFO - retry: Failed, sleeping 30 seconds before retrying 03:49:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #2 03:49:08 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:49:08 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:49:08 INFO - retry: Failed, sleeping 60 seconds before retrying 03:50:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #3 03:50:08 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:50:08 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:50:08 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json! 03:50:08 INFO - Caught exception: HTTP Error 404: Not Found 03:50:08 INFO - Caught exception: HTTP Error 404: Not Found 03:50:08 INFO - Caught exception: HTTP Error 404: Not Found 03:50:08 INFO - trying https://queue.taskcluster.net/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:50:08 INFO - Downloading https://queue.taskcluster.net/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:50:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 03:50:08 INFO - Downloaded 1361 bytes. 03:50:08 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:50:08 INFO - Using the following test package requirements: 03:50:08 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 03:50:08 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:50:08 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 03:50:08 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:50:08 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 03:50:08 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:50:08 INFO - u'jsshell-linux-x86_64.zip'], 03:50:08 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:50:08 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 03:50:08 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 03:50:08 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:50:08 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 03:50:08 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:50:08 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 03:50:08 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:50:08 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 03:50:08 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:50:08 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 03:50:08 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:50:08 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:50:08 INFO - https://queue.taskcluster.net/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:50:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:50:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:50:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:50:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:50:08 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:50:08 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:50:08 INFO - retry: Failed, sleeping 30 seconds before retrying 03:50:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 03:50:38 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:50:38 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:50:38 INFO - retry: Failed, sleeping 60 seconds before retrying 03:51:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 03:51:38 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:51:38 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:51:38 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 03:51:38 INFO - Caught exception: HTTP Error 404: Not Found 03:51:38 INFO - Caught exception: HTTP Error 404: Not Found 03:51:38 INFO - Caught exception: HTTP Error 404: Not Found 03:51:38 INFO - trying https://queue.taskcluster.net/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:51:38 INFO - Downloading https://queue.taskcluster.net/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:51:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:51:39 INFO - Downloaded 21769613 bytes. 03:51:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:51:39 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 03:51:40 INFO - Return code: 0 03:51:40 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 03:51:40 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:51:40 INFO - https://queue.taskcluster.net/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:51:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:51:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:51:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:51:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:51:40 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:51:40 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:51:40 INFO - retry: Failed, sleeping 30 seconds before retrying 03:52:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 03:52:11 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:52:11 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:52:11 INFO - retry: Failed, sleeping 60 seconds before retrying 03:53:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 03:53:11 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:53:11 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:53:11 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 03:53:11 INFO - Caught exception: HTTP Error 404: Not Found 03:53:11 INFO - Caught exception: HTTP Error 404: Not Found 03:53:11 INFO - Caught exception: HTTP Error 404: Not Found 03:53:11 INFO - trying https://queue.taskcluster.net/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:53:11 INFO - Downloading https://queue.taskcluster.net/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:53:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:53:11 INFO - Downloaded 21769613 bytes. 03:53:11 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:53:11 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 03:53:13 INFO - Return code: 0 03:53:13 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:53:13 INFO - https://queue.taskcluster.net/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 03:53:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:53:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:53:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:53:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 03:53:13 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:53:13 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:53:13 INFO - retry: Failed, sleeping 30 seconds before retrying 03:53:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 03:53:43 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:53:43 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:53:43 INFO - retry: Failed, sleeping 60 seconds before retrying 03:54:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 03:54:43 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:54:43 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:54:43 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip! 03:54:43 INFO - Caught exception: HTTP Error 404: Not Found 03:54:43 INFO - Caught exception: HTTP Error 404: Not Found 03:54:43 INFO - Caught exception: HTTP Error 404: Not Found 03:54:43 INFO - trying https://queue.taskcluster.net/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:54:43 INFO - Downloading https://queue.taskcluster.net/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:54:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 03:54:44 INFO - Downloaded 14413723 bytes. 03:54:44 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:54:44 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 03:54:44 INFO - Return code: 0 03:54:44 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:54:44 INFO - https://queue.taskcluster.net/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 03:54:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:54:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:54:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:54:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 03:54:44 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:54:44 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:54:44 INFO - retry: Failed, sleeping 30 seconds before retrying 03:55:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #2 03:55:14 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:55:14 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:55:14 INFO - retry: Failed, sleeping 60 seconds before retrying 03:56:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #3 03:56:14 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:56:14 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:56:14 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2! 03:56:14 INFO - Caught exception: HTTP Error 404: Not Found 03:56:14 INFO - Caught exception: HTTP Error 404: Not Found 03:56:14 INFO - Caught exception: HTTP Error 404: Not Found 03:56:14 INFO - trying https://queue.taskcluster.net/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:56:14 INFO - Downloading https://queue.taskcluster.net/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:56:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 03:56:15 INFO - Downloaded 63750489 bytes. 03:56:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:56:15 INFO - mkdir: /builds/slave/test/properties 03:56:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:56:15 INFO - Writing to file /builds/slave/test/properties/build_url 03:56:15 INFO - Contents: 03:56:15 INFO - build_url:https://queue.taskcluster.net/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:56:16 INFO - Running post-action listener: _resource_record_post_action 03:56:16 INFO - Running post-action listener: set_extra_try_arguments 03:56:16 INFO - [mozharness: 2016-08-26 10:56:16.303271Z] Finished download-and-extract step (success) 03:56:16 INFO - [mozharness: 2016-08-26 10:56:16.303504Z] Running populate-webroot step. 03:56:16 INFO - Running pre-action listener: _resource_record_pre_action 03:56:16 INFO - Running main action method: populate_webroot 03:56:16 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:56:16 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:56:16 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:56:16 INFO - '--tppagecycles', 03:56:16 INFO - '7'], 03:56:16 INFO - 'tests': ['tsvgm']}}, 03:56:16 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:56:16 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:56:16 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:56:16 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:56:16 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:56:16 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:56:16 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:56:16 INFO - 'talos_options': ['--disable-e10s'], 03:56:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:56:16 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:56:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:56:16 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:56:16 INFO - 'talos_options': ['--disable-e10s'], 03:56:16 INFO - 'tests': ['damp', 'tps']}, 03:56:16 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:56:16 INFO - 'tests': ['damp', 'tps']}, 03:56:16 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:56:16 INFO - 'tests': ['dromaeo_dom']}, 03:56:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:56:16 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:56:16 INFO - 'tests': ['basic_compositor_video']}, 03:56:16 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:56:16 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:56:16 INFO - 'tests': ['a11yr', 03:56:16 INFO - 'ts_paint', 03:56:16 INFO - 'tpaint', 03:56:16 INFO - 'sessionrestore', 03:56:16 INFO - 'sessionrestore_no_auto_restore', 03:56:16 INFO - 'tabpaint']}, 03:56:16 INFO - 'other-e10s': {'tests': ['a11yr', 03:56:16 INFO - 'ts_paint', 03:56:16 INFO - 'tpaint', 03:56:16 INFO - 'sessionrestore', 03:56:16 INFO - 'sessionrestore_no_auto_restore', 03:56:16 INFO - 'tabpaint']}, 03:56:16 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:56:16 INFO - 'ts_paint', 03:56:16 INFO - 'tpaint', 03:56:16 INFO - 'sessionrestore', 03:56:16 INFO - 'sessionrestore_no_auto_restore', 03:56:16 INFO - 'tabpaint']}, 03:56:16 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:56:16 INFO - 'ts_paint', 03:56:16 INFO - 'tpaint', 03:56:16 INFO - 'sessionrestore', 03:56:16 INFO - 'sessionrestore_no_auto_restore', 03:56:16 INFO - 'tabpaint']}, 03:56:16 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:56:16 INFO - 'tests': ['a11yr', 03:56:16 INFO - 'ts_paint', 03:56:16 INFO - 'tpaint', 03:56:16 INFO - 'sessionrestore', 03:56:16 INFO - 'sessionrestore_no_auto_restore', 03:56:16 INFO - 'tabpaint']}, 03:56:16 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:56:16 INFO - 'tests': ['a11yr', 03:56:16 INFO - 'ts_paint', 03:56:16 INFO - 'tpaint', 03:56:16 INFO - 'sessionrestore', 03:56:16 INFO - 'sessionrestore_no_auto_restore', 03:56:16 INFO - 'tabpaint']}, 03:56:16 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:56:16 INFO - 'tests': ['tsvgx', 03:56:16 INFO - 'tsvgr_opacity', 03:56:16 INFO - 'tart', 03:56:16 INFO - 'tscrollx', 03:56:16 INFO - 'cart']}, 03:56:16 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:56:16 INFO - 'tsvgr_opacity', 03:56:16 INFO - 'tart', 03:56:16 INFO - 'tscrollx', 03:56:16 INFO - 'cart']}, 03:56:16 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:56:16 INFO - 'talos_options': ['--disable-e10s'], 03:56:16 INFO - 'tests': ['tp5o']}, 03:56:16 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:56:16 INFO - 'tests': ['tp5o']}, 03:56:16 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:56:16 INFO - 'talos_options': ['--disable-e10s', 03:56:16 INFO - '--xperf_path', 03:56:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:56:16 INFO - 'tests': ['tp5n']}, 03:56:16 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:56:16 INFO - 'talos_options': ['--xperf_path', 03:56:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:56:16 INFO - 'tests': ['tp5n']}}, 03:56:16 INFO - 'talos.zip': {'path': '', 03:56:16 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:56:16 INFO - Running post-action listener: _resource_record_post_action 03:56:16 INFO - [mozharness: 2016-08-26 10:56:16.325577Z] Finished populate-webroot step (success) 03:56:16 INFO - [mozharness: 2016-08-26 10:56:16.325666Z] Running create-virtualenv step. 03:56:16 INFO - Running pre-action listener: _resource_record_pre_action 03:56:16 INFO - Running main action method: create_virtualenv 03:56:16 INFO - Creating virtualenv /builds/slave/test/build/venv 03:56:16 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 03:56:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:56:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:56:16 INFO - Using real prefix '/usr' 03:56:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:56:16 INFO - Installing distribute.............................................................................................................................................................................................done. 03:56:18 INFO - Installing pip.................done. 03:56:18 INFO - Return code: 0 03:56:18 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:56: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:56:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:56:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:56:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:56:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:18 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 0x1638030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x179b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18f9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f73b0>, '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 0x18f82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18f8740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1472208514.291038-700744219', '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 03:56:18 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 03:56:18 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 03:56:18 INFO - Using env: {'DISPLAY': ':0', 03:56:18 INFO - 'HOME': '/home/cltbld', 03:56:18 INFO - 'LANG': 'en_US.UTF-8', 03:56:18 INFO - 'LANGUAGE': 'en_US:en', 03:56:18 INFO - 'LOGNAME': 'cltbld', 03:56:18 INFO - 'MAIL': '/var/mail/cltbld', 03:56:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:18 INFO - 'MOZ_NO_REMOTE': '1', 03:56:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:56:18 INFO - 'NO_EM_RESTART': '1', 03:56:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:56:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:56:18 INFO - 'PWD': '/builds/slave/test', 03:56:18 INFO - 'SHELL': '/bin/bash', 03:56:18 INFO - 'SHLVL': '1', 03:56:18 INFO - 'TERM': 'linux', 03:56:18 INFO - 'TMOUT': '86400', 03:56:18 INFO - 'USER': 'cltbld', 03:56:18 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1472208514.291038-700744219', 03:56:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:18 INFO - '_': '/tools/buildbot/bin/python'} 03:56:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:56:19 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 03:56:19 INFO - Cleaning up... 03:56:19 INFO - Return code: 0 03:56:19 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:56:19 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:56:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:56:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:19 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:56:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:56:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:19 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 0x1638030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x179b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18f9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f73b0>, '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 0x18f82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18f8740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1472208514.291038-700744219', '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 03:56:19 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 03:56:19 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 03:56:19 INFO - Using env: {'DISPLAY': ':0', 03:56:19 INFO - 'HOME': '/home/cltbld', 03:56:19 INFO - 'LANG': 'en_US.UTF-8', 03:56:19 INFO - 'LANGUAGE': 'en_US:en', 03:56:19 INFO - 'LOGNAME': 'cltbld', 03:56:19 INFO - 'MAIL': '/var/mail/cltbld', 03:56:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:19 INFO - 'MOZ_NO_REMOTE': '1', 03:56:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:56:19 INFO - 'NO_EM_RESTART': '1', 03:56:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:56:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:56:19 INFO - 'PWD': '/builds/slave/test', 03:56:19 INFO - 'SHELL': '/bin/bash', 03:56:19 INFO - 'SHLVL': '1', 03:56:19 INFO - 'TERM': 'linux', 03:56:19 INFO - 'TMOUT': '86400', 03:56:19 INFO - 'USER': 'cltbld', 03:56:19 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1472208514.291038-700744219', 03:56:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:19 INFO - '_': '/tools/buildbot/bin/python'} 03:56:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:56:19 INFO - Downloading/unpacking psutil>=3.1.1 03:56:19 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 03:56:19 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 03:56:19 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 03:56:19 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 03:56:19 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 03:56:19 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 03:56:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:56:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:56:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:56:21 INFO - Installing collected packages: psutil 03:56:21 INFO - Running setup.py install for psutil 03:56:21 INFO - building 'psutil._psutil_linux' extension 03:56:21 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 03:56:21 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 03:56:21 INFO - building 'psutil._psutil_posix' extension 03:56:21 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 03:56:21 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 03:56:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:56:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:56:21 INFO - Successfully installed psutil 03:56:21 INFO - Cleaning up... 03:56:21 INFO - Return code: 0 03:56:21 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:56:21 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:56:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:56:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:21 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:56:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:56:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:21 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 0x1638030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x179b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18f9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f73b0>, '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 0x18f82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18f8740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1472208514.291038-700744219', '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 03:56:21 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 03:56:21 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 03:56:21 INFO - Using env: {'DISPLAY': ':0', 03:56:21 INFO - 'HOME': '/home/cltbld', 03:56:21 INFO - 'LANG': 'en_US.UTF-8', 03:56:21 INFO - 'LANGUAGE': 'en_US:en', 03:56:21 INFO - 'LOGNAME': 'cltbld', 03:56:21 INFO - 'MAIL': '/var/mail/cltbld', 03:56:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:21 INFO - 'MOZ_NO_REMOTE': '1', 03:56:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:56:21 INFO - 'NO_EM_RESTART': '1', 03:56:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:56:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:56:21 INFO - 'PWD': '/builds/slave/test', 03:56:21 INFO - 'SHELL': '/bin/bash', 03:56:21 INFO - 'SHLVL': '1', 03:56:21 INFO - 'TERM': 'linux', 03:56:21 INFO - 'TMOUT': '86400', 03:56:21 INFO - 'USER': 'cltbld', 03:56:21 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1472208514.291038-700744219', 03:56:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:21 INFO - '_': '/tools/buildbot/bin/python'} 03:56:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:56:22 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:56:22 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 03:56:22 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 03:56:22 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 03:56:22 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 03:56:22 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 03:56:22 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 03:56:23 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:56:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:56:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:56:23 INFO - Installing collected packages: mozsystemmonitor 03:56:23 INFO - Running setup.py install for mozsystemmonitor 03:56:24 INFO - Successfully installed mozsystemmonitor 03:56:24 INFO - Cleaning up... 03:56:24 INFO - Return code: 0 03:56:24 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:56: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:56:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:56:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:56:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:56:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:24 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 0x1638030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x179b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18f9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f73b0>, '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 0x18f82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18f8740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1472208514.291038-700744219', '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 03:56:24 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 03:56:24 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 03:56:24 INFO - Using env: {'DISPLAY': ':0', 03:56:24 INFO - 'HOME': '/home/cltbld', 03:56:24 INFO - 'LANG': 'en_US.UTF-8', 03:56:24 INFO - 'LANGUAGE': 'en_US:en', 03:56:24 INFO - 'LOGNAME': 'cltbld', 03:56:24 INFO - 'MAIL': '/var/mail/cltbld', 03:56:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:24 INFO - 'MOZ_NO_REMOTE': '1', 03:56:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:56:24 INFO - 'NO_EM_RESTART': '1', 03:56:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:56:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:56:24 INFO - 'PWD': '/builds/slave/test', 03:56:24 INFO - 'SHELL': '/bin/bash', 03:56:24 INFO - 'SHLVL': '1', 03:56:24 INFO - 'TERM': 'linux', 03:56:24 INFO - 'TMOUT': '86400', 03:56:24 INFO - 'USER': 'cltbld', 03:56:24 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1472208514.291038-700744219', 03:56:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:24 INFO - '_': '/tools/buildbot/bin/python'} 03:56:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:56:24 INFO - Downloading/unpacking jsonschema==2.5.1 03:56:24 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 03:56:24 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 03:56:24 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 03:56:24 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 03:56:24 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 03:56:24 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 03:56:26 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:56:26 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:56:26 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 03:56:26 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 03:56:26 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 03:56:26 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 03:56:26 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 03:56:26 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 03:56:26 INFO - Downloading functools32-3.2.3-2.tar.gz 03:56:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:56:26 INFO - warning: no files found matching '*.txt' 03:56:26 INFO - no previously-included directories found matching 'build' 03:56:26 INFO - no previously-included directories found matching 'dist' 03:56:26 INFO - no previously-included directories found matching '.git*' 03:56:26 INFO - Installing collected packages: jsonschema, functools32 03:56:26 INFO - Running setup.py install for functools32 03:56:26 INFO - warning: no files found matching '*.txt' 03:56:26 INFO - no previously-included directories found matching 'build' 03:56:26 INFO - no previously-included directories found matching 'dist' 03:56:26 INFO - no previously-included directories found matching '.git*' 03:56:26 INFO - Successfully installed jsonschema functools32 03:56:26 INFO - Cleaning up... 03:56:26 INFO - Return code: 0 03:56:26 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:56:26 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:56:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:56:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:26 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:56:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:56:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:26 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 0x1638030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x179b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18f9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f73b0>, '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 0x18f82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18f8740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1472208514.291038-700744219', '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 03:56:26 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 03:56:26 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 03:56:26 INFO - Using env: {'DISPLAY': ':0', 03:56:26 INFO - 'HOME': '/home/cltbld', 03:56:26 INFO - 'LANG': 'en_US.UTF-8', 03:56:26 INFO - 'LANGUAGE': 'en_US:en', 03:56:26 INFO - 'LOGNAME': 'cltbld', 03:56:26 INFO - 'MAIL': '/var/mail/cltbld', 03:56:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:26 INFO - 'MOZ_NO_REMOTE': '1', 03:56:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:56:26 INFO - 'NO_EM_RESTART': '1', 03:56:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:56:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:56:26 INFO - 'PWD': '/builds/slave/test', 03:56:26 INFO - 'SHELL': '/bin/bash', 03:56:26 INFO - 'SHLVL': '1', 03:56:26 INFO - 'TERM': 'linux', 03:56:26 INFO - 'TMOUT': '86400', 03:56:26 INFO - 'USER': 'cltbld', 03:56:26 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1472208514.291038-700744219', 03:56:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:26 INFO - '_': '/tools/buildbot/bin/python'} 03:56:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:56:26 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:56:26 INFO - Cleaning up... 03:56:26 INFO - Return code: 0 03:56:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:56:26 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:56:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:56:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:26 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:56:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:56:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:26 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 0x1638030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x179b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18f9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f73b0>, '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 0x18f82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18f8740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1472208514.291038-700744219', '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 03:56:26 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 03:56:26 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 03:56:26 INFO - Using env: {'DISPLAY': ':0', 03:56:26 INFO - 'HOME': '/home/cltbld', 03:56:26 INFO - 'LANG': 'en_US.UTF-8', 03:56:26 INFO - 'LANGUAGE': 'en_US:en', 03:56:26 INFO - 'LOGNAME': 'cltbld', 03:56:26 INFO - 'MAIL': '/var/mail/cltbld', 03:56:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:26 INFO - 'MOZ_NO_REMOTE': '1', 03:56:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:56:26 INFO - 'NO_EM_RESTART': '1', 03:56:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:56:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:56:26 INFO - 'PWD': '/builds/slave/test', 03:56:26 INFO - 'SHELL': '/bin/bash', 03:56:26 INFO - 'SHLVL': '1', 03:56:26 INFO - 'TERM': 'linux', 03:56:26 INFO - 'TMOUT': '86400', 03:56:26 INFO - 'USER': 'cltbld', 03:56:26 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1472208514.291038-700744219', 03:56:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:26 INFO - '_': '/tools/buildbot/bin/python'} 03:56:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:56:27 INFO - Downloading/unpacking blobuploader==1.2.4 03:56:27 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 03:56:27 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 03:56:27 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 03:56:27 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 03:56:27 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 03:56:27 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 03:56:28 INFO - Downloading blobuploader-1.2.4.tar.gz 03:56:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:56:29 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:56:29 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 03:56:29 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 03:56:29 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 03:56:29 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 03:56:29 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 03:56:29 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 03:56:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:56:29 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:56:29 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 03:56:29 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 03:56:29 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 03:56:29 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 03:56:29 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 03:56:29 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 03:56:29 INFO - Downloading docopt-0.6.1.tar.gz 03:56:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:56:29 INFO - Installing collected packages: blobuploader, requests, docopt 03:56:29 INFO - Running setup.py install for blobuploader 03:56:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:56:29 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:56:29 INFO - Running setup.py install for requests 03:56:30 INFO - Running setup.py install for docopt 03:56:30 INFO - Successfully installed blobuploader requests docopt 03:56:30 INFO - Cleaning up... 03:56:30 INFO - Return code: 0 03:56:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:56:30 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:56:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:56:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:30 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:56:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:56:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:30 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 0x1638030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x179b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18f9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f73b0>, '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 0x18f82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18f8740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1472208514.291038-700744219', '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 03:56:30 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 03:56:30 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 03:56:30 INFO - Using env: {'DISPLAY': ':0', 03:56:30 INFO - 'HOME': '/home/cltbld', 03:56:30 INFO - 'LANG': 'en_US.UTF-8', 03:56:30 INFO - 'LANGUAGE': 'en_US:en', 03:56:30 INFO - 'LOGNAME': 'cltbld', 03:56:30 INFO - 'MAIL': '/var/mail/cltbld', 03:56:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:30 INFO - 'MOZ_NO_REMOTE': '1', 03:56:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:56:30 INFO - 'NO_EM_RESTART': '1', 03:56:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:56:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:56:30 INFO - 'PWD': '/builds/slave/test', 03:56:30 INFO - 'SHELL': '/bin/bash', 03:56:30 INFO - 'SHLVL': '1', 03:56:30 INFO - 'TERM': 'linux', 03:56:30 INFO - 'TMOUT': '86400', 03:56:30 INFO - 'USER': 'cltbld', 03:56:30 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1472208514.291038-700744219', 03:56:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:30 INFO - '_': '/tools/buildbot/bin/python'} 03:56:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:56:30 INFO - Running setup.py (path:/tmp/pip-8rR4vl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:56:30 INFO - Running setup.py (path:/tmp/pip-PQ03y5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:56:30 INFO - Running setup.py (path:/tmp/pip-J1u6wT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:56:30 INFO - Running setup.py (path:/tmp/pip-XZynH4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:56:30 INFO - Running setup.py (path:/tmp/pip-UhnaCe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:56:30 INFO - Running setup.py (path:/tmp/pip-hlNTxF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:56:30 INFO - Running setup.py (path:/tmp/pip-3UzoWl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:56:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:56:31 INFO - Running setup.py (path:/tmp/pip-kQCQhF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:56:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:56:31 INFO - Running setup.py (path:/tmp/pip-61J9Zv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:56:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:56:31 INFO - Running setup.py (path:/tmp/pip-iyfT5g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:56:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:56:31 INFO - Running setup.py (path:/tmp/pip-_84y_h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:56:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:56:31 INFO - Running setup.py (path:/tmp/pip-kGhKdg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:56:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:56:31 INFO - Running setup.py (path:/tmp/pip-FM4sMS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:56:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:56:31 INFO - Running setup.py (path:/tmp/pip-6zJToK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:56:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:56:31 INFO - Running setup.py (path:/tmp/pip-r_tmVP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:56:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:56:31 INFO - Running setup.py (path:/tmp/pip-ahqTgA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:56:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:56:31 INFO - Running setup.py (path:/tmp/pip-gZDG8Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:56:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:56:31 INFO - Running setup.py install for manifestparser 03:56:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:56:31 INFO - Running setup.py install for mozcrash 03:56:31 INFO - Running setup.py install for mozdebug 03:56:32 INFO - Running setup.py install for mozdevice 03:56:32 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:56:32 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:56:32 INFO - Running setup.py install for mozfile 03:56:32 INFO - Running setup.py install for mozhttpd 03:56:32 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:56:32 INFO - Running setup.py install for mozinfo 03:56:32 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:56:32 INFO - Running setup.py install for mozInstall 03:56:32 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:56:32 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:56:32 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:56:32 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:56:32 INFO - Running setup.py install for mozleak 03:56:32 INFO - Running setup.py install for mozlog 03:56:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:56:32 INFO - Running setup.py install for moznetwork 03:56:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:56:32 INFO - Running setup.py install for mozprocess 03:56:33 INFO - Running setup.py install for mozprofile 03:56:33 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:56:33 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:56:33 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:56:33 INFO - Running setup.py install for mozrunner 03:56:33 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:56:33 INFO - Running setup.py install for mozscreenshot 03:56:33 INFO - Running setup.py install for moztest 03:56:33 INFO - Running setup.py install for mozversion 03:56:33 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:56:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:56:33 INFO - Cleaning up... 03:56:33 INFO - Return code: 0 03:56:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:56:33 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:56:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:56:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:33 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:56:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:56:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:33 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 0x1638030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x179b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18f9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f73b0>, '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 0x18f82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18f8740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1472208514.291038-700744219', '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 03:56:33 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 03:56:33 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 03:56:33 INFO - Using env: {'DISPLAY': ':0', 03:56:33 INFO - 'HOME': '/home/cltbld', 03:56:33 INFO - 'LANG': 'en_US.UTF-8', 03:56:33 INFO - 'LANGUAGE': 'en_US:en', 03:56:33 INFO - 'LOGNAME': 'cltbld', 03:56:33 INFO - 'MAIL': '/var/mail/cltbld', 03:56:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:33 INFO - 'MOZ_NO_REMOTE': '1', 03:56:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:56:33 INFO - 'NO_EM_RESTART': '1', 03:56:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:56:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:56:33 INFO - 'PWD': '/builds/slave/test', 03:56:33 INFO - 'SHELL': '/bin/bash', 03:56:33 INFO - 'SHLVL': '1', 03:56:33 INFO - 'TERM': 'linux', 03:56:33 INFO - 'TMOUT': '86400', 03:56:33 INFO - 'USER': 'cltbld', 03:56:33 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1472208514.291038-700744219', 03:56:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:33 INFO - '_': '/tools/buildbot/bin/python'} 03:56:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:56:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:56:33 INFO - Running setup.py (path:/tmp/pip-irJSEY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:56:33 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)) 03:56:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:56:33 INFO - Running setup.py (path:/tmp/pip-qFLl1y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:56:33 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)) 03:56:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:56:33 INFO - Running setup.py (path:/tmp/pip-WKedUd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:56:34 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)) 03:56:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:56:34 INFO - Running setup.py (path:/tmp/pip-uAZv9O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:56:34 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)) 03:56:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:56:34 INFO - Running setup.py (path:/tmp/pip-uEE2tI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:56:34 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)) 03:56:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:56:34 INFO - Running setup.py (path:/tmp/pip-e6DO9p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:56:34 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)) 03:56:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:56:34 INFO - Running setup.py (path:/tmp/pip-Wy_8le-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:56:34 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)) 03:56:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:56:34 INFO - Running setup.py (path:/tmp/pip-uO9v9C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:56:34 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)) 03:56:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:56:34 INFO - Running setup.py (path:/tmp/pip-3P5zqI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:56:34 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)) 03:56:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:56:34 INFO - Running setup.py (path:/tmp/pip-aInbmu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:56:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 03:56:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:56:34 INFO - Running setup.py (path:/tmp/pip-uh2Pdx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:56:34 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)) 03:56:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:56:34 INFO - Running setup.py (path:/tmp/pip-kFhT5N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:56:34 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)) 03:56:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:56:34 INFO - Running setup.py (path:/tmp/pip-oDe9Jb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:56:34 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)) 03:56:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:56:34 INFO - Running setup.py (path:/tmp/pip-tz53fq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:56:34 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)) 03:56:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:56:34 INFO - Running setup.py (path:/tmp/pip-Er7qI5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:56:34 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)) 03:56:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:56:34 INFO - Running setup.py (path:/tmp/pip-PoCy3_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:56:34 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)) 03:56:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:56:34 INFO - Running setup.py (path:/tmp/pip-3aAgTG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:56:35 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)) 03:56:35 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)) 03:56:35 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)) 03:56:35 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)) 03:56:35 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)) 03:56:35 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)) 03:56:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:56:35 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 03:56:35 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 03:56:35 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 03:56:35 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 03:56:35 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 03:56:35 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 03:56:36 INFO - Downloading blessings-1.6.tar.gz 03:56:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:56:37 INFO - Installing collected packages: blessings 03:56:37 INFO - Running setup.py install for blessings 03:56:37 INFO - Successfully installed blessings 03:56:37 INFO - Cleaning up... 03:56:37 INFO - Return code: 0 03:56:37 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:56:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:56:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:56:37 INFO - Reading from file tmpfile_stdout 03:56:37 INFO - Current package versions: 03:56:37 INFO - argparse == 1.2.1 03:56:37 INFO - blessings == 1.6 03:56:37 INFO - blobuploader == 1.2.4 03:56:37 INFO - docopt == 0.6.1 03:56:37 INFO - functools32 == 3.2.3-2 03:56:37 INFO - jsonschema == 2.5.1 03:56:37 INFO - manifestparser == 1.1 03:56:37 INFO - mozInstall == 1.12 03:56:37 INFO - mozcrash == 0.17 03:56:37 INFO - mozdebug == 0.1 03:56:37 INFO - mozdevice == 0.48 03:56:37 INFO - mozfile == 1.2 03:56:37 INFO - mozhttpd == 0.7 03:56:37 INFO - mozinfo == 0.9 03:56:37 INFO - mozleak == 0.1 03:56:37 INFO - mozlog == 3.3 03:56:37 INFO - moznetwork == 0.27 03:56:37 INFO - mozprocess == 0.23 03:56:37 INFO - mozprofile == 0.28 03:56:37 INFO - mozrunner == 6.12 03:56:37 INFO - mozscreenshot == 0.1 03:56:37 INFO - mozsystemmonitor == 0.3 03:56:37 INFO - moztest == 0.7 03:56:37 INFO - mozversion == 1.4 03:56:37 INFO - psutil == 3.1.1 03:56:37 INFO - requests == 1.2.3 03:56:37 INFO - wsgiref == 0.1.2 03:56:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:56: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:56:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:56:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:56:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:56:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:37 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 0x1638030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x179b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18f9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f73b0>, '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 0x18f82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18f8740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1472208514.291038-700744219', '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 03:56:37 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 03:56:37 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 03:56:37 INFO - Using env: {'DISPLAY': ':0', 03:56:37 INFO - 'HOME': '/home/cltbld', 03:56:37 INFO - 'LANG': 'en_US.UTF-8', 03:56:37 INFO - 'LANGUAGE': 'en_US:en', 03:56:37 INFO - 'LOGNAME': 'cltbld', 03:56:37 INFO - 'MAIL': '/var/mail/cltbld', 03:56:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:37 INFO - 'MOZ_NO_REMOTE': '1', 03:56:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:56:37 INFO - 'NO_EM_RESTART': '1', 03:56:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:56:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:56:37 INFO - 'PWD': '/builds/slave/test', 03:56:37 INFO - 'SHELL': '/bin/bash', 03:56:37 INFO - 'SHLVL': '1', 03:56:37 INFO - 'TERM': 'linux', 03:56:37 INFO - 'TMOUT': '86400', 03:56:37 INFO - 'USER': 'cltbld', 03:56:37 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1472208514.291038-700744219', 03:56:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:37 INFO - '_': '/tools/buildbot/bin/python'} 03:56:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:56:37 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)) 03:56:37 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)) 03:56:37 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)) 03:56:37 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)) 03:56:37 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)) 03:56:37 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)) 03:56:37 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)) 03:56:37 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)) 03:56:37 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)) 03:56:37 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)) 03:56:37 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)) 03:56:37 INFO - Cleaning up... 03:56:37 INFO - Return code: 0 03:56:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:56: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:56:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:56:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:56:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:56:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:37 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 0x1638030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x179b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18f9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f73b0>, '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 0x18f82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18f8740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1472208514.291038-700744219', '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 03:56:37 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 03:56:37 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 03:56:37 INFO - Using env: {'DISPLAY': ':0', 03:56:37 INFO - 'HOME': '/home/cltbld', 03:56:37 INFO - 'LANG': 'en_US.UTF-8', 03:56:37 INFO - 'LANGUAGE': 'en_US:en', 03:56:37 INFO - 'LOGNAME': 'cltbld', 03:56:37 INFO - 'MAIL': '/var/mail/cltbld', 03:56:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:37 INFO - 'MOZ_NO_REMOTE': '1', 03:56:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:56:37 INFO - 'NO_EM_RESTART': '1', 03:56:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:56:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:56:37 INFO - 'PWD': '/builds/slave/test', 03:56:37 INFO - 'SHELL': '/bin/bash', 03:56:37 INFO - 'SHLVL': '1', 03:56:37 INFO - 'TERM': 'linux', 03:56:37 INFO - 'TMOUT': '86400', 03:56:37 INFO - 'USER': 'cltbld', 03:56:37 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1472208514.291038-700744219', 03:56:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:37 INFO - '_': '/tools/buildbot/bin/python'} 03:56:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:56:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:56:37 INFO - Cleaning up... 03:56:37 INFO - Return code: 0 03:56:37 INFO - Running post-action listener: _resource_record_post_action 03:56:37 INFO - Running post-action listener: _start_resource_monitoring 03:56:37 INFO - Starting resource monitoring. 03:56:37 INFO - [mozharness: 2016-08-26 10:56:37.872674Z] Finished create-virtualenv step (success) 03:56:37 INFO - [mozharness: 2016-08-26 10:56:37.873154Z] Running install step. 03:56:37 INFO - Running pre-action listener: _resource_record_pre_action 03:56:37 INFO - Running main action method: install 03:56:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:56:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:56:38 INFO - Reading from file tmpfile_stdout 03:56:38 INFO - Detecting whether we're running mozinstall >=1.0... 03:56:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:56:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:56:38 INFO - Reading from file tmpfile_stdout 03:56:38 INFO - Output received: 03:56:38 INFO - Usage: mozinstall [options] installer 03:56:38 INFO - Options: 03:56:38 INFO - -h, --help show this help message and exit 03:56:38 INFO - -d DEST, --destination=DEST 03:56:38 INFO - Directory to install application into. [default: 03:56:38 INFO - "/builds/slave/test"] 03:56:38 INFO - --app=APP Application being installed. [default: firefox] 03:56:38 INFO - mkdir: /builds/slave/test/build/application 03:56:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 03:56:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 03:56:54 INFO - Reading from file tmpfile_stdout 03:56:54 INFO - Output received: 03:56:54 INFO - /builds/slave/test/build/application/firefox/firefox 03:56:54 INFO - Running post-action listener: _resource_record_post_action 03:56:54 INFO - [mozharness: 2016-08-26 10:56:54.139494Z] Finished install step (success) 03:56:54 INFO - [mozharness: 2016-08-26 10:56:54.139831Z] Running run-tests step. 03:56:54 INFO - Running pre-action listener: _resource_record_pre_action 03:56:54 INFO - Running main action method: run_tests 03:56:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:56:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:56:54 INFO - Python 2.7.3 03:56:54 INFO - Return code: 0 03:56:54 INFO - grabbing minidump binary from tooltool 03:56: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:56:54 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 0x18f73b0>, '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 0x18f82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18f8740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:56:54 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 03:56:54 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 03:56:54 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 03:56:54 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:56:54 INFO - Return code: 0 03:56:54 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:56:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:56:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:56:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:56:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:56:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-005', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 03:56:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-005 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 03:56:54 INFO - Using env: {'DISPLAY': ':0', 03:56:54 INFO - 'HOME': '/home/cltbld', 03:56:54 INFO - 'LANG': 'en_US.UTF-8', 03:56:54 INFO - 'LANGUAGE': 'en_US:en', 03:56:54 INFO - 'LOGNAME': 'cltbld', 03:56:54 INFO - 'MAIL': '/var/mail/cltbld', 03:56:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:56:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:56:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:54 INFO - 'MOZ_NO_REMOTE': '1', 03:56:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:56:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:56:54 INFO - 'NO_EM_RESTART': '1', 03:56:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:56:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:56:54 INFO - 'PWD': '/builds/slave/test', 03:56:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:56:54 INFO - 'SHELL': '/bin/bash', 03:56:54 INFO - 'SHLVL': '1', 03:56:54 INFO - 'TERM': 'linux', 03:56:54 INFO - 'TMOUT': '86400', 03:56:54 INFO - 'USER': 'cltbld', 03:56:54 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1472208514.291038-700744219', 03:56:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:54 INFO - '_': '/tools/buildbot/bin/python'} 03:56:54 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-005', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 03:56:54 INFO - mozversion application_buildid: 20160826004001 03:56:54 INFO - mozversion application_changeset: 21fd3ea62d8ccb941ac7e14d85034c5799073635 03:56:54 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:56:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:56:54 INFO - mozversion application_name: Firefox 03:56:54 INFO - mozversion application_remotingname: firefox-dev 03:56:54 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:56:54 INFO - mozversion application_vendor: Mozilla 03:56:54 INFO - mozversion application_version: 50.0a2 03:56:54 INFO - mozversion platform_buildid: 20160826004001 03:56:54 INFO - mozversion platform_changeset: 21fd3ea62d8ccb941ac7e14d85034c5799073635 03:56:54 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:56:54 INFO - mozversion platform_version: 50.0a2 03:56:54 INFO - using testdate: 1472209014 03:56:54 INFO - actual date: 1472209014 03:56:54 INFO - starting webserver on 'localhost:50945' 03:56:54 INFO - SUITE-START | Running 2 tests 03:56:54 INFO - TEST-START | dromaeo_css 03:56:54 INFO - operating with platform_type : linux_ 03:56:54 INFO - Initialising browser for dromaeo_css test... 03:56:54 INFO - TEST-INFO | started process 11733 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpufaMYD/profile http://localhost:50945/getInfo.html) 03:56:58 INFO - PROCESS | 11733 | __metrics Screen width/height:1600/1200 03:56:58 INFO - PROCESS | 11733 | colorDepth:24 03:56:58 INFO - PROCESS | 11733 | Browser inner width/height: 1024/697 03:56:58 INFO - PROCESS | 11733 | __metrics 03:56:59 INFO - PROCESS | 11733 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:56:59 INFO - PROCESS | 11733 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:57:00 INFO - TEST-INFO | 11733: exit 0 03:57:00 INFO - Browser initialized. 03:57:00 INFO - Running cycle 1/1 for dromaeo_css test... 03:57:00 INFO - TEST-INFO | started process 11825 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpufaMYD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:57:11 INFO - PROCESS | 11825 | RSS: Main: 145387520 03:57:11 INFO - PROCESS | 11825 | 03:58:02 INFO - PROCESS | 11825 | 1472209082815 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] 04:00:11 INFO - PROCESS | 11825 | Cycle 1(1): loaded http://localhost:50945/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50945/tests/dromaeo/cssquery-ext.html) 04:00:13 INFO - PROCESS | 11825 | RSS: Main: 281915392 04:00:13 INFO - PROCESS | 11825 | 04:03:15 INFO - PROCESS | 11825 | Cycle 1(1): loaded http://localhost:50945/tests/dromaeo/cssquery-ext.html (next: http://localhost:50945/tests/dromaeo/cssquery-jquery.html) 04:03:16 INFO - PROCESS | 11825 | RSS: Main: 232677376 04:03:16 INFO - PROCESS | 11825 | 04:06:17 INFO - PROCESS | 11825 | Cycle 1(1): loaded http://localhost:50945/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50945/tests/dromaeo/cssquery-mootools.html) 04:06:18 INFO - PROCESS | 11825 | RSS: Main: 174809088 04:06:18 INFO - PROCESS | 11825 | 04:09:20 INFO - PROCESS | 11825 | Cycle 1(1): loaded http://localhost:50945/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50945/tests/dromaeo/cssquery-prototype.html) 04:09:20 INFO - PROCESS | 11825 | RSS: Main: 209117184 04:09:20 INFO - PROCESS | 11825 | 04:12:23 INFO - PROCESS | 11825 | Cycle 1(1): loaded http://localhost:50945/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50945/tests/dromaeo/cssquery-yui.html) 04:12:24 INFO - PROCESS | 11825 | RSS: Main: 177655808 04:12:24 INFO - PROCESS | 11825 | 04:15:26 INFO - PROCESS | 11825 | Cycle 1(1): loaded http://localhost:50945/tests/dromaeo/cssquery-yui.html (next: http://localhost:50945/tests/dromaeo/cssquery-dojo.html) 04:15:26 INFO - PROCESS | 11825 | RSS: Main: 265121792 04:15:26 INFO - PROCESS | 11825 | 04:15:26 INFO - PROCESS | 11825 | __start_tp_report 04:15:26 INFO - PROCESS | 11825 | _x_x_mozilla_page_load 04:15:26 INFO - PROCESS | 11825 | _x_x_mozilla_page_load_details 04:15:26 INFO - PROCESS | 11825 | |i|pagename|runs| 04:15:26 INFO - PROCESS | 11825 | |0;dojo.html;3320.2529430878053;3326.434506133957;3329.9500507492394;3362.2266878617934;3380.5267262583243;5731.369549349573;5818.3308919474275;5839.708014599274;5851.4148585141465;5860.882782344345;5767.913481297774;5846.4445877641665;5852.912206316898;5853.385394533577;5854.268216472941;5094.363204599425;5250.7112108834;5273.34083239595;5359.08895487768;5368.892622147555;5166.9224961625605;5174.922376164361;5236.895262094756;5268.841934741964;5305.204219367107;5241.973790131044;5280.683159010472;5302.6023048271345;5320.308359913206;5322.547583455392;5373.596980226499;5375.193720941851;5377.731113444352;5379.542738867221;5417.052015897202;5017.673851199696;5162.328897243372;5207.531322181022;5260.736963151827;5272.051030814452;5655.434456554352;5707.800226992077;5719.170720245541;5735.82792516221;5770.394108618619;6213.378662133796;6233;6233.87532249357;6243.968780156116;6250.281217659982;5927.585069045169;6117.311613022215;6142.959235178615;6163.568550201488;6241.594296370767;5778.88442231153;5790.652560846363;5798.333191682968;5804.88390232193;5815.127730840323;6324.08300796382;6324.462420694293;6329.208848893888;6334.081558174039;6344.302126766052;5832.12518122285;5850.005499065169;5869.911951320733;5894.410558944157;5968.970155149196;6042.879142417127;6046.395360463918;6064.848378790477;6075.027995520783;6081.543884208702;5952.7321270542925;5994.9700251499335;6008.188894499274;6015.909761353583;6018.247719035121;5955.67243801595;6004.579679422399;6023.397660234029;6062.575619706578;6071.848203794852;4637.281221410619;4678.134545109166;4687.296905464208;4711.740854253049;4718.551737584924;4167.8958026049595;4170.35359519275;4176.561461046607;4178.206140833293;4200.600942910419;5660.8867822643315;5812.622179558315;5849.1226316052935;5849.327327357323;5850.736716847752;6202.658853763086;6217.409346112112;6220.129181914535;6222.222222222223;6227.470664993526;5881.206037184926;5887.646741195542;5905.557083218776;5912.822615321547;5939;5178.223266510054;5218.112920803473;5222.55608273301;5271.630985830955;5328.573714102862;6366.85396628602;6417.839554011093;6418.326075761977;6423.13287706163;6430;6109.511239100772;6221.9377806223165;6231.906521402273;6233.283172435228;6242.1261023455845;6115.544587952112;6253.030780229072;6296.118543404019;6326.905096423466;6410.679466026773;6062.090686397076;6089.634621922699;6090.725917333642;6096.817095487142;6120.418560236771;6179.721912514037;6188.628682279078;6191.25704915413;6192.071189321638;6197.194364732556;5830.53355731532;6011.639301641915;6032.6983650818165;6039.818805435844;6077.4226448487325;6001.90997135052;6043.8186854394435;6049.546284028804;6055.424734650202;6081;5969.522438204846;6020.066889632114;6028.306744724237;6031;6038.124471951454;5854.882902342015;6015.368386319374;6029.125776762433;6081;6088.665123418255;5925.4370834770625;5957.76168953237;5958.582899197015;5961.195238642727;5997.520198384032 04:15:26 INFO - PROCESS | 11825 | |1;ext.html;586.2788769813129;626.5019309648828;631.6967855429393;644.9000404937235;654.2704884054281;33398;37067;45099.32351014738;45841.54158458414;45963.31055034177;48108.51891481093;48263;48452.2732159017;48460.51539484604;48474.51525484744;9303.25573954082;9457.621695132186;9461.053894610553;9462.101100395486;9467.763305917339;8497.915020849805;11035.337879727202;11259.887401126007;11282.266652667551;11645;11433.942830285878;11444.14168937329;11444.542218311257;11462.025727813147;11504.942475287611;11162.609308674198;11213.327200368005;11224.66326010221;11238.213325067249;11250;11215.831762523569;11270.267432616854;11283.37941413222;11287.209895307331;11289;45177.77411112956;45390.319145212845;45440.77279613614;45537.316940245924;45543.544564554264;3858.9421158682644;3942.5466071401584;4086.4891888513935;4120.587941205886;4224.598663126998;34515.82742086299;34710;34778.13054673628;34862.651373486195;35109.47335789965;4670.182718024366;4731.810727570893;4754.096721622881;4786.832460863853;4835.661503694742;390784;393733;394416;394494;395486;66941.6652916742;67037;67109.32890671128;67353.66323168353;67560;39391.40912886309;40115;40339;70438.64780676064;70522.64738676274;18373;18525.351612693492;18544.536386590175;18556.536086597942;18621;12704.174228675107;13562.660933476742;13827.585172444842;13935.58193254204;13938.37277322523;20087.39737807868;21065.894670526857;21236.893815531133;21255;21367;7588.734394296173;7706.344960678405;7717.189695082048;7843.72546960865;7876.369890408753;1063.31416236527;1129.6046383765615;1142.708609304622;1161.0131388320028;1161.5005547614437;34875.30249394998;35052.82473587616;35406.82296588552;35796.46305305423;35873.82063089668;1162.686074759827;1187.5131196209513;1195.5755706724162;1196.3778835005749;1282.0576875996321;16215.918920405322;18527.53681157955;18757.249710011718;19246.577736936593;20078.598428031648;689045;695845;698260;699516;699709;65462.345376546575;65826.34173658202;66103;66252.33747662559;66338;1326.7943468762164;1378.8690074442911;1471.5438214153646;1489.53079779869;1500.7748837674217;31725.20686982844;35556.28887422289;35910.102247444025;36023.459648105825;36313.818430907675;55543;56091.439085608625;56104;56249.43750562442;56285;458139;459663;459970;460078;460938;30899;31689.36621267608;31970.84014579912;33471.66528334783;33481.83259083689;53483.46516534941;53526.73236633792;53640.463595363544;53719.731401342746;53899;44227;44968;45161;45824.312635311166;46764.53235467602;44011.77994110009;44576.777116114215;45821.54178458173;46371.304430432894;46536;44699.55300447084;45206.32190517211;45840.31239531343;46359.768201158775;46464.303035455174;38017.429738553394;40439.3934090983;40565;41312.793436032625;41462.58537414587;29442.263943401587;29573.704262957956;29801.25496862596;29901;30335.848320758254 04:15:26 INFO - PROCESS | 11825 | |2;jquery.html;391.07510737106713;399.4328054163086;411.30694779296886;414.6558356564052;425.23670012327887;16479.176041197938;20382.898085509587;20477.385678429633;20752.27367042154;20960;20304.593908121828;20573;20681.896590517026;20913.16347346108;21068.789312106874;5682.090865461527;5758.251427314443;5959.642421454727;5993.820185394445;5994.8801023979495;4411.448568928884;5888.764449422039;5976.850578735544;6389.488840892741;6569.737210511574;6685.4985876059245;6688.09710689058;6691.464682825386;6693.297203793604;6696.096027036337;6445.935540644604;6548;6613.2064152301555;6624.039514270428;6624.238212605515;5731.111677689964;5806.477417032445;5856.7071646417935;5861.970690146564;5871.588988770788;25244.368890777692;25350;25437.236882893543;25455.872720636464;25512.872435637888;2423.5152969406013;2468.7654672806057;2573.794096472278;2700.8784604692837;2887.971120288791;10693.411862347568;10915.890841091566;11153.717322507853;11166.218364714474;11813.1140164488;2050.4771283322807;2061.597988392264;2083.468715477559;2103.2533450625115;2132.818710409617;84367;84703.15296847076;86112.56943715242;86127;87845;11375.146863985234;11403.372814495282;11439.714007149887;11590;11721.355325457182;15894.68210635804;16050.277737501841;16054.357825686842;16055.598610034842;16074.75887861683;8345.374096942754;8547.74356769298;8558.786030349167;8581;8745.47527148373;8293.875591866126;8309.252167304972;8499.702510412106;8515.701950431829;8566.357523185787;4415.823367065345;4507.684462087623;4827.662063655581;4922.384701912261;5021.372328458942;1963.9312624058089;2001.739773829422;2006.6822919264066;2063.13348393675;2084.687296905446;937.6999360204805;979.2459805949521;985.5417230987625;1021.2085633633993;1043.5617040842865;20144;20265.189392424432;20903.68644470334;21941;22042.33872983813;1036.79782442423;1056.799208150449;1068.647346375694;1077.380506208933;1103.442761405485;10829.458527073615;11662.76674466506;11678.94160529209;11702.356370399539;11751.529938802523;476550;480997;481922;483556;489636;101535;102918;103557;103845.48077259565;104800;1074.957001719938;1089.580511503077;1104.3925840787535;1108.5953626926114;1112.038087054692;17003;18201;18431.631367372847;18589.256429742658;18940.43178704641;46669.76665116653;46822.76588617035;47223.52776472191;48049;48760.2685959718;66892;67033;67049.32950670431;67113.32886671071;67514.66242668756;8185.05871824724;8409.705660301861;8470.449420787629;8646.82706345857;9027.052159523419;12379.442925068573;13494;13532.729345413234;13832.654183645489;13998.510052148125;11142.888571114185;11526.827097593376;11631;11973.221740586834;12088.939555302168;11751.412429378326;12092.334921579397;12112.333821639895;12159.696007599881;12314.199577027823;11487.36819474937;11985.101117416054;12242.510299587917;12383.442745076312;12396.938015309866;7703.38372930153;8736.082711315474;8904.643814247358;8996.370254082152;9047.68333108338;10018.298719089886;11211.103111751203;11449.370284634091;11506.712332191428;11765.588204413147 04:15:26 INFO - PROCESS | 11825 | |3;mootools.html;1366.637840972142;1561.1959840682048;1598.896071755336;1789.3468883857397;1917.261854186138;8618.310535157188;8959.73120806376;8967.237784788285;8974.551272436385;9195.954020229907;8894.955525222365;9080.137386948245;9517.095875891779;9521.285903557244;9527.380720253179;2234.8323875709266;2269.024319542596;2272.2842304673973;2274.692916456274;2275.6472746724285;2435.914742983996;2436.8903399347073;2524.1670248817936;2524.7601477859575;2526.178991827654;2456.447299357639;2469.6665950096685;2482.2181012980855;2525.8484490930605;2534.1637259704253;2358.3986083548757;2368.2539999899977;2374.9406264843346;2376.3821406434363;2400.6759087523137;2341.2273949596593;2349.8237632177484;2353.2822489140804;2355.599548076831;2357.54027964547;9295.07049295072;9405.43567385959;9418.24654027683;9468.28987825909;9471.763205919839;5658.264425624642;5879.588428809985;5893.204417403638;5896.852578685524;5901.911471327933;3530.9646903530893;3561.3411518869093;3598.1724203433096;3612.963870361289;3644.3075815595216;1133.1727838677812;1164.097824186254;1170.8126699728002;1183.786918354696;2332.720073591163;13299.601011969657;13886.305684715724;13915;13928.930355348159;13935.09421887574;4988.925166122511;5049.974750126225;5130.538251557377;5145.614078944094;5183.922241166386;11701.473433695504;11703.063754899587;11711.531538738525;11732.530698772125;11741.589044383405;5850.882982340415;5885.323187833368;5891.587588868738;5905.616134951214;5907.881842363215;4585.19759042173;4729.597984171384;5059.544640982329;5128.871778205575;5379.004884096455;3819.8281077351585;3947.2105578883766;3974.18529201513;3992.5208974923194;4070.5522392536795;1367.3983447283363;1375.628580283318;1381.0815807487909;1398.9930050349888;1405.8102156208986;893.7854914820397;988.3427520698799;1014.3558840136419;1033.5504055735628;1048.3447845096814;5977.1333156692035;6186.505079593621;6249.312575616678;6397.232332120082;6414.326495717975;475.07123573413895;488.0434348676563;1086.2613422872364;1110.9500072496758;1146.1804809561038;4139.172165566899;4342.6742994275555;4364.1053584015835;4419.1382680377665;4551;11826.645200643994;12318.630441086782;12343.259404435763;12353.3823308836;12360.690982725504;12102.81845772314;12105.636830895088;12206.450709718085;12240.510379584717;12279.140460167702;8551.657933682583;8651.264642505332;8787.121287871161;8795.164459376349;8796.736097917073;6152.907706384318;6425.1968503937005;6463.450606698295;6466.515011374072;6572.112764776694;10729.731756706145;10805.567777289134;10876;10879.673609791718;11026.558937642405;10663.520141593492;10843.078338341329;10974.835377469184;11054.392008439612;11106.777864442827;8338.124496927736;8425.789355266168;8485.618147183268;8645.308375329832;8742.650293988168;9190.862137068083;9251.861222081809;9254;9257.35198536096;9257.67598134062;8099.959500202462;8210.58947052657;8296.543690097102;8500.914990850013;8638.049814520273;8025.5184688916515;8054;8112.18878112184;8161.102278749214;8323.375746819134;7252.38354739853;7340.11918569775;7343.522671026366;7351.926480735124;7448.553086814808;5299.39057008449;5448.863778405572;5492.395836458067;5500.9174862377895;5576.0799468088935;5605.803796867253;5668.178114173505;5669.036263835241;5792.768289268382;5796.565257605612 04:15:26 INFO - PROCESS | 11825 | |4;prototype.html;331.8854995026715;341.7300332737138;350.6773768133317;351.74674234551117;352.1355073295061;5564.86087847804;5570.832875013746;5651.124075768253;5653.180288858113;5675.602707810455;5314.123169677008;5382.273393091931;5400.78396864126;5443.8094666686675;5596.188552659872;4182.268103081964;4269.8292068317305;4272.273713468702;4303.634191093761;4343.348497725335;4296.849610263642;4305.266155990937;4313.2020576193345;4313.2451820931365;4350.695451318409;4091.754494730311;4178.707490475668;4193.203291374644;4262.701610887239;4292.892677683051;4235.512916014667;4241.172971270604;4245.469316335458;4265.466816647903;4270.807813648394;3731.2164445466215;3733.757305775116;3754.8310326035394;3772.792496412696;3778.9433158502643;5271.341082364705;5351.117065684158;5363.436839131913;5391.838244852622;5413.810516631938;4827.8551643450755;4841.903161936777;4873.074115917964;4881.365422495089;4890.706557606555;5678.914816277759;5691.9146212806845;5705.5435565154685;5745.166950792153;5781.4218578141845;3961;4010.631308606604;4031.113155105872;4053.5541090480024;4058.6753059755147;5899.410058994109;5950;5955.493783028448;5961.5230781537375;5995.250593675834;4240.088380998039;4243.42713733642;4257.212415703105;4282.614564689131;4287;4165.229432554987;4223.894402639959;4268.722533035343;4339.7396156230725;4339.7830108494445;4849.248366503198;4852.708837469763;4889.535494128052;4891.951080489221;4918.606511479073;5763.94236057634;5777.133429985452;5778.219940308089;5799.347186051955;5802.4777770000055;1381.240317825192;1438.8057612222217;1449.3767679897535;1452.6077958951023;1471.249662672045;924.4314746430897;926.3654396738256;943.2077055273603;944.1172503709054;951.7906060666643;4990;5012.749362531858;5013.824516141991;5019.598432125421;5032.446430892599;5209.765560549784;5209.7916083356995;5211.765470553758;5217.243499692523;5251.632385732962;4704.612139418811;4897.593534192058;4988.551030407209;4991.850244492671;4999.00059964026;2742.903998360048;2768.7646550043473;2789.9537673372483;2798.572727908781;2807.5648274517484;5368.4631536846;5391.7304134793885;5398.78404863801;5406.432324605938;5407.648502847303;4930.556249937523;5266.7629956652045;5272.815451459181;5289.391719952255;5313.963777063435;5267.973660131752;5271.367435907716;5291.285676433709;5304.92042619361;5315.73421328932;5201.323827902348;5205.895882082262;5208.880090780349;5220.321358223407;5225.294585230945;4821.457133717317;5070.873228169326;5100.311457953128;5137.486251374833;5155.123628983157;5356.732163391893;5383.757730902197;5385.46145385474;5402.891942161214;5419.458054194707;3842.5965273645866;3864.71014673895;3876.3991581304945;3905.785181814914;4044.4944381952255;5843.474087331991;5911.940880591311;5919.733611987384;5920.8815823684145;5922.7927022554;5797;5804.564657650778;5807.83843231347;5810.767569297181;5847.736851841592;5544.1960915667805;5711.000574899293;5758.9189783465645;5771.59598828078;5837.532997360286;5674.716264186692;5710.714464276853;5728.713564321851;5738.7948530807325;5752.511036561939;5555.833325000256;5797.391273916178;5847.473727364558;5857.209276747584;5861.882762344644;5608.327000759935;5630.971845140748;5650.717464126695;5651.519620832275;5698.772049117989 04:15:26 INFO - PROCESS | 11825 | |5;yui.html;825.3933358981147;838.8448137094634;842.0779246724834;854.6965827131369;912.4570880326207;16731.163441827906;17790.555236119104;17991.19039643216;18060.729089063676;18066.458006259818;16316.448615710544;16337.18314084297;16478.7528187077;16489.752653710202;16629.168541572904;898.4025622960723;920.1166879795404;925.421611492817;925.4956048953303;925.6390007896925;751.0949306086153;881.2949640287776;891.0465801592298;894.0523045571682;906.9773255668595;964.3490643815431;970.5729479029204;972.3873959405599;972.9854052189223;974.9463779492125;950.4914870544295;951.8381875081218;956.6460409648405;957.6552441121225;957.9377340472847;572.3761100400542;671.2012704881175;681.4718593090345;682;709.1915216653019;16048.598785030352;16072.83927160725;16090.275937582837;16090.678186436206;16093.114878681668;13313.800292995613;13640.658983525393;13915.652108697263;14804.629884252872;15283.847161528354;13163.473461061547;13209.603711888658;13479.932600337033;13591.116577422436;13883.930580347034;4581.862544123682;4619.838305659286;4640.721556706575;4645.279981602857;4803.063402636487;90297;91045.54477227638;92454.68863278405;92821.07178928259;94471.52764236135;11240.887591124148;11285.774284514264;11429.771404571862;11534.59628912984;11886.762264754656;20176.596468070555;20232.19071237134;20260.797392026187;21243.575128497345;24182.637260441108;8566.742997710078;8699.739007829648;8748;8809.779755506035;8911;5038.244263360452;5087.135187018216;5168.9741551292;5185.48145185486;5196.376434827845;3997.320455522568;4140.979295103565;4146.232946904832;4148.087420767426;4162.75023498585;682.1234713393266;684.5139950635105;684.5379368925956;688.3254410677564;704.2042491984025;778.5289899610767;810.7446154461326;818.2104269380077;838.7903024243939;849.1041950742026;10263.692089237335;10947.288426252268;11119.944400277947;11127.666170014912;11982.400879956142;755.9886601700979;811.3103861717493;831.4055450353027;837.1962915601091;837.3860031227978;5314.335708036496;5320.600954928318;5415.512603865671;5421.756020979065;5459.3994660587305;75263;75359.62320188365;76679.23320766832;97879;101122;106748;106802;108260;108617.45691271493;110332;74709.6264518674;77639;88447;94191.52904235298;94364;10365.826466551465;10559;10734.463276836283;10987.670369888916;11010.3944283062;53635.19547206873;54896.45103548913;55011;55700;58043;70702.64648676723;83894.16105839108;84511.15488845033;86684.56657716671;86725.56637216774;12750.362481876055;13049.217046977212;13188.604341869759;13550;13800.93099534496;15093.24533773285;16108.033517988959;16277.430289939795;16606.418775342805;17084.65830683404;14109;14389.568312950629;14790.260486975392;15165.696686065996;16112.436064737678;10305;13320.33398330099;14206.289685515476;14318.85681143217;14362.712745745235;14860.182689952157;14928.402863885323;15146.772798407812;15154;15480.845191548391;11333.923277288644;11563.710907227387;12117.212381195193;12214;12386.32784574071;11143.832842507207;11454.94272528632;11577.881627706493;11692.94153529227;11816.995555377545 04:15:26 INFO - PROCESS | 11825 | __end_tp_report 04:15:26 INFO - PROCESS | 11825 | __start_cc_report 04:15:26 INFO - PROCESS | 11825 | _x_x_mozilla_cycle_collect,1161 04:15:26 INFO - PROCESS | 11825 | __end_cc_report 04:15:26 INFO - PROCESS | 11825 | __startTimestamp1472210126867__endTimestamp 04:15:26 INFO - PROCESS | 11825 | 04:15:26 INFO - PROCESS | 11825 | ------- Summary: start ------- 04:15:26 INFO - PROCESS | 11825 | Number of tests: 6 04:15:26 INFO - PROCESS | 11825 | 04:15:26 INFO - PROCESS | 11825 | [#0] dojo.html Cycles:165 Average:5720.68 Median:5939.00 stddev:645.28 (10.9%) stddev-sans-first:619.17 04:15:26 INFO - PROCESS | 11825 | Values: 3320.3 3326.4 3330.0 3362.2 3380.5 5731.4 5818.3 5839.7 5851.4 5860.9 5767.9 5846.4 5852.9 5853.4 5854.3 5094.4 5250.7 5273.3 5359.1 5368.9 5166.9 5174.9 5236.9 5268.8 5305.2 5242.0 5280.7 5302.6 5320.3 5322.5 5373.6 5375.2 5377.7 5379.5 5417.1 5017.7 5162.3 5207.5 5260.7 5272.1 5655.4 5707.8 5719.2 5735.8 5770.4 6213.4 6233.0 6233.9 6244.0 6250.3 5927.6 6117.3 6143.0 6163.6 6241.6 5778.9 5790.7 5798.3 5804.9 5815.1 6324.1 6324.5 6329.2 6334.1 6344.3 5832.1 5850.0 5869.9 5894.4 5969.0 6042.9 6046.4 6064.8 6075.0 6081.5 5952.7 5995.0 6008.2 6015.9 6018.2 5955.7 6004.6 6023.4 6062.6 6071.8 4637.3 4678.1 4687.3 4711.7 4718.6 4167.9 4170.4 4176.6 4178.2 4200.6 5660.9 5812.6 5849.1 5849.3 5850.7 6202.7 6217.4 6220.1 6222.2 6227.5 5881.2 5887.6 5905.6 5912.8 5939.0 5178.2 5218.1 5222.6 5271.6 5328.6 6366.9 6417.8 6418.3 6423.1 6430.0 6109.5 6221.9 6231.9 6233.3 6242.1 6115.5 6253.0 6296.1 6326.9 6410.7 6062.1 6089.6 6090.7 6096.8 6120.4 6179.7 6188.6 6191.3 6192.1 6197.2 5830.5 6011.6 6032.7 6039.8 6077.4 6001.9 6043.8 6049.5 6055.4 6081.0 5969.5 6020.1 6028.3 6031.0 6038.1 5854.9 6015.4 6029.1 6081.0 6088.7 5925.4 5957.8 5958.6 5961.2 5997.5 04:15:26 INFO - PROCESS | 11825 | 04:15:26 INFO - PROCESS | 11825 | [#1] ext.html Cycles:180 Average:68650.52 Median:40227.00 stddev:141948.87 (352.9%) stddev-sans-first:142255.09 04:15:26 INFO - PROCESS | 11825 | Values: 586.3 626.5 631.7 644.9 654.3 33398.0 37067.0 45099.3 45841.5 45963.3 48108.5 48263.0 48452.3 48460.5 48474.5 9303.3 9457.6 9461.1 9462.1 9467.8 8497.9 11035.3 11259.9 11282.3 11645.0 11433.9 11444.1 11444.5 11462.0 11504.9 11162.6 11213.3 11224.7 11238.2 11250.0 11215.8 11270.3 11283.4 11287.2 11289.0 45177.8 45390.3 45440.8 45537.3 45543.5 3858.9 3942.5 4086.5 4120.6 4224.6 34515.8 34710.0 34778.1 34862.7 35109.5 4670.2 4731.8 4754.1 4786.8 4835.7 390784.0 393733.0 394416.0 394494.0 395486.0 66941.7 67037.0 67109.3 67353.7 67560.0 39391.4 40115.0 40339.0 70438.6 70522.6 18373.0 18525.4 18544.5 18556.5 18621.0 12704.2 13562.7 13827.6 13935.6 13938.4 20087.4 21065.9 21236.9 21255.0 21367.0 7588.7 7706.3 7717.2 7843.7 7876.4 1063.3 1129.6 1142.7 1161.0 1161.5 34875.3 35052.8 35406.8 35796.5 35873.8 1162.7 1187.5 1195.6 1196.4 1282.1 16215.9 18527.5 18757.2 19246.6 20078.6 689045.0 695845.0 698260.0 699516.0 699709.0 65462.3 65826.3 66103.0 66252.3 66338.0 1326.8 1378.9 1471.5 1489.5 1500.8 31725.2 35556.3 35910.1 36023.5 36313.8 55543.0 56091.4 56104.0 56249.4 56285.0 458139.0 459663.0 459970.0 460078.0 460938.0 30899.0 31689.4 31970.8 33471.7 33481.8 53483.5 53526.7 53640.5 53719.7 53899.0 44227.0 44968.0 45161.0 45824.3 46764.5 44011.8 44576.8 45821.5 46371.3 46536.0 44699.6 45206.3 45840.3 46359.8 46464.3 38017.4 40439.4 40565.0 41312.8 41462.6 29442.3 29573.7 29801.3 29901.0 30335.8 04:15:26 INFO - PROCESS | 11825 | 04:15:26 INFO - PROCESS | 11825 | [#2] jquery.html Cycles:180 Average:30063.63 Median:21991.67 stddev:80028.21 (363.9%) stddev-sans-first:80221.70 04:15:26 INFO - PROCESS | 11825 | Values: 391.1 399.4 411.3 414.7 425.2 16479.2 20382.9 20477.4 20752.3 20960.0 20304.6 20573.0 20681.9 20913.2 21068.8 5682.1 5758.3 5959.6 5993.8 5994.9 4411.4 5888.8 5976.9 6389.5 6569.7 6685.5 6688.1 6691.5 6693.3 6696.1 6445.9 6548.0 6613.2 6624.0 6624.2 5731.1 5806.5 5856.7 5862.0 5871.6 25244.4 25350.0 25437.2 25455.9 25512.9 2423.5 2468.8 2573.8 2700.9 2888.0 10693.4 10915.9 11153.7 11166.2 11813.1 2050.5 2061.6 2083.5 2103.3 2132.8 84367.0 84703.2 86112.6 86127.0 87845.0 11375.1 11403.4 11439.7 11590.0 11721.4 15894.7 16050.3 16054.4 16055.6 16074.8 8345.4 8547.7 8558.8 8581.0 8745.5 8293.9 8309.3 8499.7 8515.7 8566.4 4415.8 4507.7 4827.7 4922.4 5021.4 1963.9 2001.7 2006.7 2063.1 2084.7 937.7 979.2 985.5 1021.2 1043.6 20144.0 20265.2 20903.7 21941.0 22042.3 1036.8 1056.8 1068.6 1077.4 1103.4 10829.5 11662.8 11678.9 11702.4 11751.5 476550.0 480997.0 481922.0 483556.0 489636.0 101535.0 102918.0 103557.0 103845.5 104800.0 1075.0 1089.6 1104.4 1108.6 1112.0 17003.0 18201.0 18431.6 18589.3 18940.4 46669.8 46822.8 47223.5 48049.0 48760.3 66892.0 67033.0 67049.3 67113.3 67514.7 8185.1 8409.7 8470.4 8646.8 9027.1 12379.4 13494.0 13532.7 13832.7 13998.5 11142.9 11526.8 11631.0 11973.2 12088.9 11751.4 12092.3 12112.3 12159.7 12314.2 11487.4 11985.1 12242.5 12383.4 12396.9 7703.4 8736.1 8904.6 8996.4 9047.7 10018.3 11211.1 11449.4 11506.7 11765.6 04:15:26 INFO - PROCESS | 11825 | 04:15:26 INFO - PROCESS | 11825 | [#3] mootools.html Cycles:180 Average:6264.69 Median:4568.10 stddev:3651.78 (79.9%) stddev-sans-first:3643.47 04:15:26 INFO - PROCESS | 11825 | Values: 1366.6 1561.2 1598.9 1789.3 1917.3 8618.3 8959.7 8967.2 8974.6 9196.0 8895.0 9080.1 9517.1 9521.3 9527.4 2234.8 2269.0 2272.3 2274.7 2275.6 2435.9 2436.9 2524.2 2524.8 2526.2 2456.4 2469.7 2482.2 2525.8 2534.2 2358.4 2368.3 2374.9 2376.4 2400.7 2341.2 2349.8 2353.3 2355.6 2357.5 9295.1 9405.4 9418.2 9468.3 9471.8 5658.3 5879.6 5893.2 5896.9 5901.9 3531.0 3561.3 3598.2 3613.0 3644.3 1133.2 1164.1 1170.8 1183.8 2332.7 13299.6 13886.3 13915.0 13928.9 13935.1 4988.9 5050.0 5130.5 5145.6 5183.9 11701.5 11703.1 11711.5 11732.5 11741.6 5850.9 5885.3 5891.6 5905.6 5907.9 4585.2 4729.6 5059.5 5128.9 5379.0 3819.8 3947.2 3974.2 3992.5 4070.6 1367.4 1375.6 1381.1 1399.0 1405.8 893.8 988.3 1014.4 1033.6 1048.3 5977.1 6186.5 6249.3 6397.2 6414.3 475.1 488.0 1086.3 1111.0 1146.2 4139.2 4342.7 4364.1 4419.1 4551.0 11826.6 12318.6 12343.3 12353.4 12360.7 12102.8 12105.6 12206.5 12240.5 12279.1 8551.7 8651.3 8787.1 8795.2 8796.7 6152.9 6425.2 6463.5 6466.5 6572.1 10729.7 10805.6 10876.0 10879.7 11026.6 10663.5 10843.1 10974.8 11054.4 11106.8 8338.1 8425.8 8485.6 8645.3 8742.7 9190.9 9251.9 9254.0 9257.4 9257.7 8100.0 8210.6 8296.5 8500.9 8638.0 8025.5 8054.0 8112.2 8161.1 8323.4 7252.4 7340.1 7343.5 7351.9 7448.6 5299.4 5448.9 5492.4 5500.9 5576.1 5605.8 5668.2 5669.0 5792.8 5796.6 04:15:26 INFO - PROCESS | 11825 | 04:15:26 INFO - PROCESS | 11825 | [#4] prototype.html Cycles:180 Average:4657.68 Median:5210.78 stddev:1352.72 (26.0%) stddev-sans-first:1316.97 04:15:26 INFO - PROCESS | 11825 | Values: 331.9 341.7 350.7 351.7 352.1 5564.9 5570.8 5651.1 5653.2 5675.6 5314.1 5382.3 5400.8 5443.8 5596.2 4182.3 4269.8 4272.3 4303.6 4343.3 4296.8 4305.3 4313.2 4313.2 4350.7 4091.8 4178.7 4193.2 4262.7 4292.9 4235.5 4241.2 4245.5 4265.5 4270.8 3731.2 3733.8 3754.8 3772.8 3778.9 5271.3 5351.1 5363.4 5391.8 5413.8 4827.9 4841.9 4873.1 4881.4 4890.7 5678.9 5691.9 5705.5 5745.2 5781.4 3961.0 4010.6 4031.1 4053.6 4058.7 5899.4 5950.0 5955.5 5961.5 5995.3 4240.1 4243.4 4257.2 4282.6 4287.0 4165.2 4223.9 4268.7 4339.7 4339.8 4849.2 4852.7 4889.5 4892.0 4918.6 5763.9 5777.1 5778.2 5799.3 5802.5 1381.2 1438.8 1449.4 1452.6 1471.2 924.4 926.4 943.2 944.1 951.8 4990.0 5012.7 5013.8 5019.6 5032.4 5209.8 5209.8 5211.8 5217.2 5251.6 4704.6 4897.6 4988.6 4991.9 4999.0 2742.9 2768.8 2790.0 2798.6 2807.6 5368.5 5391.7 5398.8 5406.4 5407.6 4930.6 5266.8 5272.8 5289.4 5314.0 5268.0 5271.4 5291.3 5304.9 5315.7 5201.3 5205.9 5208.9 5220.3 5225.3 4821.5 5070.9 5100.3 5137.5 5155.1 5356.7 5383.8 5385.5 5402.9 5419.5 3842.6 3864.7 3876.4 3905.8 4044.5 5843.5 5911.9 5919.7 5920.9 5922.8 5797.0 5804.6 5807.8 5810.8 5847.7 5544.2 5711.0 5758.9 5771.6 5837.5 5674.7 5710.7 5728.7 5738.8 5752.5 5555.8 5797.4 5847.5 5857.2 5861.9 5608.3 5631.0 5650.7 5651.5 5698.8 04:15:26 INFO - PROCESS | 11825 | 04:15:26 INFO - PROCESS | 11825 | [#5] yui.html Cycles:180 Average:21493.86 Median:4372.31 stddev:29857.57 (682.9%) stddev-sans-first:29901.00 04:15:26 INFO - PROCESS | 11825 | Values: 825.4 838.8 842.1 854.7 912.5 16731.2 17790.6 17991.2 18060.7 18066.5 16316.4 16337.2 16478.8 16489.8 16629.2 898.4 920.1 925.4 925.5 925.6 751.1 881.3 891.0 894.1 907.0 964.3 970.6 972.4 973.0 974.9 950.5 951.8 956.6 957.7 957.9 572.4 671.2 681.5 682.0 709.2 16048.6 16072.8 16090.3 16090.7 16093.1 13313.8 13640.7 13915.7 14804.6 15283.8 13163.5 13209.6 13479.9 13591.1 13883.9 4581.9 4619.8 4640.7 4645.3 4803.1 90297.0 91045.5 92454.7 92821.1 94471.5 11240.9 11285.8 11429.8 11534.6 11886.8 20176.6 20232.2 20260.8 21243.6 24182.6 8566.7 8699.7 8748.0 8809.8 8911.0 5038.2 5087.1 5169.0 5185.5 5196.4 3997.3 4141.0 4146.2 4148.1 4162.8 682.1 684.5 684.5 688.3 704.2 778.5 810.7 818.2 838.8 849.1 10263.7 10947.3 11119.9 11127.7 11982.4 756.0 811.3 831.4 837.2 837.4 5314.3 5320.6 5415.5 5421.8 5459.4 75263.0 75359.6 76679.2 97879.0 101122.0 106748.0 106802.0 108260.0 108617.5 110332.0 74709.6 77639.0 88447.0 94191.5 94364.0 10365.8 10559.0 10734.5 10987.7 11010.4 53635.2 54896.5 55011.0 55700.0 58043.0 70702.6 83894.2 84511.2 86684.6 86725.6 12750.4 13049.2 13188.6 13550.0 13800.9 15093.2 16108.0 16277.4 16606.4 17084.7 14109.0 14389.6 14790.3 15165.7 16112.4 10305.0 13320.3 14206.3 14318.9 14362.7 14860.2 14928.4 15146.8 15154.0 15480.8 11333.9 11563.7 12117.2 12214.0 12386.3 11143.8 11454.9 11577.9 11692.9 11817.0 04:15:26 INFO - PROCESS | 11825 | -------- Summary: end -------- 04:15:26 INFO - PROCESS | 11825 | 04:15:27 INFO - TEST-INFO | 11825: exit 0 04:15:27 INFO - TEST-OK | dromaeo_css | took 1113202ms 04:15:27 INFO - TEST-START | kraken 04:15:27 INFO - operating with platform_type : linux_ 04:15:27 INFO - Initialising browser for kraken test... 04:15:27 INFO - TEST-INFO | started process 12025 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppla9oe/profile http://localhost:50945/getInfo.html) 04:15:31 INFO - PROCESS | 12025 | __metrics Screen width/height:1600/1200 04:15:31 INFO - PROCESS | 12025 | colorDepth:24 04:15:31 INFO - PROCESS | 12025 | Browser inner width/height: 1024/697 04:15:31 INFO - PROCESS | 12025 | __metrics 04:15:32 INFO - PROCESS | 12025 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:15:32 INFO - PROCESS | 12025 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:15:33 INFO - TEST-INFO | 12025: exit 0 04:15:33 INFO - Browser initialized. 04:15:33 INFO - Running cycle 1/1 for kraken test... 04:15:33 INFO - TEST-INFO | started process 12112 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppla9oe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:15:45 INFO - PROCESS | 12112 | RSS: Main: 144883712 04:15:45 INFO - PROCESS | 12112 | 04:15:47 INFO - PROCESS | 12112 | Cycle 1(1): loaded http://localhost:50945/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50945/tests/kraken/driver.html?testName=audio-beat-detection) 04:15:48 INFO - PROCESS | 12112 | RSS: Main: 191348736 04:15:48 INFO - PROCESS | 12112 | 04:15:50 INFO - PROCESS | 12112 | Cycle 1(1): loaded http://localhost:50945/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50945/tests/kraken/driver.html?testName=audio-dft) 04:15:51 INFO - PROCESS | 12112 | RSS: Main: 181190656 04:15:51 INFO - PROCESS | 12112 | 04:15:54 INFO - PROCESS | 12112 | Cycle 1(1): loaded http://localhost:50945/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50945/tests/kraken/driver.html?testName=audio-fft) 04:15:54 INFO - PROCESS | 12112 | RSS: Main: 269156352 04:15:54 INFO - PROCESS | 12112 | 04:15:56 INFO - PROCESS | 12112 | Cycle 1(1): loaded http://localhost:50945/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50945/tests/kraken/driver.html?testName=audio-oscillator) 04:15:57 INFO - PROCESS | 12112 | RSS: Main: 175108096 04:15:57 INFO - PROCESS | 12112 | 04:15:59 INFO - PROCESS | 12112 | Cycle 1(1): loaded http://localhost:50945/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50945/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:16:00 INFO - PROCESS | 12112 | RSS: Main: 176615424 04:16:00 INFO - PROCESS | 12112 | 04:16:03 INFO - PROCESS | 12112 | Cycle 1(1): loaded http://localhost:50945/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50945/tests/kraken/driver.html?testName=imaging-darkroom) 04:16:03 INFO - PROCESS | 12112 | RSS: Main: 212758528 04:16:03 INFO - PROCESS | 12112 | 04:16:07 INFO - PROCESS | 12112 | Cycle 1(1): loaded http://localhost:50945/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50945/tests/kraken/driver.html?testName=imaging-desaturate) 04:16:07 INFO - PROCESS | 12112 | RSS: Main: 211759104 04:16:07 INFO - PROCESS | 12112 | 04:16:10 INFO - PROCESS | 12112 | Cycle 1(1): loaded http://localhost:50945/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50945/tests/kraken/driver.html?testName=json-parse-financial) 04:16:11 INFO - PROCESS | 12112 | RSS: Main: 210870272 04:16:11 INFO - PROCESS | 12112 | 04:16:12 INFO - PROCESS | 12112 | Cycle 1(1): loaded http://localhost:50945/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50945/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:16:13 INFO - PROCESS | 12112 | RSS: Main: 175673344 04:16:13 INFO - PROCESS | 12112 | 04:16:15 INFO - PROCESS | 12112 | Cycle 1(1): loaded http://localhost:50945/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50945/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:16:15 INFO - PROCESS | 12112 | RSS: Main: 180502528 04:16:15 INFO - PROCESS | 12112 | 04:16:17 INFO - PROCESS | 12112 | Cycle 1(1): loaded http://localhost:50945/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50945/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:16:18 INFO - PROCESS | 12112 | RSS: Main: 186150912 04:16:18 INFO - PROCESS | 12112 | 04:16:20 INFO - PROCESS | 12112 | Cycle 1(1): loaded http://localhost:50945/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50945/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:16:21 INFO - PROCESS | 12112 | RSS: Main: 181899264 04:16:21 INFO - PROCESS | 12112 | 04:16:24 INFO - PROCESS | 12112 | Cycle 1(1): loaded http://localhost:50945/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50945/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:16:24 INFO - PROCESS | 12112 | RSS: Main: 184602624 04:16:24 INFO - PROCESS | 12112 | 04:16:26 INFO - PROCESS | 12112 | Cycle 1(1): loaded http://localhost:50945/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50945/tests/kraken/driver.html?testName=ai-astar) 04:16:26 INFO - PROCESS | 12112 | RSS: Main: 235556864 04:16:26 INFO - PROCESS | 12112 | 04:16:26 INFO - PROCESS | 12112 | __start_tp_report 04:16:26 INFO - PROCESS | 12112 | _x_x_mozilla_page_load 04:16:26 INFO - PROCESS | 12112 | _x_x_mozilla_page_load_details 04:16:26 INFO - PROCESS | 12112 | |i|pagename|runs| 04:16:26 INFO - PROCESS | 12112 | |0;ai-astar;102;110;111;111;111;110;110;110;111;111 04:16:26 INFO - PROCESS | 12112 | |1;audio-beat-detection;140;114;115;189;115;117;140;118;160;114 04:16:26 INFO - PROCESS | 12112 | |2;audio-dft;161;161;162;161;160;161;161;160;161;161 04:16:26 INFO - PROCESS | 12112 | |3;audio-fft;74;74;74;113;74;77;75;76;103;75 04:16:26 INFO - PROCESS | 12112 | |4;audio-oscillator;83;82;135;83;83;83;116;83;83;126 04:16:26 INFO - PROCESS | 12112 | |5;imaging-gaussian-blur;116;116;117;117;117;116;118;117;116;117 04:16:26 INFO - PROCESS | 12112 | |6;imaging-darkroom;146;146;147;145;146;145;145;145;146;145 04:16:26 INFO - PROCESS | 12112 | |7;imaging-desaturate;95;95;95;95;95;94;95;95;94;95 04:16:26 INFO - PROCESS | 12112 | |8;json-parse-financial;51;51;50;49;55;51;51;51;50;50 04:16:26 INFO - PROCESS | 12112 | |9;json-stringify-tinderbox;61;60;59;59;59;59;59;59;60;58 04:16:26 INFO - PROCESS | 12112 | |10;stanford-crypto-aes;72;72;75;73;73;72;78;72;81;80 04:16:26 INFO - PROCESS | 12112 | |11;stanford-crypto-ccm;119;124;125;127;124;117;131;118;160;127 04:16:26 INFO - PROCESS | 12112 | |12;stanford-crypto-pbkdf2;148;152;150;151;152;153;150;151;150;151 04:16:26 INFO - PROCESS | 12112 | |13;stanford-crypto-sha256-iterative;63;61;61;60;60;62;62;60;60;60 04:16:26 INFO - PROCESS | 12112 | __end_tp_report 04:16:26 INFO - PROCESS | 12112 | __start_cc_report 04:16:26 INFO - PROCESS | 12112 | _x_x_mozilla_cycle_collect,1638 04:16:26 INFO - PROCESS | 12112 | __end_cc_report 04:16:26 INFO - PROCESS | 12112 | __startTimestamp1472210186522__endTimestamp 04:16:26 INFO - PROCESS | 12112 | 04:16:26 INFO - PROCESS | 12112 | ------- Summary: start ------- 04:16:26 INFO - PROCESS | 12112 | Number of tests: 14 04:16:26 INFO - PROCESS | 12112 | 04:16:26 INFO - PROCESS | 12112 | [#0] ai-astar Cycles:10 Average:109.70 Median:111.00 stddev:2.75 (2.5%) stddev-sans-first:0.53 04:16:26 INFO - PROCESS | 12112 | Values: 102.0 110.0 111.0 111.0 111.0 110.0 110.0 110.0 111.0 111.0 04:16:26 INFO - PROCESS | 12112 | 04:16:26 INFO - PROCESS | 12112 | [#1] audio-beat-detection Cycles:10 Average:132.20 Median:129.00 stddev:25.40 (19.7%) stddev-sans-first:26.79 04:16:26 INFO - PROCESS | 12112 | Values: 140.0 114.0 115.0 189.0 115.0 117.0 140.0 118.0 160.0 114.0 04:16:26 INFO - PROCESS | 12112 | 04:16:26 INFO - PROCESS | 12112 | [#2] audio-dft Cycles:10 Average:160.90 Median:161.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 04:16:26 INFO - PROCESS | 12112 | Values: 161.0 161.0 162.0 161.0 160.0 161.0 161.0 160.0 161.0 161.0 04:16:26 INFO - PROCESS | 12112 | 04:16:26 INFO - PROCESS | 12112 | [#3] audio-fft Cycles:10 Average:81.50 Median:74.50 stddev:14.20 (19.1%) stddev-sans-first:14.80 04:16:26 INFO - PROCESS | 12112 | Values: 74.0 74.0 74.0 113.0 74.0 77.0 75.0 76.0 103.0 75.0 04:16:26 INFO - PROCESS | 12112 | 04:16:26 INFO - PROCESS | 12112 | [#4] audio-oscillator Cycles:10 Average:95.70 Median:83.00 stddev:21.16 (25.5%) stddev-sans-first:21.94 04:16:26 INFO - PROCESS | 12112 | Values: 83.0 82.0 135.0 83.0 83.0 83.0 116.0 83.0 83.0 126.0 04:16:26 INFO - PROCESS | 12112 | 04:16:26 INFO - PROCESS | 12112 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.67 (0.6%) stddev-sans-first:0.67 04:16:26 INFO - PROCESS | 12112 | Values: 116.0 116.0 117.0 117.0 117.0 116.0 118.0 117.0 116.0 117.0 04:16:26 INFO - PROCESS | 12112 | 04:16:26 INFO - PROCESS | 12112 | [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.70 (0.5%) stddev-sans-first:0.73 04:16:26 INFO - PROCESS | 12112 | Values: 146.0 146.0 147.0 145.0 146.0 145.0 145.0 145.0 146.0 145.0 04:16:26 INFO - PROCESS | 12112 | 04:16:26 INFO - PROCESS | 12112 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 04:16:26 INFO - PROCESS | 12112 | Values: 95.0 95.0 95.0 95.0 95.0 94.0 95.0 95.0 94.0 95.0 04:16:26 INFO - PROCESS | 12112 | 04:16:26 INFO - PROCESS | 12112 | [#8] json-parse-financial Cycles:10 Average:50.90 Median:51.00 stddev:1.60 (3.1%) stddev-sans-first:1.69 04:16:26 INFO - PROCESS | 12112 | Values: 51.0 51.0 50.0 49.0 55.0 51.0 51.0 51.0 50.0 50.0 04:16:26 INFO - PROCESS | 12112 | 04:16:26 INFO - PROCESS | 12112 | [#9] json-stringify-tinderbox Cycles:10 Average:59.30 Median:59.00 stddev:0.82 (1.4%) stddev-sans-first:0.60 04:16:26 INFO - PROCESS | 12112 | Values: 61.0 60.0 59.0 59.0 59.0 59.0 59.0 59.0 60.0 58.0 04:16:26 INFO - PROCESS | 12112 | 04:16:26 INFO - PROCESS | 12112 | [#10] stanford-crypto-aes Cycles:10 Average:74.80 Median:74.00 stddev:3.55 (4.8%) stddev-sans-first:3.62 04:16:26 INFO - PROCESS | 12112 | Values: 72.0 72.0 75.0 73.0 73.0 72.0 78.0 72.0 81.0 80.0 04:16:26 INFO - PROCESS | 12112 | 04:16:26 INFO - PROCESS | 12112 | [#11] stanford-crypto-ccm Cycles:10 Average:127.20 Median:126.00 stddev:12.35 (9.8%) stddev-sans-first:12.73 04:16:26 INFO - PROCESS | 12112 | Values: 119.0 124.0 125.0 127.0 124.0 117.0 131.0 118.0 160.0 127.0 04:16:26 INFO - PROCESS | 12112 | 04:16:26 INFO - PROCESS | 12112 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:150.80 Median:151.00 stddev:1.40 (0.9%) stddev-sans-first:1.05 04:16:26 INFO - PROCESS | 12112 | Values: 148.0 152.0 150.0 151.0 152.0 153.0 150.0 151.0 150.0 151.0 04:16:26 INFO - PROCESS | 12112 | 04:16:26 INFO - PROCESS | 12112 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.90 Median:61.00 stddev:1.10 (1.8%) stddev-sans-first:0.87 04:16:26 INFO - PROCESS | 12112 | Values: 63.0 61.0 61.0 60.0 60.0 62.0 62.0 60.0 60.0 60.0 04:16:26 INFO - PROCESS | 12112 | -------- Summary: end -------- 04:16:26 INFO - PROCESS | 12112 | 04:16:27 INFO - TEST-INFO | 12112: exit 0 04:16:27 INFO - TEST-OK | kraken | took 59937ms 04:16:27 INFO - SUITE-END | took 1173s 04:16:27 INFO - Completed test suite (00:19:33) 04:16:27 INFO - javascript benchmark 04:16:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3320.2529430878053, 3326.434506133957, 3329.9500507492394, 3362.2266878617934, 3380.5267262583243, 5731.369549349573, 5818.3308919474275, 5839.708014599274, 5851.4148585141465, 5860.882782344345, 5767.913481297774, 5846.4445877641665, 5852.912206316898, 5853.385394533577, 5854.268216472941, 5094.363204599425, 5250.7112108834, 5273.34083239595, 5359.08895487768, 5368.892622147555, 5166.9224961625605, 5174.922376164361, 5236.895262094756, 5268.841934741964, 5305.204219367107, 5241.973790131044, 5280.683159010472, 5302.6023048271345, 5320.308359913206, 5322.547583455392, 5373.596980226499, 5375.193720941851, 5377.731113444352, 5379.542738867221, 5417.052015897202, 5017.673851199696, 5162.328897243372, 5207.531322181022, 5260.736963151827, 5272.051030814452, 5655.434456554352, 5707.800226992077, 5719.170720245541, 5735.82792516221, 5770.394108618619, 6213.378662133796, 6233.0, 6233.87532249357, 6243.968780156116, 6250.281217659982, 5927.585069045169, 6117.311613022215, 6142.959235178615, 6163.568550201488, 6241.594296370767, 5778.88442231153, 5790.652560846363, 5798.333191682968, 5804.88390232193, 5815.127730840323, 6324.08300796382, 6324.462420694293, 6329.208848893888, 6334.081558174039, 6344.302126766052, 5832.12518122285, 5850.005499065169, 5869.911951320733, 5894.410558944157, 5968.970155149196, 6042.879142417127, 6046.395360463918, 6064.848378790477, 6075.027995520783, 6081.543884208702, 5952.7321270542925, 5994.9700251499335, 6008.188894499274, 6015.909761353583, 6018.247719035121, 5955.67243801595, 6004.579679422399, 6023.397660234029, 6062.575619706578, 6071.848203794852, 4637.281221410619, 4678.134545109166, 4687.296905464208, 4711.740854253049, 4718.551737584924, 4167.8958026049595, 4170.35359519275, 4176.561461046607, 4178.206140833293, 4200.600942910419, 5660.8867822643315, 5812.622179558315, 5849.1226316052935, 5849.327327357323, 5850.736716847752, 6202.658853763086, 6217.409346112112, 6220.129181914535, 6222.222222222223, 6227.470664993526, 5881.206037184926, 5887.646741195542, 5905.557083218776, 5912.822615321547, 5939.0, 5178.223266510054, 5218.112920803473, 5222.55608273301, 5271.630985830955, 5328.573714102862, 6366.85396628602, 6417.839554011093, 6418.326075761977, 6423.13287706163, 6430.0, 6109.511239100772, 6221.9377806223165, 6231.906521402273, 6233.283172435228, 6242.1261023455845, 6115.544587952112, 6253.030780229072, 6296.118543404019, 6326.905096423466, 6410.679466026773, 6062.090686397076, 6089.634621922699, 6090.725917333642, 6096.817095487142, 6120.418560236771, 6179.721912514037, 6188.628682279078, 6191.25704915413, 6192.071189321638, 6197.194364732556, 5830.53355731532, 6011.639301641915, 6032.6983650818165, 6039.818805435844, 6077.4226448487325, 6001.90997135052, 6043.8186854394435, 6049.546284028804, 6055.424734650202, 6081.0, 5969.522438204846, 6020.066889632114, 6028.306744724237, 6031.0, 6038.124471951454, 5854.882902342015, 6015.368386319374, 6029.125776762433, 6081.0, 6088.665123418255, 5925.4370834770625, 5957.76168953237, 5958.582899197015, 5961.195238642727, 5997.520198384032], "value": 5677.235802596644, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [586.2788769813129, 626.5019309648828, 631.6967855429393, 644.9000404937235, 654.2704884054281, 33398.0, 37067.0, 45099.32351014738, 45841.54158458414, 45963.31055034177, 48108.51891481093, 48263.0, 48452.2732159017, 48460.51539484604, 48474.51525484744, 9303.25573954082, 9457.621695132186, 9461.053894610553, 9462.101100395486, 9467.763305917339, 8497.915020849805, 11035.337879727202, 11259.887401126007, 11282.266652667551, 11645.0, 11433.942830285878, 11444.14168937329, 11444.542218311257, 11462.025727813147, 11504.942475287611, 11162.609308674198, 11213.327200368005, 11224.66326010221, 11238.213325067249, 11250.0, 11215.831762523569, 11270.267432616854, 11283.37941413222, 11287.209895307331, 11289.0, 45177.77411112956, 45390.319145212845, 45440.77279613614, 45537.316940245924, 45543.544564554264, 3858.9421158682644, 3942.5466071401584, 4086.4891888513935, 4120.587941205886, 4224.598663126998, 34515.82742086299, 34710.0, 34778.13054673628, 34862.651373486195, 35109.47335789965, 4670.182718024366, 4731.810727570893, 4754.096721622881, 4786.832460863853, 4835.661503694742, 390784.0, 393733.0, 394416.0, 394494.0, 395486.0, 66941.6652916742, 67037.0, 67109.32890671128, 67353.66323168353, 67560.0, 39391.40912886309, 40115.0, 40339.0, 70438.64780676064, 70522.64738676274, 18373.0, 18525.351612693492, 18544.536386590175, 18556.536086597942, 18621.0, 12704.174228675107, 13562.660933476742, 13827.585172444842, 13935.58193254204, 13938.37277322523, 20087.39737807868, 21065.894670526857, 21236.893815531133, 21255.0, 21367.0, 7588.734394296173, 7706.344960678405, 7717.189695082048, 7843.72546960865, 7876.369890408753, 1063.31416236527, 1129.6046383765615, 1142.708609304622, 1161.0131388320028, 1161.5005547614437, 34875.30249394998, 35052.82473587616, 35406.82296588552, 35796.46305305423, 35873.82063089668, 1162.686074759827, 1187.5131196209513, 1195.5755706724162, 1196.3778835005749, 1282.0576875996321, 16215.918920405322, 18527.53681157955, 18757.249710011718, 19246.577736936593, 20078.598428031648, 689045.0, 695845.0, 698260.0, 699516.0, 699709.0, 65462.345376546575, 65826.34173658202, 66103.0, 66252.33747662559, 66338.0, 1326.7943468762164, 1378.8690074442911, 1471.5438214153646, 1489.53079779869, 1500.7748837674217, 31725.20686982844, 35556.28887422289, 35910.102247444025, 36023.459648105825, 36313.818430907675, 55543.0, 56091.439085608625, 56104.0, 56249.43750562442, 56285.0, 458139.0, 459663.0, 459970.0, 460078.0, 460938.0, 30899.0, 31689.36621267608, 31970.84014579912, 33471.66528334783, 33481.83259083689, 53483.46516534941, 53526.73236633792, 53640.463595363544, 53719.731401342746, 53899.0, 44227.0, 44968.0, 45161.0, 45824.312635311166, 46764.53235467602, 44011.77994110009, 44576.777116114215, 45821.54178458173, 46371.304430432894, 46536.0, 44699.55300447084, 45206.32190517211, 45840.31239531343, 46359.768201158775, 46464.303035455174, 38017.429738553394, 40439.3934090983, 40565.0, 41312.793436032625, 41462.58537414587, 29442.263943401587, 29573.704262957956, 29801.25496862596, 29901.0, 30335.848320758254], "value": 22281.511556656755, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [391.07510737106713, 399.4328054163086, 411.30694779296886, 414.6558356564052, 425.23670012327887, 16479.176041197938, 20382.898085509587, 20477.385678429633, 20752.27367042154, 20960.0, 20304.593908121828, 20573.0, 20681.896590517026, 20913.16347346108, 21068.789312106874, 5682.090865461527, 5758.251427314443, 5959.642421454727, 5993.820185394445, 5994.8801023979495, 4411.448568928884, 5888.764449422039, 5976.850578735544, 6389.488840892741, 6569.737210511574, 6685.4985876059245, 6688.09710689058, 6691.464682825386, 6693.297203793604, 6696.096027036337, 6445.935540644604, 6548.0, 6613.2064152301555, 6624.039514270428, 6624.238212605515, 5731.111677689964, 5806.477417032445, 5856.7071646417935, 5861.970690146564, 5871.588988770788, 25244.368890777692, 25350.0, 25437.236882893543, 25455.872720636464, 25512.872435637888, 2423.5152969406013, 2468.7654672806057, 2573.794096472278, 2700.8784604692837, 2887.971120288791, 10693.411862347568, 10915.890841091566, 11153.717322507853, 11166.218364714474, 11813.1140164488, 2050.4771283322807, 2061.597988392264, 2083.468715477559, 2103.2533450625115, 2132.818710409617, 84367.0, 84703.15296847076, 86112.56943715242, 86127.0, 87845.0, 11375.146863985234, 11403.372814495282, 11439.714007149887, 11590.0, 11721.355325457182, 15894.68210635804, 16050.277737501841, 16054.357825686842, 16055.598610034842, 16074.75887861683, 8345.374096942754, 8547.74356769298, 8558.786030349167, 8581.0, 8745.47527148373, 8293.875591866126, 8309.252167304972, 8499.702510412106, 8515.701950431829, 8566.357523185787, 4415.823367065345, 4507.684462087623, 4827.662063655581, 4922.384701912261, 5021.372328458942, 1963.9312624058089, 2001.739773829422, 2006.6822919264066, 2063.13348393675, 2084.687296905446, 937.6999360204805, 979.2459805949521, 985.5417230987625, 1021.2085633633993, 1043.5617040842865, 20144.0, 20265.189392424432, 20903.68644470334, 21941.0, 22042.33872983813, 1036.79782442423, 1056.799208150449, 1068.647346375694, 1077.380506208933, 1103.442761405485, 10829.458527073615, 11662.76674466506, 11678.94160529209, 11702.356370399539, 11751.529938802523, 476550.0, 480997.0, 481922.0, 483556.0, 489636.0, 101535.0, 102918.0, 103557.0, 103845.48077259565, 104800.0, 1074.957001719938, 1089.580511503077, 1104.3925840787535, 1108.5953626926114, 1112.038087054692, 17003.0, 18201.0, 18431.631367372847, 18589.256429742658, 18940.43178704641, 46669.76665116653, 46822.76588617035, 47223.52776472191, 48049.0, 48760.2685959718, 66892.0, 67033.0, 67049.32950670431, 67113.32886671071, 67514.66242668756, 8185.05871824724, 8409.705660301861, 8470.449420787629, 8646.82706345857, 9027.052159523419, 12379.442925068573, 13494.0, 13532.729345413234, 13832.654183645489, 13998.510052148125, 11142.888571114185, 11526.827097593376, 11631.0, 11973.221740586834, 12088.939555302168, 11751.412429378326, 12092.334921579397, 12112.333821639895, 12159.696007599881, 12314.199577027823, 11487.36819474937, 11985.101117416054, 12242.510299587917, 12383.442745076312, 12396.938015309866, 7703.38372930153, 8736.082711315474, 8904.643814247358, 8996.370254082152, 9047.68333108338, 10018.298719089886, 11211.103111751203, 11449.370284634091, 11506.712332191428, 11765.588204413147], "value": 9742.916944205115, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1366.637840972142, 1561.1959840682048, 1598.896071755336, 1789.3468883857397, 1917.261854186138, 8618.310535157188, 8959.73120806376, 8967.237784788285, 8974.551272436385, 9195.954020229907, 8894.955525222365, 9080.137386948245, 9517.095875891779, 9521.285903557244, 9527.380720253179, 2234.8323875709266, 2269.024319542596, 2272.2842304673973, 2274.692916456274, 2275.6472746724285, 2435.914742983996, 2436.8903399347073, 2524.1670248817936, 2524.7601477859575, 2526.178991827654, 2456.447299357639, 2469.6665950096685, 2482.2181012980855, 2525.8484490930605, 2534.1637259704253, 2358.3986083548757, 2368.2539999899977, 2374.9406264843346, 2376.3821406434363, 2400.6759087523137, 2341.2273949596593, 2349.8237632177484, 2353.2822489140804, 2355.599548076831, 2357.54027964547, 9295.07049295072, 9405.43567385959, 9418.24654027683, 9468.28987825909, 9471.763205919839, 5658.264425624642, 5879.588428809985, 5893.204417403638, 5896.852578685524, 5901.911471327933, 3530.9646903530893, 3561.3411518869093, 3598.1724203433096, 3612.963870361289, 3644.3075815595216, 1133.1727838677812, 1164.097824186254, 1170.8126699728002, 1183.786918354696, 2332.720073591163, 13299.601011969657, 13886.305684715724, 13915.0, 13928.930355348159, 13935.09421887574, 4988.925166122511, 5049.974750126225, 5130.538251557377, 5145.614078944094, 5183.922241166386, 11701.473433695504, 11703.063754899587, 11711.531538738525, 11732.530698772125, 11741.589044383405, 5850.882982340415, 5885.323187833368, 5891.587588868738, 5905.616134951214, 5907.881842363215, 4585.19759042173, 4729.597984171384, 5059.544640982329, 5128.871778205575, 5379.004884096455, 3819.8281077351585, 3947.2105578883766, 3974.18529201513, 3992.5208974923194, 4070.5522392536795, 1367.3983447283363, 1375.628580283318, 1381.0815807487909, 1398.9930050349888, 1405.8102156208986, 893.7854914820397, 988.3427520698799, 1014.3558840136419, 1033.5504055735628, 1048.3447845096814, 5977.1333156692035, 6186.505079593621, 6249.312575616678, 6397.232332120082, 6414.326495717975, 475.07123573413895, 488.0434348676563, 1086.2613422872364, 1110.9500072496758, 1146.1804809561038, 4139.172165566899, 4342.6742994275555, 4364.1053584015835, 4419.1382680377665, 4551.0, 11826.645200643994, 12318.630441086782, 12343.259404435763, 12353.3823308836, 12360.690982725504, 12102.81845772314, 12105.636830895088, 12206.450709718085, 12240.510379584717, 12279.140460167702, 8551.657933682583, 8651.264642505332, 8787.121287871161, 8795.164459376349, 8796.736097917073, 6152.907706384318, 6425.1968503937005, 6463.450606698295, 6466.515011374072, 6572.112764776694, 10729.731756706145, 10805.567777289134, 10876.0, 10879.673609791718, 11026.558937642405, 10663.520141593492, 10843.078338341329, 10974.835377469184, 11054.392008439612, 11106.777864442827, 8338.124496927736, 8425.789355266168, 8485.618147183268, 8645.308375329832, 8742.650293988168, 9190.862137068083, 9251.861222081809, 9254.0, 9257.35198536096, 9257.67598134062, 8099.959500202462, 8210.58947052657, 8296.543690097102, 8500.914990850013, 8638.049814520273, 8025.5184688916515, 8054.0, 8112.18878112184, 8161.102278749214, 8323.375746819134, 7252.38354739853, 7340.11918569775, 7343.522671026366, 7351.926480735124, 7448.553086814808, 5299.39057008449, 5448.863778405572, 5492.395836458067, 5500.9174862377895, 5576.0799468088935, 5605.803796867253, 5668.178114173505, 5669.036263835241, 5792.768289268382, 5796.565257605612], "value": 4963.89449769296, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [331.8854995026715, 341.7300332737138, 350.6773768133317, 351.74674234551117, 352.1355073295061, 5564.86087847804, 5570.832875013746, 5651.124075768253, 5653.180288858113, 5675.602707810455, 5314.123169677008, 5382.273393091931, 5400.78396864126, 5443.8094666686675, 5596.188552659872, 4182.268103081964, 4269.8292068317305, 4272.273713468702, 4303.634191093761, 4343.348497725335, 4296.849610263642, 4305.266155990937, 4313.2020576193345, 4313.2451820931365, 4350.695451318409, 4091.754494730311, 4178.707490475668, 4193.203291374644, 4262.701610887239, 4292.892677683051, 4235.512916014667, 4241.172971270604, 4245.469316335458, 4265.466816647903, 4270.807813648394, 3731.2164445466215, 3733.757305775116, 3754.8310326035394, 3772.792496412696, 3778.9433158502643, 5271.341082364705, 5351.117065684158, 5363.436839131913, 5391.838244852622, 5413.810516631938, 4827.8551643450755, 4841.903161936777, 4873.074115917964, 4881.365422495089, 4890.706557606555, 5678.914816277759, 5691.9146212806845, 5705.5435565154685, 5745.166950792153, 5781.4218578141845, 3961.0, 4010.631308606604, 4031.113155105872, 4053.5541090480024, 4058.6753059755147, 5899.410058994109, 5950.0, 5955.493783028448, 5961.5230781537375, 5995.250593675834, 4240.088380998039, 4243.42713733642, 4257.212415703105, 4282.614564689131, 4287.0, 4165.229432554987, 4223.894402639959, 4268.722533035343, 4339.7396156230725, 4339.7830108494445, 4849.248366503198, 4852.708837469763, 4889.535494128052, 4891.951080489221, 4918.606511479073, 5763.94236057634, 5777.133429985452, 5778.219940308089, 5799.347186051955, 5802.4777770000055, 1381.240317825192, 1438.8057612222217, 1449.3767679897535, 1452.6077958951023, 1471.249662672045, 924.4314746430897, 926.3654396738256, 943.2077055273603, 944.1172503709054, 951.7906060666643, 4990.0, 5012.749362531858, 5013.824516141991, 5019.598432125421, 5032.446430892599, 5209.765560549784, 5209.7916083356995, 5211.765470553758, 5217.243499692523, 5251.632385732962, 4704.612139418811, 4897.593534192058, 4988.551030407209, 4991.850244492671, 4999.00059964026, 2742.903998360048, 2768.7646550043473, 2789.9537673372483, 2798.572727908781, 2807.5648274517484, 5368.4631536846, 5391.7304134793885, 5398.78404863801, 5406.432324605938, 5407.648502847303, 4930.556249937523, 5266.7629956652045, 5272.815451459181, 5289.391719952255, 5313.963777063435, 5267.973660131752, 5271.367435907716, 5291.285676433709, 5304.92042619361, 5315.73421328932, 5201.323827902348, 5205.895882082262, 5208.880090780349, 5220.321358223407, 5225.294585230945, 4821.457133717317, 5070.873228169326, 5100.311457953128, 5137.486251374833, 5155.123628983157, 5356.732163391893, 5383.757730902197, 5385.46145385474, 5402.891942161214, 5419.458054194707, 3842.5965273645866, 3864.71014673895, 3876.3991581304945, 3905.785181814914, 4044.4944381952255, 5843.474087331991, 5911.940880591311, 5919.733611987384, 5920.8815823684145, 5922.7927022554, 5797.0, 5804.564657650778, 5807.83843231347, 5810.767569297181, 5847.736851841592, 5544.1960915667805, 5711.000574899293, 5758.9189783465645, 5771.59598828078, 5837.532997360286, 5674.716264186692, 5710.714464276853, 5728.713564321851, 5738.7948530807325, 5752.511036561939, 5555.833325000256, 5797.391273916178, 5847.473727364558, 5857.209276747584, 5861.882762344644, 5608.327000759935, 5630.971845140748, 5650.717464126695, 5651.519620832275, 5698.772049117989], "value": 4229.402892160331, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [825.3933358981147, 838.8448137094634, 842.0779246724834, 854.6965827131369, 912.4570880326207, 16731.163441827906, 17790.555236119104, 17991.19039643216, 18060.729089063676, 18066.458006259818, 16316.448615710544, 16337.18314084297, 16478.7528187077, 16489.752653710202, 16629.168541572904, 898.4025622960723, 920.1166879795404, 925.421611492817, 925.4956048953303, 925.6390007896925, 751.0949306086153, 881.2949640287776, 891.0465801592298, 894.0523045571682, 906.9773255668595, 964.3490643815431, 970.5729479029204, 972.3873959405599, 972.9854052189223, 974.9463779492125, 950.4914870544295, 951.8381875081218, 956.6460409648405, 957.6552441121225, 957.9377340472847, 572.3761100400542, 671.2012704881175, 681.4718593090345, 682.0, 709.1915216653019, 16048.598785030352, 16072.83927160725, 16090.275937582837, 16090.678186436206, 16093.114878681668, 13313.800292995613, 13640.658983525393, 13915.652108697263, 14804.629884252872, 15283.847161528354, 13163.473461061547, 13209.603711888658, 13479.932600337033, 13591.116577422436, 13883.930580347034, 4581.862544123682, 4619.838305659286, 4640.721556706575, 4645.279981602857, 4803.063402636487, 90297.0, 91045.54477227638, 92454.68863278405, 92821.07178928259, 94471.52764236135, 11240.887591124148, 11285.774284514264, 11429.771404571862, 11534.59628912984, 11886.762264754656, 20176.596468070555, 20232.19071237134, 20260.797392026187, 21243.575128497345, 24182.637260441108, 8566.742997710078, 8699.739007829648, 8748.0, 8809.779755506035, 8911.0, 5038.244263360452, 5087.135187018216, 5168.9741551292, 5185.48145185486, 5196.376434827845, 3997.320455522568, 4140.979295103565, 4146.232946904832, 4148.087420767426, 4162.75023498585, 682.1234713393266, 684.5139950635105, 684.5379368925956, 688.3254410677564, 704.2042491984025, 778.5289899610767, 810.7446154461326, 818.2104269380077, 838.7903024243939, 849.1041950742026, 10263.692089237335, 10947.288426252268, 11119.944400277947, 11127.666170014912, 11982.400879956142, 755.9886601700979, 811.3103861717493, 831.4055450353027, 837.1962915601091, 837.3860031227978, 5314.335708036496, 5320.600954928318, 5415.512603865671, 5421.756020979065, 5459.3994660587305, 75263.0, 75359.62320188365, 76679.23320766832, 97879.0, 101122.0, 106748.0, 106802.0, 108260.0, 108617.45691271493, 110332.0, 74709.6264518674, 77639.0, 88447.0, 94191.52904235298, 94364.0, 10365.826466551465, 10559.0, 10734.463276836283, 10987.670369888916, 11010.3944283062, 53635.19547206873, 54896.45103548913, 55011.0, 55700.0, 58043.0, 70702.64648676723, 83894.16105839108, 84511.15488845033, 86684.56657716671, 86725.56637216774, 12750.362481876055, 13049.217046977212, 13188.604341869759, 13550.0, 13800.93099534496, 15093.24533773285, 16108.033517988959, 16277.430289939795, 16606.418775342805, 17084.65830683404, 14109.0, 14389.568312950629, 14790.260486975392, 15165.696686065996, 16112.436064737678, 10305.0, 13320.33398330099, 14206.289685515476, 14318.85681143217, 14362.712745745235, 14860.182689952157, 14928.402863885323, 15146.772798407812, 15154.0, 15480.845191548391, 11333.923277288644, 11563.710907227387, 12117.212381195193, 12214.0, 12386.32784574071, 11143.832842507207, 11454.94272528632, 11577.881627706493, 11692.94153529227, 11816.995555377545], "value": 8170.361066139707, "unit": "score"}], "name": "dromaeo_css", "value": 7718.388209176184, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 110.0, 111.0, 111.0, 111.0, 110.0, 110.0, 110.0, 111.0, 111.0], "value": 109.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 114.0, 115.0, 189.0, 115.0, 117.0, 140.0, 118.0, 160.0, 114.0], "value": 132.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 162.0, 161.0, 160.0, 161.0, 161.0, 160.0, 161.0, 161.0], "value": 160.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 74.0, 113.0, 74.0, 77.0, 75.0, 76.0, 103.0, 75.0], "value": 81.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 135.0, 83.0, 83.0, 83.0, 116.0, 83.0, 83.0, 126.0], "value": 95.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 117.0, 117.0, 117.0, 116.0, 118.0, 117.0, 116.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 147.0, 145.0, 146.0, 145.0, 145.0, 145.0, 146.0, 145.0], "value": 145.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 94.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 51.0, 50.0, 49.0, 55.0, 51.0, 51.0, 51.0, 50.0, 50.0], "value": 50.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 60.0, 59.0, 59.0, 59.0, 59.0, 59.0, 59.0, 60.0, 58.0], "value": 59.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 72.0, 75.0, 73.0, 73.0, 72.0, 78.0, 72.0, 81.0, 80.0], "value": 74.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 124.0, 125.0, 127.0, 124.0, 117.0, 131.0, 118.0, 160.0, 127.0], "value": 127.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 152.0, 150.0, 151.0, 152.0, 153.0, 150.0, 151.0, 150.0, 151.0], "value": 150.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 61.0, 61.0, 60.0, 60.0, 62.0, 62.0, 60.0, 60.0, 60.0], "value": 60.9, "unit": "score"}], "name": "kraken", "value": 1461.0, "alertThreshold": 2.0}]} 04:16:27 INFO - Return code: 0 04:16:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:16:27 INFO - # TBPL SUCCESS # 04:16:27 INFO - Running post-action listener: _resource_record_post_action 04:16:27 INFO - [mozharness: 2016-08-26 11:16:27.816794Z] Finished run-tests step (success) 04:16:27 INFO - Running post-run listener: _resource_record_post_run 04:16:27 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 270336; Write bytes: 328790016; Read time: 196; Write time: 200104 04:16:27 INFO - TinderboxPrint: CPU usage
13.0% 04:16:27 INFO - TinderboxPrint: I/O read bytes / time
270,336 / 196 04:16:27 INFO - TinderboxPrint: I/O write bytes / time
328,790,016 / 200,104 04:16:27 INFO - TinderboxPrint: CPU idle
8,254.7 (87.0%) 04:16:27 INFO - TinderboxPrint: CPU user
1,189.1 (12.5%) 04:16:27 INFO - TinderboxPrint: Swap in / out
0 / 0 04:16:27 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 77053952; Read time: 0; Write time: 50380 04:16:27 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 266240; Write bytes: 249008128; Read time: 184; Write time: 149432 04:16:28 INFO - Running post-run listener: _upload_blobber_files 04:16:28 INFO - Blob upload gear active. 04:16:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:16:28 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 04:16:28 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:16:28 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:16:28 INFO - (blobuploader) - INFO - Open directory for files ... 04:16:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:16:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:16:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:16:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:16:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:16:29 INFO - (blobuploader) - INFO - Done attempting. 04:16:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:16:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:16:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:16:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:16:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:16:30 INFO - (blobuploader) - INFO - Done attempting. 04:16:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:16:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:16:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:16:33 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:16:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:16:33 INFO - (blobuploader) - INFO - Done attempting. 04:16:33 INFO - (blobuploader) - INFO - Iteration through files over. 04:16:33 INFO - Return code: 0 04:16:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:16:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:16:33 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0b739bb1add9ed7e7f8e71b92755226539177e959c41959e3c90e066e01a91978f9d16a8ede07aa4c4aca39a872f98183a4ae8f87afc47c34c12ef34d97e0b2f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/02b9a61884cae2c29bf5bcf52d133208309e2581c7ed0a4828283c31fd18cf219dc2bf55e8ac0eb3335f1516af8acdd9c2820b8721ff8e53684d39cb6df0919e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/560d7dd9e17ff46927e670ee0a6c091175fdadf1af9148afefc559a24fcaf2814339bb0af712375fe29f12f4c27c6fce7457d6e58271c8e6f30bab8118a991db"} 04:16:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:16:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:16:33 INFO - Contents: 04:16:33 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0b739bb1add9ed7e7f8e71b92755226539177e959c41959e3c90e066e01a91978f9d16a8ede07aa4c4aca39a872f98183a4ae8f87afc47c34c12ef34d97e0b2f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/02b9a61884cae2c29bf5bcf52d133208309e2581c7ed0a4828283c31fd18cf219dc2bf55e8ac0eb3335f1516af8acdd9c2820b8721ff8e53684d39cb6df0919e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/560d7dd9e17ff46927e670ee0a6c091175fdadf1af9148afefc559a24fcaf2814339bb0af712375fe29f12f4c27c6fce7457d6e58271c8e6f30bab8118a991db"} 04:16:33 INFO - Running post-run listener: copy_logs_to_upload_dir 04:16:33 INFO - Copying logs to upload dir... 04:16:33 INFO - mkdir: /builds/slave/test/build/upload/logs 04:16:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1676.735473 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 56 secs) (at 2016-08-26 04:16:33.821360) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-26 04:16:33.824405) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1472208514.291038-700744219 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0b739bb1add9ed7e7f8e71b92755226539177e959c41959e3c90e066e01a91978f9d16a8ede07aa4c4aca39a872f98183a4ae8f87afc47c34c12ef34d97e0b2f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/02b9a61884cae2c29bf5bcf52d133208309e2581c7ed0a4828283c31fd18cf219dc2bf55e8ac0eb3335f1516af8acdd9c2820b8721ff8e53684d39cb6df0919e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/560d7dd9e17ff46927e670ee0a6c091175fdadf1af9148afefc559a24fcaf2814339bb0af712375fe29f12f4c27c6fce7457d6e58271c8e6f30bab8118a991db"} build_url:https://queue.taskcluster.net/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011494 build_url: 'https://queue.taskcluster.net/v1/task/dJFJFF35TkOvMIMAv_5hsA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0b739bb1add9ed7e7f8e71b92755226539177e959c41959e3c90e066e01a91978f9d16a8ede07aa4c4aca39a872f98183a4ae8f87afc47c34c12ef34d97e0b2f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/02b9a61884cae2c29bf5bcf52d133208309e2581c7ed0a4828283c31fd18cf219dc2bf55e8ac0eb3335f1516af8acdd9c2820b8721ff8e53684d39cb6df0919e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/560d7dd9e17ff46927e670ee0a6c091175fdadf1af9148afefc559a24fcaf2814339bb0af712375fe29f12f4c27c6fce7457d6e58271c8e6f30bab8118a991db"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-26 04:16:33.851170) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 04:16:33.851508) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1472208514.291038-700744219 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004501 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 04:16:33.936489) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-26 04:16:33.937187) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-26 04:16:33.937817) ========= ========= Total master_lag: 0.16 =========