builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-017 starttime: 1474521309.32 results: success (0) buildid: 20160921192859 builduid: 40c8b48a24e14554a4ba930a5ff3651e revision: 35d7d3c4d627a3d88cff51e42eb4b1cbea0133ef ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-21 22:15:09.318554) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-21 22:15:09.337713) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-21 22:15:09.338335) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-21 22:15:09.375958) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 22:15:09.376449) ========= 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=0333a256a5e4f052382dd180000001b1-1474521280.714703-1036006715 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-21 22:15:09-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 38.5M=0s 2016-09-21 22:15:09 (38.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.089719 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 22:15:09.480448) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 22:15:09.480798) ========= 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=0333a256a5e4f052382dd180000001b1-1474521280.714703-1036006715 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017481 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 22:15:09.536416) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-21 22:15:09.536783) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 35d7d3c4d627a3d88cff51e42eb4b1cbea0133ef --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-beta --rev 35d7d3c4d627a3d88cff51e42eb4b1cbea0133ef --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=0333a256a5e4f052382dd180000001b1-1474521280.714703-1036006715 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-21 22:15:09,745 truncating revision to first 12 chars 2016-09-21 22:15:09,745 Setting DEBUG logging. 2016-09-21 22:15:09,745 attempt 1/10 2016-09-21 22:15:09,745 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/35d7d3c4d627?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-21 22:15:10,682 unpacking tar archive at: mozilla-beta-35d7d3c4d627/testing/mozharness/ program finished with exit code 0 elapsedTime=1.317773 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-21 22:15:10.869190) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-21 22:15:10.869542) ========= script_repo_revision: 35d7d3c4d627a3d88cff51e42eb4b1cbea0133ef ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-21 22:15:10.870010) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-21 22:15:10.870366) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-21 22:15:10.883386) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 57 secs) (at 2016-09-21 22:15:10.883707) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] 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=0333a256a5e4f052382dd180000001b1-1474521280.714703-1036006715 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:15:11 INFO - MultiFileLogger online at 20160921 22:15:11 in /builds/slave/test 22:15:11 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 22:15:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:15:11 INFO - {'append_to_log': False, 22:15:11 INFO - 'base_work_dir': '/builds/slave/test', 22:15:11 INFO - 'blob_upload_branch': 'Mozilla-Beta', 22:15:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:15:11 INFO - 'branch': 'Mozilla-Beta', 22:15:11 INFO - 'buildbot_json_path': 'buildprops.json', 22:15:11 INFO - 'config_files': ('talos/linux_config.py',), 22:15:11 INFO - 'default_actions': ('clobber', 22:15:11 INFO - 'read-buildbot-config', 22:15:11 INFO - 'download-and-extract', 22:15:11 INFO - 'populate-webroot', 22:15:11 INFO - 'create-virtualenv', 22:15:11 INFO - 'install', 22:15:11 INFO - 'run-tests'), 22:15:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:15:11 INFO - 'download_minidump_stackwalk': True, 22:15:11 INFO - 'download_symbols': 'ondemand', 22:15:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:15:11 INFO - 'tooltool.py': '/tools/tooltool.py', 22:15:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:15:11 INFO - '/tools/misc-python/virtualenv.py')}, 22:15:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:15:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:15:11 INFO - 'installer_path': 'installer.exe', 22:15:11 INFO - 'log_level': 'info', 22:15:11 INFO - 'log_name': 'talos', 22:15:11 INFO - 'log_to_console': True, 22:15:11 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:15:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:15:11 INFO - 'opt_config_files': (), 22:15:11 INFO - 'pip_index': False, 22:15:11 INFO - 'sps_profile': False, 22:15:11 INFO - 'sps_profile_interval': 0, 22:15:11 INFO - 'suite': 'dromaeojs', 22:15:11 INFO - 'system_bits': '32', 22:15:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:15:11 INFO - 'title': 'talos-linux64-ix-017', 22:15:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:15:11 INFO - 'use_talos_json': True, 22:15:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:15:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:15:11 INFO - 'work_dir': 'build'} 22:15:11 INFO - [mozharness: 2016-09-22 05:15:11.179944Z] Running clobber step. 22:15:11 INFO - Running pre-action listener: _resource_record_pre_action 22:15:11 INFO - Running main action method: clobber 22:15:11 INFO - rmtree: /builds/slave/test/build 22:15:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:15:11 INFO - Running post-action listener: _resource_record_post_action 22:15:11 INFO - [mozharness: 2016-09-22 05:15:11.630587Z] Finished clobber step (success) 22:15:11 INFO - [mozharness: 2016-09-22 05:15:11.630689Z] Running read-buildbot-config step. 22:15:11 INFO - Running pre-action listener: _resource_record_pre_action 22:15:11 INFO - Running main action method: read_buildbot_config 22:15:11 INFO - Using buildbot properties: 22:15:11 INFO - { 22:15:11 INFO - "project": "", 22:15:11 INFO - "product": "firefox", 22:15:11 INFO - "script_repo_revision": "production", 22:15:11 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 22:15:11 INFO - "repository": "", 22:15:11 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 22:15:11 INFO - "buildid": "20160921192859", 22:15:11 INFO - "pgo_build": "True", 22:15:11 INFO - "basedir": "/builds/slave/test", 22:15:11 INFO - "buildnumber": 394, 22:15:11 INFO - "slavename": "talos-linux64-ix-017", 22:15:11 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 22:15:11 INFO - "platform": "ubuntu64_hw", 22:15:11 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 22:15:11 INFO - "branch": "mozilla-beta", 22:15:11 INFO - "repo_path": "releases/mozilla-beta", 22:15:11 INFO - "revision": "35d7d3c4d627a3d88cff51e42eb4b1cbea0133ef", 22:15:11 INFO - "stage_platform": "linux64-pgo", 22:15:11 INFO - "builduid": "40c8b48a24e14554a4ba930a5ff3651e", 22:15:11 INFO - "slavebuilddir": "test" 22:15:11 INFO - } 22:15:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 22:15:11 INFO - Running post-action listener: _resource_record_post_action 22:15:11 INFO - [mozharness: 2016-09-22 05:15:11.633141Z] Finished read-buildbot-config step (success) 22:15:11 INFO - [mozharness: 2016-09-22 05:15:11.633230Z] Running download-and-extract step. 22:15:11 INFO - Running pre-action listener: _resource_record_pre_action 22:15:11 INFO - Running main action method: download_and_extract 22:15:11 INFO - mkdir: /builds/slave/test/build/tests 22:15:11 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')]} 22:15:11 INFO - https://queue.taskcluster.net/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 22:15:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 22:15:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 22:15:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 22:15:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 22:15:11 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 22:15:11 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 22:15:11 INFO - retry: Failed, sleeping 30 seconds before retrying 22:15:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #2 22:15:41 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 22:15:41 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 22:15:41 INFO - retry: Failed, sleeping 60 seconds before retrying 22:16:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #3 22:16:41 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 22:16:41 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 22:16:41 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json! 22:16:41 INFO - Caught exception: HTTP Error 404: Not Found 22:16:41 INFO - Caught exception: HTTP Error 404: Not Found 22:16:41 INFO - Caught exception: HTTP Error 404: Not Found 22:16:41 INFO - trying https://queue.taskcluster.net/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 22:16:41 INFO - Downloading https://queue.taskcluster.net/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 22:16:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 22:16:42 INFO - Downloaded 1327 bytes. 22:16:42 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 22:16:42 INFO - Using the following test package requirements: 22:16:42 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 22:16:42 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 22:16:42 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 22:16:42 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 22:16:42 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 22:16:42 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 22:16:42 INFO - u'jsshell-linux-x86_64.zip'], 22:16:42 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 22:16:42 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 22:16:42 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 22:16:42 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 22:16:42 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 22:16:42 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 22:16:42 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 22:16:42 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 22:16:42 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 22:16:42 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 22:16:42 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 22:16:42 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:16:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 22:16:42 INFO - https://queue.taskcluster.net/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:16:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 22:16:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 22:16:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 22:16:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:16:42 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 22:16:42 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 22:16:42 INFO - retry: Failed, sleeping 30 seconds before retrying 22:17:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #2 22:17:12 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 22:17:12 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 22:17:12 INFO - retry: Failed, sleeping 60 seconds before retrying 22:18:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #3 22:18:12 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 22:18:12 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 22:18:12 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip! 22:18:12 INFO - Caught exception: HTTP Error 404: Not Found 22:18:12 INFO - Caught exception: HTTP Error 404: Not Found 22:18:12 INFO - Caught exception: HTTP Error 404: Not Found 22:18:12 INFO - trying https://queue.taskcluster.net/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 22:18:12 INFO - Downloading https://queue.taskcluster.net/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 22:18:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:18:14 INFO - Downloaded 21839272 bytes. 22:18:14 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:18:14 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:18:15 INFO - Return code: 0 22:18:15 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 22:18:15 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')]} 22:18:15 INFO - https://queue.taskcluster.net/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:18:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 22:18:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 22:18:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 22:18:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:18:15 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 22:18:15 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 22:18:15 INFO - retry: Failed, sleeping 30 seconds before retrying 22:18:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #2 22:18:45 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 22:18:45 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 22:18:45 INFO - retry: Failed, sleeping 60 seconds before retrying 22:19:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #3 22:19:45 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 22:19:45 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 22:19:45 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip! 22:19:45 INFO - Caught exception: HTTP Error 404: Not Found 22:19:45 INFO - Caught exception: HTTP Error 404: Not Found 22:19:45 INFO - Caught exception: HTTP Error 404: Not Found 22:19:45 INFO - trying https://queue.taskcluster.net/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 22:19:45 INFO - Downloading https://queue.taskcluster.net/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 22:19:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:19:46 INFO - Downloaded 21839272 bytes. 22:19:46 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:19:46 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:19:48 INFO - Return code: 0 22:19:48 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')]} 22:19:48 INFO - https://queue.taskcluster.net/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:19:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 22:19:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 22:19:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 22:19:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 22:19:48 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 22:19:48 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 22:19:48 INFO - retry: Failed, sleeping 30 seconds before retrying 22:20:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 22:20:18 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 22:20:18 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 22:20:18 INFO - retry: Failed, sleeping 60 seconds before retrying 22:21:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 22:21:18 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 22:21:18 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 22:21:18 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip! 22:21:18 INFO - Caught exception: HTTP Error 404: Not Found 22:21:18 INFO - Caught exception: HTTP Error 404: Not Found 22:21:18 INFO - Caught exception: HTTP Error 404: Not Found 22:21:18 INFO - trying https://queue.taskcluster.net/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 22:21:18 INFO - Downloading https://queue.taskcluster.net/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 22:21:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 22:21:19 INFO - Downloaded 14413740 bytes. 22:21:19 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:21:19 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 22:21:20 INFO - Return code: 0 22:21:20 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')]} 22:21:20 INFO - https://queue.taskcluster.net/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:21:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 22:21:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 22:21:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 22:21:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 22:21:20 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 22:21:20 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 22:21:20 INFO - retry: Failed, sleeping 30 seconds before retrying 22:21:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #2 22:21:50 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 22:21:50 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 22:21:50 INFO - retry: Failed, sleeping 60 seconds before retrying 22:22:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #3 22:22:50 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 22:22:50 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 22:22:50 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2! 22:22:50 INFO - Caught exception: HTTP Error 404: Not Found 22:22:50 INFO - Caught exception: HTTP Error 404: Not Found 22:22:50 INFO - Caught exception: HTTP Error 404: Not Found 22:22:50 INFO - trying https://queue.taskcluster.net/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 22:22:50 INFO - Downloading https://queue.taskcluster.net/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 22:22:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 22:22:51 INFO - Downloaded 56779083 bytes. 22:22:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 22:22:51 INFO - mkdir: /builds/slave/test/properties 22:22:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:22:51 INFO - Writing to file /builds/slave/test/properties/build_url 22:22:51 INFO - Contents: 22:22:51 INFO - build_url:https://queue.taskcluster.net/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 22:22:51 INFO - Running post-action listener: _resource_record_post_action 22:22:51 INFO - Running post-action listener: set_extra_try_arguments 22:22:51 INFO - [mozharness: 2016-09-22 05:22:51.997315Z] Finished download-and-extract step (success) 22:22:51 INFO - [mozharness: 2016-09-22 05:22:51.997550Z] Running populate-webroot step. 22:22:51 INFO - Running pre-action listener: _resource_record_pre_action 22:22:51 INFO - Running main action method: populate_webroot 22:22:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:22:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:22:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:22:52 INFO - '--tppagecycles', 22:22:52 INFO - '7'], 22:22:52 INFO - 'tests': ['tsvgm']}}, 22:22:52 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:22:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:22:52 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:22:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:22:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:22:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:22:52 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:22:52 INFO - 'talos_options': ['--disable-e10s'], 22:22:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:22:52 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:22:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:22:52 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:22:52 INFO - 'talos_options': ['--disable-e10s'], 22:22:52 INFO - 'tests': ['damp', 'tps']}, 22:22:52 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:22:52 INFO - 'tests': ['damp', 'tps']}, 22:22:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:22:52 INFO - 'tests': ['dromaeo_dom']}, 22:22:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:22:52 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:22:52 INFO - 'tests': ['basic_compositor_video']}, 22:22:52 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:22:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:22:52 INFO - 'tests': ['a11yr', 22:22:52 INFO - 'ts_paint', 22:22:52 INFO - 'tpaint', 22:22:52 INFO - 'sessionrestore', 22:22:52 INFO - 'sessionrestore_no_auto_restore', 22:22:52 INFO - 'tabpaint']}, 22:22:52 INFO - 'other-e10s': {'tests': ['a11yr', 22:22:52 INFO - 'ts_paint', 22:22:52 INFO - 'tpaint', 22:22:52 INFO - 'sessionrestore', 22:22:52 INFO - 'sessionrestore_no_auto_restore', 22:22:52 INFO - 'tabpaint']}, 22:22:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:22:52 INFO - 'ts_paint', 22:22:52 INFO - 'tpaint', 22:22:52 INFO - 'sessionrestore', 22:22:52 INFO - 'sessionrestore_no_auto_restore', 22:22:52 INFO - 'tabpaint']}, 22:22:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:22:52 INFO - 'ts_paint', 22:22:52 INFO - 'tpaint', 22:22:52 INFO - 'sessionrestore', 22:22:52 INFO - 'sessionrestore_no_auto_restore', 22:22:52 INFO - 'tabpaint']}, 22:22:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:22:52 INFO - 'tests': ['a11yr', 22:22:52 INFO - 'ts_paint', 22:22:52 INFO - 'tpaint', 22:22:52 INFO - 'sessionrestore', 22:22:52 INFO - 'sessionrestore_no_auto_restore', 22:22:52 INFO - 'tabpaint']}, 22:22:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:22:52 INFO - 'tests': ['a11yr', 22:22:52 INFO - 'ts_paint', 22:22:52 INFO - 'tpaint', 22:22:52 INFO - 'sessionrestore', 22:22:52 INFO - 'sessionrestore_no_auto_restore', 22:22:52 INFO - 'tabpaint']}, 22:22:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:22:52 INFO - 'tests': ['tsvgx', 22:22:52 INFO - 'tsvgr_opacity', 22:22:52 INFO - 'tart', 22:22:52 INFO - 'tscrollx', 22:22:52 INFO - 'cart']}, 22:22:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:22:52 INFO - 'tsvgr_opacity', 22:22:52 INFO - 'tart', 22:22:52 INFO - 'tscrollx', 22:22:52 INFO - 'cart']}, 22:22:52 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:22:52 INFO - 'talos_options': ['--disable-e10s'], 22:22:52 INFO - 'tests': ['tp5o']}, 22:22:52 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:22:52 INFO - 'tests': ['tp5o']}, 22:22:52 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:22:52 INFO - 'talos_options': ['--disable-e10s', 22:22:52 INFO - '--xperf_path', 22:22:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:22:52 INFO - 'tests': ['tp5n']}, 22:22:52 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:22:52 INFO - 'talos_options': ['--xperf_path', 22:22:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:22:52 INFO - 'tests': ['tp5n']}}, 22:22:52 INFO - 'talos.zip': {'path': '', 22:22:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:22:52 INFO - Running post-action listener: _resource_record_post_action 22:22:52 INFO - [mozharness: 2016-09-22 05:22:52.019679Z] Finished populate-webroot step (success) 22:22:52 INFO - [mozharness: 2016-09-22 05:22:52.019769Z] Running create-virtualenv step. 22:22:52 INFO - Running pre-action listener: _resource_record_pre_action 22:22:52 INFO - Running main action method: create_virtualenv 22:22:52 INFO - Creating virtualenv /builds/slave/test/build/venv 22:22:52 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 22:22:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:22:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:22:52 INFO - Using real prefix '/usr' 22:22:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:22:52 INFO - Installing distribute.............................................................................................................................................................................................done. 22:22:54 INFO - Installing pip.................done. 22:22:54 INFO - Return code: 0 22:22:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:22: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')]} 22:22:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:22:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:22: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')]} 22:22:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:22:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:22:54 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 0x2a8b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bee8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d4c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d4a3b0>, '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 0x2d4b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d4b740>, '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': '0333a256a5e4f052382dd180000001b1-1474521280.714703-1036006715', '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 22:22:54 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 22:22:54 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 22:22:54 INFO - Using env: {'DISPLAY': ':0', 22:22:54 INFO - 'HOME': '/home/cltbld', 22:22:54 INFO - 'LANG': 'en_US.UTF-8', 22:22:54 INFO - 'LANGUAGE': 'en_US:en', 22:22:54 INFO - 'LOGNAME': 'cltbld', 22:22:54 INFO - 'MAIL': '/var/mail/cltbld', 22:22:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:22:54 INFO - 'MOZ_NO_REMOTE': '1', 22:22:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:22:54 INFO - 'NO_EM_RESTART': '1', 22:22:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:22:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:22:54 INFO - 'PWD': '/builds/slave/test', 22:22:54 INFO - 'SHELL': '/bin/bash', 22:22:54 INFO - 'SHLVL': '1', 22:22:54 INFO - 'TERM': 'linux', 22:22:54 INFO - 'TMOUT': '86400', 22:22:54 INFO - 'USER': 'cltbld', 22:22:54 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1474521280.714703-1036006715', 22:22:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:22:54 INFO - '_': '/tools/buildbot/bin/python'} 22:22:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:22:54 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 22:22:54 INFO - Cleaning up... 22:22:54 INFO - Return code: 0 22:22:54 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:22: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')]} 22:22:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:22:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:22: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')]} 22:22:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:22:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:22:54 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 0x2a8b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bee8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d4c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d4a3b0>, '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 0x2d4b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d4b740>, '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': '0333a256a5e4f052382dd180000001b1-1474521280.714703-1036006715', '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 22:22:54 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 22:22:54 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 22:22:54 INFO - Using env: {'DISPLAY': ':0', 22:22:54 INFO - 'HOME': '/home/cltbld', 22:22:54 INFO - 'LANG': 'en_US.UTF-8', 22:22:54 INFO - 'LANGUAGE': 'en_US:en', 22:22:54 INFO - 'LOGNAME': 'cltbld', 22:22:54 INFO - 'MAIL': '/var/mail/cltbld', 22:22:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:22:54 INFO - 'MOZ_NO_REMOTE': '1', 22:22:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:22:54 INFO - 'NO_EM_RESTART': '1', 22:22:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:22:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:22:54 INFO - 'PWD': '/builds/slave/test', 22:22:54 INFO - 'SHELL': '/bin/bash', 22:22:54 INFO - 'SHLVL': '1', 22:22:54 INFO - 'TERM': 'linux', 22:22:54 INFO - 'TMOUT': '86400', 22:22:54 INFO - 'USER': 'cltbld', 22:22:54 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1474521280.714703-1036006715', 22:22:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:22:54 INFO - '_': '/tools/buildbot/bin/python'} 22:22:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:22:55 INFO - Downloading/unpacking psutil>=3.1.1 22:22:55 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 22:22:55 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 22:22:55 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 22:22:55 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 22:22:55 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 22:22:55 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 22:22:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:22:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:22:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:22:56 INFO - Installing collected packages: psutil 22:22:56 INFO - Running setup.py install for psutil 22:22:57 INFO - building 'psutil._psutil_linux' extension 22:22:57 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 22:22:57 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 22:22:57 INFO - building 'psutil._psutil_posix' extension 22:22:57 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 22:22:57 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 22:22:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:22:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:22:57 INFO - Successfully installed psutil 22:22:57 INFO - Cleaning up... 22:22:57 INFO - Return code: 0 22:22:57 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:22:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 22:22:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:22:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:22:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 22:22:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:22:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:22:57 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 0x2a8b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bee8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d4c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d4a3b0>, '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 0x2d4b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d4b740>, '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': '0333a256a5e4f052382dd180000001b1-1474521280.714703-1036006715', '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 22:22:57 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 22:22:57 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 22:22:57 INFO - Using env: {'DISPLAY': ':0', 22:22:57 INFO - 'HOME': '/home/cltbld', 22:22:57 INFO - 'LANG': 'en_US.UTF-8', 22:22:57 INFO - 'LANGUAGE': 'en_US:en', 22:22:57 INFO - 'LOGNAME': 'cltbld', 22:22:57 INFO - 'MAIL': '/var/mail/cltbld', 22:22:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:22:57 INFO - 'MOZ_NO_REMOTE': '1', 22:22:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:22:57 INFO - 'NO_EM_RESTART': '1', 22:22:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:22:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:22:57 INFO - 'PWD': '/builds/slave/test', 22:22:57 INFO - 'SHELL': '/bin/bash', 22:22:57 INFO - 'SHLVL': '1', 22:22:57 INFO - 'TERM': 'linux', 22:22:57 INFO - 'TMOUT': '86400', 22:22:57 INFO - 'USER': 'cltbld', 22:22:57 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1474521280.714703-1036006715', 22:22:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:22:57 INFO - '_': '/tools/buildbot/bin/python'} 22:22:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:22:57 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:22:57 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 22:22:57 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 22:22:57 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 22:22:57 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 22:22:57 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 22:22:57 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 22:22:59 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:22:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:22:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:22:59 INFO - Installing collected packages: mozsystemmonitor 22:22:59 INFO - Running setup.py install for mozsystemmonitor 22:22:59 INFO - Successfully installed mozsystemmonitor 22:22:59 INFO - Cleaning up... 22:22:59 INFO - Return code: 0 22:22:59 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:22:59 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')]} 22:22:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:22:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:22:59 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')]} 22:22:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:22:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:22:59 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 0x2a8b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bee8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d4c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d4a3b0>, '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 0x2d4b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d4b740>, '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': '0333a256a5e4f052382dd180000001b1-1474521280.714703-1036006715', '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 22:22:59 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 22:22:59 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 22:22:59 INFO - Using env: {'DISPLAY': ':0', 22:22:59 INFO - 'HOME': '/home/cltbld', 22:22:59 INFO - 'LANG': 'en_US.UTF-8', 22:22:59 INFO - 'LANGUAGE': 'en_US:en', 22:22:59 INFO - 'LOGNAME': 'cltbld', 22:22:59 INFO - 'MAIL': '/var/mail/cltbld', 22:22:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:22:59 INFO - 'MOZ_NO_REMOTE': '1', 22:22:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:22:59 INFO - 'NO_EM_RESTART': '1', 22:22:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:22:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:22:59 INFO - 'PWD': '/builds/slave/test', 22:22:59 INFO - 'SHELL': '/bin/bash', 22:22:59 INFO - 'SHLVL': '1', 22:22:59 INFO - 'TERM': 'linux', 22:22:59 INFO - 'TMOUT': '86400', 22:22:59 INFO - 'USER': 'cltbld', 22:22:59 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1474521280.714703-1036006715', 22:22:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:22:59 INFO - '_': '/tools/buildbot/bin/python'} 22:23:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:23:00 INFO - Downloading/unpacking jsonschema==2.5.1 22:23:00 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 22:23:00 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 22:23:00 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 22:23:00 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 22:23:00 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 22:23:00 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 22:23:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:23:01 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:23:01 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 22:23:01 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 22:23:01 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 22:23:01 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 22:23:01 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 22:23:01 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 22:23:02 INFO - Downloading functools32-3.2.3-2.tar.gz 22:23:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:23:02 INFO - warning: no files found matching '*.txt' 22:23:02 INFO - no previously-included directories found matching 'build' 22:23:02 INFO - no previously-included directories found matching 'dist' 22:23:02 INFO - no previously-included directories found matching '.git*' 22:23:02 INFO - Installing collected packages: jsonschema, functools32 22:23:02 INFO - Running setup.py install for functools32 22:23:02 INFO - warning: no files found matching '*.txt' 22:23:02 INFO - no previously-included directories found matching 'build' 22:23:02 INFO - no previously-included directories found matching 'dist' 22:23:02 INFO - no previously-included directories found matching '.git*' 22:23:02 INFO - Successfully installed jsonschema functools32 22:23:02 INFO - Cleaning up... 22:23:02 INFO - Return code: 0 22:23:02 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:23:02 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')]} 22:23:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:23:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:02 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')]} 22:23:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:23:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:02 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 0x2a8b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bee8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d4c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d4a3b0>, '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 0x2d4b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d4b740>, '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': '0333a256a5e4f052382dd180000001b1-1474521280.714703-1036006715', '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 22:23:02 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 22:23:02 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 22:23:02 INFO - Using env: {'DISPLAY': ':0', 22:23:02 INFO - 'HOME': '/home/cltbld', 22:23:02 INFO - 'LANG': 'en_US.UTF-8', 22:23:02 INFO - 'LANGUAGE': 'en_US:en', 22:23:02 INFO - 'LOGNAME': 'cltbld', 22:23:02 INFO - 'MAIL': '/var/mail/cltbld', 22:23:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:23:02 INFO - 'MOZ_NO_REMOTE': '1', 22:23:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:23:02 INFO - 'NO_EM_RESTART': '1', 22:23:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:23:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:23:02 INFO - 'PWD': '/builds/slave/test', 22:23:02 INFO - 'SHELL': '/bin/bash', 22:23:02 INFO - 'SHLVL': '1', 22:23:02 INFO - 'TERM': 'linux', 22:23:02 INFO - 'TMOUT': '86400', 22:23:02 INFO - 'USER': 'cltbld', 22:23:02 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1474521280.714703-1036006715', 22:23:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:23:02 INFO - '_': '/tools/buildbot/bin/python'} 22:23:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:23:02 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:23:02 INFO - Cleaning up... 22:23:02 INFO - Return code: 0 22:23:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:23:02 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')]} 22:23:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:23:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:02 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')]} 22:23:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:23:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:02 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 0x2a8b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bee8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d4c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d4a3b0>, '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 0x2d4b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d4b740>, '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': '0333a256a5e4f052382dd180000001b1-1474521280.714703-1036006715', '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 22:23:02 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 22:23:02 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 22:23:02 INFO - Using env: {'DISPLAY': ':0', 22:23:02 INFO - 'HOME': '/home/cltbld', 22:23:02 INFO - 'LANG': 'en_US.UTF-8', 22:23:02 INFO - 'LANGUAGE': 'en_US:en', 22:23:02 INFO - 'LOGNAME': 'cltbld', 22:23:02 INFO - 'MAIL': '/var/mail/cltbld', 22:23:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:23:02 INFO - 'MOZ_NO_REMOTE': '1', 22:23:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:23:02 INFO - 'NO_EM_RESTART': '1', 22:23:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:23:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:23:02 INFO - 'PWD': '/builds/slave/test', 22:23:02 INFO - 'SHELL': '/bin/bash', 22:23:02 INFO - 'SHLVL': '1', 22:23:02 INFO - 'TERM': 'linux', 22:23:02 INFO - 'TMOUT': '86400', 22:23:02 INFO - 'USER': 'cltbld', 22:23:02 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1474521280.714703-1036006715', 22:23:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:23:02 INFO - '_': '/tools/buildbot/bin/python'} 22:23:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:23:02 INFO - Downloading/unpacking blobuploader==1.2.4 22:23:02 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 22:23:02 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 22:23:02 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 22:23:02 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 22:23:02 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 22:23:02 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 22:23:04 INFO - Downloading blobuploader-1.2.4.tar.gz 22:23:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:23:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:23:04 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 22:23:04 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 22:23:04 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 22:23:04 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 22:23:04 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 22:23:04 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 22:23:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:23:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:23:05 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 22:23:05 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 22:23:05 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 22:23:05 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 22:23:05 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 22:23:05 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 22:23:05 INFO - Downloading docopt-0.6.1.tar.gz 22:23:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:23:05 INFO - Installing collected packages: blobuploader, requests, docopt 22:23:05 INFO - Running setup.py install for blobuploader 22:23:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:23:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:23:05 INFO - Running setup.py install for requests 22:23:05 INFO - Running setup.py install for docopt 22:23:06 INFO - Successfully installed blobuploader requests docopt 22:23:06 INFO - Cleaning up... 22:23:06 INFO - Return code: 0 22:23:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:23:06 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')]} 22:23:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:23:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:06 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')]} 22:23:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:23:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:06 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 0x2a8b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bee8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d4c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d4a3b0>, '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 0x2d4b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d4b740>, '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': '0333a256a5e4f052382dd180000001b1-1474521280.714703-1036006715', '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 22:23:06 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 22:23:06 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 22:23:06 INFO - Using env: {'DISPLAY': ':0', 22:23:06 INFO - 'HOME': '/home/cltbld', 22:23:06 INFO - 'LANG': 'en_US.UTF-8', 22:23:06 INFO - 'LANGUAGE': 'en_US:en', 22:23:06 INFO - 'LOGNAME': 'cltbld', 22:23:06 INFO - 'MAIL': '/var/mail/cltbld', 22:23:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:23:06 INFO - 'MOZ_NO_REMOTE': '1', 22:23:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:23:06 INFO - 'NO_EM_RESTART': '1', 22:23:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:23:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:23:06 INFO - 'PWD': '/builds/slave/test', 22:23:06 INFO - 'SHELL': '/bin/bash', 22:23:06 INFO - 'SHLVL': '1', 22:23:06 INFO - 'TERM': 'linux', 22:23:06 INFO - 'TMOUT': '86400', 22:23:06 INFO - 'USER': 'cltbld', 22:23:06 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1474521280.714703-1036006715', 22:23:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:23:06 INFO - '_': '/tools/buildbot/bin/python'} 22:23:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:23:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:23:06 INFO - Running setup.py (path:/tmp/pip-wdbes1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:23:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:23:06 INFO - Running setup.py (path:/tmp/pip-lfjoiS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:23:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:23:06 INFO - Running setup.py (path:/tmp/pip-GUnicp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:23:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:23:06 INFO - Running setup.py (path:/tmp/pip-aOogkR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:23:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:23:06 INFO - Running setup.py (path:/tmp/pip-jYkMmj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:23:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:23:06 INFO - Running setup.py (path:/tmp/pip-mjgJUQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:23:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:23:06 INFO - Running setup.py (path:/tmp/pip-YDOEn9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:23:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:23:06 INFO - Running setup.py (path:/tmp/pip-zI5iG1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:23:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:23:06 INFO - Running setup.py (path:/tmp/pip-YoNgMZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:23:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:23:06 INFO - Running setup.py (path:/tmp/pip-tRGGxi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:23:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:23:07 INFO - Running setup.py (path:/tmp/pip-9FbhJe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:23:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:23:07 INFO - Running setup.py (path:/tmp/pip-ZQ4eJJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:23:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:23:07 INFO - Running setup.py (path:/tmp/pip-1YgjOq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:23:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:23:07 INFO - Running setup.py (path:/tmp/pip-TOEMqY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:23:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:23:07 INFO - Running setup.py (path:/tmp/pip-xthzeV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:23:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:23:07 INFO - Running setup.py (path:/tmp/pip-4KTASV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:23:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:23:07 INFO - Running setup.py (path:/tmp/pip-2cI_aW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:23:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:23:07 INFO - Running setup.py install for manifestparser 22:23:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:23:07 INFO - Running setup.py install for mozcrash 22:23:07 INFO - Running setup.py install for mozdebug 22:23:07 INFO - Running setup.py install for mozdevice 22:23:07 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:23:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:23:07 INFO - Running setup.py install for mozfile 22:23:08 INFO - Running setup.py install for mozhttpd 22:23:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:23:08 INFO - Running setup.py install for mozinfo 22:23:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:23:08 INFO - Running setup.py install for mozInstall 22:23:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:23:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:23:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:23:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:23:08 INFO - Running setup.py install for mozleak 22:23:08 INFO - Running setup.py install for mozlog 22:23:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:23:08 INFO - Running setup.py install for moznetwork 22:23:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:23:08 INFO - Running setup.py install for mozprocess 22:23:08 INFO - Running setup.py install for mozprofile 22:23:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:23:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:23:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:23:08 INFO - Running setup.py install for mozrunner 22:23:08 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:23:08 INFO - Running setup.py install for mozscreenshot 22:23:09 INFO - Running setup.py install for moztest 22:23:09 INFO - Running setup.py install for mozversion 22:23:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:23:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:23:09 INFO - Cleaning up... 22:23:09 INFO - Return code: 0 22:23:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:23:09 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')]} 22:23:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:23:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:09 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')]} 22:23:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:23:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:09 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 0x2a8b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bee8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d4c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d4a3b0>, '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 0x2d4b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d4b740>, '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': '0333a256a5e4f052382dd180000001b1-1474521280.714703-1036006715', '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 22:23:09 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 22:23:09 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 22:23:09 INFO - Using env: {'DISPLAY': ':0', 22:23:09 INFO - 'HOME': '/home/cltbld', 22:23:09 INFO - 'LANG': 'en_US.UTF-8', 22:23:09 INFO - 'LANGUAGE': 'en_US:en', 22:23:09 INFO - 'LOGNAME': 'cltbld', 22:23:09 INFO - 'MAIL': '/var/mail/cltbld', 22:23:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:23:09 INFO - 'MOZ_NO_REMOTE': '1', 22:23:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:23:09 INFO - 'NO_EM_RESTART': '1', 22:23:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:23:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:23:09 INFO - 'PWD': '/builds/slave/test', 22:23:09 INFO - 'SHELL': '/bin/bash', 22:23:09 INFO - 'SHLVL': '1', 22:23:09 INFO - 'TERM': 'linux', 22:23:09 INFO - 'TMOUT': '86400', 22:23:09 INFO - 'USER': 'cltbld', 22:23:09 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1474521280.714703-1036006715', 22:23:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:23:09 INFO - '_': '/tools/buildbot/bin/python'} 22:23:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:23:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:23:09 INFO - Running setup.py (path:/tmp/pip-mH5NJb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:23:09 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)) 22:23:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:23:09 INFO - Running setup.py (path:/tmp/pip-RX0nsC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:23:09 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)) 22:23:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:23:09 INFO - Running setup.py (path:/tmp/pip-Zqqb6I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:23:09 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)) 22:23:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:23:09 INFO - Running setup.py (path:/tmp/pip-ezmLNm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:23:09 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)) 22:23:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:23:09 INFO - Running setup.py (path:/tmp/pip-faK5U4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:23:09 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)) 22:23:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:23:09 INFO - Running setup.py (path:/tmp/pip-MGkYcA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:23:09 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)) 22:23:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:23:09 INFO - Running setup.py (path:/tmp/pip-JsgnKM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:23:10 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)) 22:23:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:23:10 INFO - Running setup.py (path:/tmp/pip-Iws1fz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:23:10 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)) 22:23:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:23:10 INFO - Running setup.py (path:/tmp/pip-Yt1e0F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:23:10 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)) 22:23:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:23:10 INFO - Running setup.py (path:/tmp/pip-u7hPFs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:23:10 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)) 22:23:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:23:10 INFO - Running setup.py (path:/tmp/pip-_hdT82-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:23:10 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)) 22:23:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:23:10 INFO - Running setup.py (path:/tmp/pip-hiaNo5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:23:10 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)) 22:23:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:23:10 INFO - Running setup.py (path:/tmp/pip-0wjT80-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:23:10 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)) 22:23:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:23:10 INFO - Running setup.py (path:/tmp/pip-v9laeG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:23:10 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)) 22:23:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:23:10 INFO - Running setup.py (path:/tmp/pip-bB_ZEs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:23:10 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)) 22:23:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:23:10 INFO - Running setup.py (path:/tmp/pip-Y2AaCK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:23:10 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)) 22:23:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:23:10 INFO - Running setup.py (path:/tmp/pip-KTCK78-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:23:10 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)) 22:23:10 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)) 22:23:10 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)) 22:23:10 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)) 22:23:10 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)) 22:23:10 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)) 22:23:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:23:10 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 22:23:10 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 22:23:10 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 22:23:10 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 22:23:10 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 22:23:10 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 22:23:12 INFO - Downloading blessings-1.6.tar.gz 22:23:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:23:12 INFO - Installing collected packages: blessings 22:23:12 INFO - Running setup.py install for blessings 22:23:12 INFO - Successfully installed blessings 22:23:12 INFO - Cleaning up... 22:23:12 INFO - Return code: 0 22:23:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:23:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:23:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:23:13 INFO - Reading from file tmpfile_stdout 22:23:13 INFO - Current package versions: 22:23:13 INFO - argparse == 1.2.1 22:23:13 INFO - blessings == 1.6 22:23:13 INFO - blobuploader == 1.2.4 22:23:13 INFO - docopt == 0.6.1 22:23:13 INFO - functools32 == 3.2.3-2 22:23:13 INFO - jsonschema == 2.5.1 22:23:13 INFO - manifestparser == 1.1 22:23:13 INFO - mozInstall == 1.12 22:23:13 INFO - mozcrash == 0.17 22:23:13 INFO - mozdebug == 0.1 22:23:13 INFO - mozdevice == 0.48 22:23:13 INFO - mozfile == 1.2 22:23:13 INFO - mozhttpd == 0.7 22:23:13 INFO - mozinfo == 0.9 22:23:13 INFO - mozleak == 0.1 22:23:13 INFO - mozlog == 3.3 22:23:13 INFO - moznetwork == 0.27 22:23:13 INFO - mozprocess == 0.23 22:23:13 INFO - mozprofile == 0.28 22:23:13 INFO - mozrunner == 6.12 22:23:13 INFO - mozscreenshot == 0.1 22:23:13 INFO - mozsystemmonitor == 0.3 22:23:13 INFO - moztest == 0.7 22:23:13 INFO - mozversion == 1.4 22:23:13 INFO - psutil == 3.1.1 22:23:13 INFO - requests == 1.2.3 22:23:13 INFO - wsgiref == 0.1.2 22:23:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:23: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')]} 22:23:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:23:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23: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')]} 22:23:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:23:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:13 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 0x2a8b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bee8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d4c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d4a3b0>, '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 0x2d4b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d4b740>, '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': '0333a256a5e4f052382dd180000001b1-1474521280.714703-1036006715', '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 22:23:13 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 22:23:13 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 22:23:13 INFO - Using env: {'DISPLAY': ':0', 22:23:13 INFO - 'HOME': '/home/cltbld', 22:23:13 INFO - 'LANG': 'en_US.UTF-8', 22:23:13 INFO - 'LANGUAGE': 'en_US:en', 22:23:13 INFO - 'LOGNAME': 'cltbld', 22:23:13 INFO - 'MAIL': '/var/mail/cltbld', 22:23:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:23:13 INFO - 'MOZ_NO_REMOTE': '1', 22:23:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:23:13 INFO - 'NO_EM_RESTART': '1', 22:23:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:23:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:23:13 INFO - 'PWD': '/builds/slave/test', 22:23:13 INFO - 'SHELL': '/bin/bash', 22:23:13 INFO - 'SHLVL': '1', 22:23:13 INFO - 'TERM': 'linux', 22:23:13 INFO - 'TMOUT': '86400', 22:23:13 INFO - 'USER': 'cltbld', 22:23:13 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1474521280.714703-1036006715', 22:23:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:23:13 INFO - '_': '/tools/buildbot/bin/python'} 22:23:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:23:13 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)) 22:23:13 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)) 22:23:13 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)) 22:23:13 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)) 22:23:13 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)) 22:23:13 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)) 22:23:13 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)) 22:23:13 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)) 22:23:13 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)) 22:23:13 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)) 22:23:13 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)) 22:23:13 INFO - Cleaning up... 22:23:13 INFO - Return code: 0 22:23:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:23: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')]} 22:23:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:23:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23: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')]} 22:23:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:23:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:13 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 0x2a8b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bee8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d4c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d4a3b0>, '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 0x2d4b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d4b740>, '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': '0333a256a5e4f052382dd180000001b1-1474521280.714703-1036006715', '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 22:23:13 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 22:23:13 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 22:23:13 INFO - Using env: {'DISPLAY': ':0', 22:23:13 INFO - 'HOME': '/home/cltbld', 22:23:13 INFO - 'LANG': 'en_US.UTF-8', 22:23:13 INFO - 'LANGUAGE': 'en_US:en', 22:23:13 INFO - 'LOGNAME': 'cltbld', 22:23:13 INFO - 'MAIL': '/var/mail/cltbld', 22:23:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:23:13 INFO - 'MOZ_NO_REMOTE': '1', 22:23:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:23:13 INFO - 'NO_EM_RESTART': '1', 22:23:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:23:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:23:13 INFO - 'PWD': '/builds/slave/test', 22:23:13 INFO - 'SHELL': '/bin/bash', 22:23:13 INFO - 'SHLVL': '1', 22:23:13 INFO - 'TERM': 'linux', 22:23:13 INFO - 'TMOUT': '86400', 22:23:13 INFO - 'USER': 'cltbld', 22:23:13 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1474521280.714703-1036006715', 22:23:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:23:13 INFO - '_': '/tools/buildbot/bin/python'} 22:23:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:23:13 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:23:13 INFO - Cleaning up... 22:23:13 INFO - Return code: 0 22:23:13 INFO - Running post-action listener: _resource_record_post_action 22:23:13 INFO - Running post-action listener: _start_resource_monitoring 22:23:13 INFO - Starting resource monitoring. 22:23:13 INFO - [mozharness: 2016-09-22 05:23:13.542697Z] Finished create-virtualenv step (success) 22:23:13 INFO - [mozharness: 2016-09-22 05:23:13.543158Z] Running install step. 22:23:13 INFO - Running pre-action listener: _resource_record_pre_action 22:23:13 INFO - Running main action method: install 22:23:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:23:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:23:13 INFO - Reading from file tmpfile_stdout 22:23:13 INFO - Detecting whether we're running mozinstall >=1.0... 22:23:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:23:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:23:13 INFO - Reading from file tmpfile_stdout 22:23:13 INFO - Output received: 22:23:13 INFO - Usage: mozinstall [options] installer 22:23:13 INFO - Options: 22:23:13 INFO - -h, --help show this help message and exit 22:23:13 INFO - -d DEST, --destination=DEST 22:23:13 INFO - Directory to install application into. [default: 22:23:13 INFO - "/builds/slave/test"] 22:23:13 INFO - --app=APP Application being installed. [default: firefox] 22:23:13 INFO - mkdir: /builds/slave/test/build/application 22:23:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 22:23:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 22:23:27 INFO - Reading from file tmpfile_stdout 22:23:27 INFO - Output received: 22:23:27 INFO - /builds/slave/test/build/application/firefox/firefox 22:23:27 INFO - Running post-action listener: _resource_record_post_action 22:23:27 INFO - [mozharness: 2016-09-22 05:23:27.877670Z] Finished install step (success) 22:23:27 INFO - [mozharness: 2016-09-22 05:23:27.877951Z] Running run-tests step. 22:23:27 INFO - Running pre-action listener: _resource_record_pre_action 22:23:27 INFO - Running main action method: run_tests 22:23:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:23:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:23:27 INFO - Python 2.7.3 22:23:27 INFO - Return code: 0 22:23:27 INFO - grabbing minidump binary from tooltool 22:23:27 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')]} 22:23:27 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 0x2d4a3b0>, '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 0x2d4b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d4b740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:23:27 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 22:23:27 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 22:23:27 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 22:23:27 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:23:27 INFO - Return code: 0 22:23:27 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:23:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:23:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:23:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:23:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:23:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-017', '--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 22:23:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-017 --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 22:23:27 INFO - Using env: {'DISPLAY': ':0', 22:23:27 INFO - 'HOME': '/home/cltbld', 22:23:27 INFO - 'LANG': 'en_US.UTF-8', 22:23:27 INFO - 'LANGUAGE': 'en_US:en', 22:23:27 INFO - 'LOGNAME': 'cltbld', 22:23:27 INFO - 'MAIL': '/var/mail/cltbld', 22:23:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:23:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:23:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:23:27 INFO - 'MOZ_NO_REMOTE': '1', 22:23:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:23:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:23:27 INFO - 'NO_EM_RESTART': '1', 22:23:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:23:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:23:27 INFO - 'PWD': '/builds/slave/test', 22:23:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:23:27 INFO - 'SHELL': '/bin/bash', 22:23:27 INFO - 'SHLVL': '1', 22:23:27 INFO - 'TERM': 'linux', 22:23:27 INFO - 'TMOUT': '86400', 22:23:27 INFO - 'USER': 'cltbld', 22:23:27 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1474521280.714703-1036006715', 22:23:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:23:27 INFO - '_': '/tools/buildbot/bin/python'} 22:23:27 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-017', '--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 22:23:28 INFO - mozversion application_buildid: 20160921192859 22:23:28 INFO - mozversion application_changeset: 35d7d3c4d627a3d88cff51e42eb4b1cbea0133ef 22:23:28 INFO - mozversion application_display_name: Firefox 22:23:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:23:28 INFO - mozversion application_name: Firefox 22:23:28 INFO - mozversion application_remotingname: firefox 22:23:28 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 22:23:28 INFO - mozversion application_vendor: Mozilla 22:23:28 INFO - mozversion application_version: 50.0 22:23:28 INFO - mozversion platform_buildid: 20160921192859 22:23:28 INFO - mozversion platform_changeset: 35d7d3c4d627a3d88cff51e42eb4b1cbea0133ef 22:23:28 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 22:23:28 INFO - mozversion platform_version: 50.0 22:23:28 INFO - using testdate: 1474521808 22:23:28 INFO - actual date: 1474521808 22:23:28 INFO - starting webserver on 'localhost:35964' 22:23:28 INFO - SUITE-START | Running 2 tests 22:23:28 INFO - TEST-START | dromaeo_css 22:23:28 INFO - operating with platform_type : linux_ 22:23:28 INFO - Initialising browser for dromaeo_css test... 22:23:28 INFO - TEST-INFO | started process 4173 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1mhIcX/profile http://localhost:35964/getInfo.html) 22:23:31 INFO - PROCESS | 4173 | __metrics Screen width/height:1600/1200 22:23:31 INFO - PROCESS | 4173 | colorDepth:24 22:23:31 INFO - PROCESS | 4173 | Browser inner width/height: 1024/697 22:23:31 INFO - PROCESS | 4173 | __metrics 22:23:32 INFO - PROCESS | 4173 | 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" 22:23:32 INFO - PROCESS | 4173 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:23:32 INFO - PROCESS | 4173 | console.error: PushService: 22:23:32 INFO - PROCESS | 4173 | stateChangeProcessEnqueue: Error transitioning state 22:23:32 INFO - PROCESS | 4173 | UnknownError 22:23:33 INFO - TEST-INFO | 4173: exit 0 22:23:33 INFO - Browser initialized. 22:23:33 INFO - Running cycle 1/1 for dromaeo_css test... 22:23:33 INFO - TEST-INFO | started process 4264 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1mhIcX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:23:45 INFO - PROCESS | 4264 | RSS: Main: 143351808 22:23:45 INFO - PROCESS | 4264 | 22:24:35 INFO - PROCESS | 4264 | 1474521875729 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] 22:26:44 INFO - PROCESS | 4264 | Cycle 1(1): loaded http://localhost:35964/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35964/tests/dromaeo/cssquery-ext.html) 22:26:45 INFO - PROCESS | 4264 | RSS: Main: 223555584 22:26:45 INFO - PROCESS | 4264 | 22:29:47 INFO - PROCESS | 4264 | Cycle 1(1): loaded http://localhost:35964/tests/dromaeo/cssquery-ext.html (next: http://localhost:35964/tests/dromaeo/cssquery-jquery.html) 22:29:48 INFO - PROCESS | 4264 | RSS: Main: 234229760 22:29:48 INFO - PROCESS | 4264 | 22:32:50 INFO - PROCESS | 4264 | Cycle 1(1): loaded http://localhost:35964/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35964/tests/dromaeo/cssquery-mootools.html) 22:32:50 INFO - PROCESS | 4264 | RSS: Main: 175935488 22:32:50 INFO - PROCESS | 4264 | 22:35:52 INFO - PROCESS | 4264 | Cycle 1(1): loaded http://localhost:35964/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35964/tests/dromaeo/cssquery-prototype.html) 22:35:53 INFO - PROCESS | 4264 | RSS: Main: 201879552 22:35:53 INFO - PROCESS | 4264 | 22:38:55 INFO - PROCESS | 4264 | Cycle 1(1): loaded http://localhost:35964/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35964/tests/dromaeo/cssquery-yui.html) 22:38:56 INFO - PROCESS | 4264 | RSS: Main: 176517120 22:38:56 INFO - PROCESS | 4264 | 22:41:58 INFO - PROCESS | 4264 | Cycle 1(1): loaded http://localhost:35964/tests/dromaeo/cssquery-yui.html (next: http://localhost:35964/tests/dromaeo/cssquery-dojo.html) 22:41:59 INFO - PROCESS | 4264 | RSS: Main: 263536640 22:41:59 INFO - PROCESS | 4264 | 22:41:59 INFO - PROCESS | 4264 | __start_tp_report 22:41:59 INFO - PROCESS | 4264 | _x_x_mozilla_page_load 22:41:59 INFO - PROCESS | 4264 | _x_x_mozilla_page_load_details 22:41:59 INFO - PROCESS | 4264 | |i|pagename|runs| 22:41:59 INFO - PROCESS | 4264 | |0;dojo.html;3195.952060719089;3200.0879749271453;3209.9518507222383;3226.8547915343806;3251.4797632378823;5525.751341189646;5598.628336057672;5612.579056570753;5616.213730077788;5622.184783206438;5560.77756889725;5660.490555849972;5664.830055098343;5665.801696940608;5666.603337766358;5079.492050794928;5106.923396149052;5131.076406246874;5207.661502002357;5225.059489291926;5035.420926593452;5156.664816786897;5193.246979188016;5210.947890521084;5219.686818790866;5068.493150684921;5119.513646203604;5131.512506311894;5131.897362052757;5208.531232189102;5220.451852555484;5229.320188375486;5229.921551176735;5239.73801309933;5272.314599102092;4979.651424400294;4989.800407983676;5013.874653133665;5119.66722163062;5132.076226279269;5481.698506582136;5510.393856675762;5513.3108361454815;5514.862128446781;5533.474319939599;5989.3711160327975;5992.8501787455225;5996.490298324604;6001.159837622737;6008.218931538916;5967.465625140464;5987.191729116557;5988.790392336291;6067.403408552816;6079.148919151322;5600.60795744294;5649.4633009864;5663.093904975225;5685.090385538336;5692.060809966392;5920.644761314335;6186.721597528122;6189.783357582463;6193.163922870445;6196.721147548371;5804.65172089676;5804.796832110856;5820.592558520948;5893.499052580579;5934.287885453773;5984.850378740566;5987.550933679991;5991.400859913973;5992.580519363602;5995.190649262381;5764.827055188351;5968.045112781934;5970.432808883149;5979.342272350038;5981.970090149522;5928.051511758098;5977.1333156692035;5992.280926288873;6026.819195424145;6031.8793624127275;4511.458624965026;4524.773761311922;4525.79633916468;4553.453585569753;4566.840160594364;3938.822752976123;4058.2695114879016;4059.3099173140636;4061.0659548304056;4092.304308267542;5641.661500309994;5692.743826527816;5709.400512946164;5732;5742.569307301969;5964.135200396005;5998.25021872266;6000.039993601003;6006.5495087868585;6013.819585412357;5538.723063846792;5668.688222147822;5669.858253543694;5672.744726487318;5681.034224181816;5144.6913185209005;5214.843554693365;5228.686278823283;5270.7628156733035;5290.947090529123;6363.713632886439;6370.649614271259;6370.6814659267775;6376.74493020274;6377.585456945284;5853.736581853912;6101.60339577926;6115.602485838406;6115.663638499926;6116.847078823065;6079.939200607937;6107.938920610737;6160.045192995093;6228.844278893064;6257.28041275241;5849.268841394825;5903.763849445974;5905.822825315247;5908.586398952032;5937.2578427696535;5801;6009.609375390585;6018.699065046642;6020.518358531394;6023.759049638141;5714.485696287354;5774.797882074107;5793.594448388572;5861.706914654335;5909.497692695997;5852.678102704393;5862.062070068769;5866.41335866427;5868.4424979626865;5871.735771890189;5688.687122208153;5741.081426971665;5784.855378615568;5790.073588225863;5795.623284486664;5855.560832937462;5874.706264686834;5876.588638795243;5936.940630593812;5980.970095149496;5775.27809023872;5785.797497087582;5794.82615521535;5799.623024503562;5801.622894511843 22:41:59 INFO - PROCESS | 4264 | |1;ext.html;600.6606267458885;601.4512629977809;602.1690067706564;626.5426238845643;648.3807963394959;34278.828605856965;46122;47020.764896175475;47142.999999999956;50848;47937.52062479374;48275.75862120693;48453.75773121129;48578.51421485784;48594.27108593374;8358.707445239417;9179.678711245107;9325.300602454809;9326.300527460433;10825.404602746847;10564.735881602945;11087.944560277187;11092.55629774812;11095.778084438307;11106.500207490682;11177.161712871526;11214.215004949658;11219.214654974156;11229.101671866272;11233;11067.944660276688;11116.388598627072;11118.166137539716;11126.61056863014;11134.443277836075;11057.557697692082;11107.166962477766;11115.333080015225;11122.55509779608;11129.499172537255;45446.77276613629;45819;45903;45935.77032114851;45938.54061459376;3603.09064664839;3774.7735135891935;3930.6265904739003;4002.2795896738576;4369.082492676542;34704.305913881835;34781.47827782585;34874.30251394958;34923;35029.29941401183;4390.27560452525;4411.117776444692;4436.822527098912;4438.822447102112;4512.368268442421;393620;395553.02223488985;395602;396059;398060;63213.683931580046;63538.0469292961;63638;63744.68127659332;63874.36125638777;39061.80469097693;39485;39509.20981580352;42114;42125.57874421278;17772;17906.462806115836;17906.731399029024;17916.193771280323;17918.104094795468;10658.467076646137;13554.661133471742;14637.853621463863;14869.330880110205;14944.925275373553;20502.282420115225;20523.179072837218;20759.169633214504;20766;20771.792282077287;7174.713011479483;7495.250474952571;7563.621818909032;7571.523552907241;7704.5762483062845;979.2655508368723;1096.8848270931437;1124.656979621207;1135.5401062569672;1152.815549512074;34237.82881085579;35006.64993350034;35118.29763404718;35119.64880351215;35185.82407087999;1127.9566401079132;1143.4911464398263;1148.8965993060665;1165.854268216473;1174.2015429508017;18020.28807263308;18107.637847243244;18250.726239106425;18349.633007339777;18487.16807743655;647312;654071;655204;656672;658429;62874.37125628685;63257.68371158115;63429;63489;63529.364706352346;1275.7448510297793;1399.258393051664;1436.719839631262;1450.6445920749484;1462.6635873748885;33854.830725846216;35292.47061294031;35414.29171416609;35580.82209588936;35581;52694.20958685546;53285.7335713319;53443;53493.46506534885;53563.73218133884;452348;453563;453780;454017;454603;24954.34821025309;27192.456150877268;27253.182404527895;29808.403831922806;31210.68789312078;49625.255621164986;51118;51927;52323.21515177199;52504.73747631237;43210.78394608007;44165.77917110523;44395.77802110969;44802.3279650799;45489.31766023446;43126.78436607922;44217.33673994957;44757.328640069776;45032.549674502836;45571.54428455673;43718.56281437145;44529.777351113036;44540.77729611331;44664;45200.547994519635;36006;38748.6125138745;39580;39750;40072.3989140169;28760.712392875805;29070;29088.41823163567;29375.265618359714;29432.705672942997 22:41:59 INFO - PROCESS | 4264 | |2;jquery.html;427.8074866310161;428.22775445009006;433.25263919738444;439.70320033977066;444.1782702001296;18944.905275473622;20396.796032039656;20483.283085092007;21265.57468850622;21601.783982160174;19513.804861951376;19849.900750496265;20703.585928281424;20922.372328830123;20966.37100886976;5780.450857168573;6012;6027.78902738402;6029.969850150743;6034.939650603482;5884.6763428011445;5900.704964751745;6126.632402055869;6157;6267.937320626804;6027.7287522061615;6236.906446403307;6239.469645080174;6245.594036387643;6248.6875656217235;6119.785807496739;6169.413905678954;6210.720517576698;6226.937730622681;6255.155554000197;5871.442212989759;6047.485963693091;6068.453839154453;6072.149899014142;6105.389461053904;14811.111333319928;14983.101013939198;15005.24973751308;19732.605347893106;24460.510789784283;2333.381653861728;2394.4492766663766;2560.6927168739685;2676.330917270682;2753.6282601848893;10158.796824063478;10771.946140269327;10929.508172132273;11042.503087361089;15357.232138393036;2022.4842665120448;2035.9898200508903;2036.9592608147902;2042.9489262768402;2079.5443189767225;82057.58971205166;85402.57298713528;86257.13742862554;86820.56589717075;88308;11519.596814111466;11649.941750291364;11664.300141991507;11862.525498979945;11982.502187226597;16770.245338959776;16779.664406711796;16815.327386904388;16826.747598786027;16853.410130645367;8339.45793523419;8613.26787223093;9083.273338132933;9142.680006199751;9209.21721653667;8917.464952102764;8932.955335223412;8974.19232269099;9124.81750364989;9177.265818734486;5062.670926389773;5090.312807770978;5100.438951715309;5102.836270928778;5270.49930256625;1996.160846602931;2028.7971202879594;2036.1651722793583;2069.968950465744;2117.5764847030655;927.735595355334;1014.4319181258371;1052.1898138433517;1053.089077947585;1095.2442814457997;20030;20173.293934712216;20325;20675.58648827015;20762.896185518977;956.9665061722807;1020.8621836052187;1039.3889471319424;1063.824468962628;1096.0683419093707;10756.838647420294;11477.426128693533;11591.304521728724;11595.652130436101;11619.419029048513;514322;516893;518884;530809;532413;78503.60748196223;83024;98390;102052;102672.48663756634;1038.5222797513077;1042.7028296935337;1078.8975047370486;1078.9676309710721;1081.070279559594;18439;18792.436226913214;18863.622727545648;18898.244070237586;19001.80998190056;44110;44259.33610995902;44630.3305450425;44661.330080049476;44730.77634611806;58238.4176158233;61554.69222653858;61637;62937.685311574984;64082;8424.663013479638;8521.275691566287;8614.18165274298;8653.610587523703;8912.064233255674;9675.419474831533;11722.531098755955;12855.935720321339;12986.805197922227;13159.736805264032;6378.266499352634;11782.823257651313;11864.830288250234;11918.821217681569;11960.820587691018;11452.94273528627;11458;11561.248518846249;11766.52933882635;11988.820167697666;11820.349880756297;11890.702732431759;12027.338496382434;12225.633231003085;12243.69390765238;8692.043875173853;8781.956090219508;8868.512231827053;8906.821863562822;9076.047015063323;11076.778464430505;11873.346965916957;11987.34069626179;11990.340531270514;12097.334646594521 22:41:59 INFO - PROCESS | 4264 | |3;mootools.html;1412.3079690951433;1489.3595753825855;1539.4227164813194;1725.2236493577877;1765.823417658235;9137.406068605536;9813.950930245348;9826.410415375074;9827.262955278347;9827.75430614235;7865.842683146334;9191.402558833672;9399.953000235006;9415.57629906654;9464.952675236615;2466.371075375777;2487.502499500098;2490.2653717153485;2494.226789695191;2494.463690306584;2469.839460435075;2538.352720056383;2541.796656267494;2543.0972004938258;2546.096135871767;2499.8875050622673;2513.949721005579;2536.087008676875;2537.4544472938314;2538.733432989528;2435.1233555919853;2444.841085329448;2446.0704932125855;2491.3397949543387;2492.5887228607257;2420.673209116782;2432.3797431654816;2435.561598912195;2450.0812195426715;2455.9263222103364;9547.761305967337;9654.082862128087;9671.177949874269;9705.417674939526;9720.513974301257;5664.915026274609;5813.186153938452;5814.825555233349;5821.53427725781;5823.039198532237;3432.7940323580665;3533.752637315389;3550.9822450887837;3572.1783989682517;3578.76738012031;1066.9679909602646;1102.862142232221;1105.1214284643727;1149.0635132367095;1150.0799360511558;13565.593032208952;13590.45638174462;13595.184288942695;13595.388207530586;13597.932010339984;4799.592034677022;4911.45973942866;4931.5315045070665;5002.649814513022;5035.974820125876;11276.32342059479;11301;11307.773844523228;11324.716882078013;11326.37704926237;5745.109508026263;5763.337216220105;5765.279340082489;5768.307803063659;5791.189233507313;4425.867223983286;4447.90988959688;4896.461389247252;5200.687958722413;5318.441563635763;4306.5262821089655;4315.395844581761;4331.5884990925815;4408.404865343202;4432.623227025739;1353.5635855460132;1370.41757253167;1380.5030189131905;1382.2052319916088;1383.9723205535834;952.9904700953041;975.6146322202691;991.2813210422386;993.3841018568534;994.1649014827435;6025.367336429699;6145.600535965237;6206.479412543866;6258.249010118815;6584;921.138735282513;926.073926073926;1000.2048371544639;1040.5837664934088;1058.433737950193;4309.806058727365;4431.180231657154;4483.75339356332;4489.191945449785;4521.050579378368;11911.344876031728;12326;12326.81509777354;12332.50669973191;12364.072694547946;11205.60780372683;11753.4710938008;12137.939310303569;12139.393030348447;12146.45340959659;8783.824323513621;8994.235489983424;9019.458832470073;9024.187823095954;9025.41334813235;6205.2553693557065;6229.96885015572;6319.8104056878365;6396.808095757135;6461.127747753993;11153.776924461628;11180.217384782987;11233.71915702114;11270.83093753578;11336.716582085513;10532.473376331;11035.448227588427;11063.944680276547;11137.10903127764;11235.269707468988;7605.201453847267;8128.309093726863;8271.421000529905;8318.45930014547;8441.83116337682;8795.208431240962;9046.959599645861;9051.909480905106;9052.818943621223;9057.68298109563;7909.604519773873;8036.808902433651;8134.796630084295;8247.95876020616;8394.748157555283;8004.239597238253;8089.595520224083;8121.878171827309;8142.47073940192;8270.2556769891;7275.163356213891;7405.333519983227;7467.738629147953;7473.364763994904;7512.774616761506;5512.614117011771;5596.692181930033;5679.460451257124;5783.103618939072;5827.79602713903;5374.274472946102;5388.407275199646;5445.7549410277425;5470.72646367688;5696.20253164549 22:41:59 INFO - PROCESS | 4264 | |4;prototype.html;345.4369377914;353.70112255144403;355.1334743226527;358.86183819229586;364.74285628631816;5419.729013549317;5456.590755693329;5504.779808807648;5512.1456174293;5526.82207730555;5147.48525147486;5187.221916712495;5265.526102650761;5303.973480132594;5402.9729851350785;4242.490901091874;4288.97855510722;4302.290122129845;4313.849015284466;4321.573880619388;4285.35719642053;4292.527821939584;4297.228287754397;4300.053988122608;4325.675574331922;4125.979370103145;4247.766372849492;4283.421738065368;4291.892702682427;4337.826486940535;4234.407182994383;4251.27728286189;4263.531011588722;4269.807858646368;4284.207421627009;3785.1483416231404;3788.1855401088624;3792.924141517182;3802.6007269236607;3805.9809700951596;5236.162214045772;5248.475152484721;5299.8410047698635;5326.627136100475;5375.16684913839;4836.51634836517;4836.879078023042;4851.466338702775;4889.902201955941;4890.559849613552;5608.691521966291;5629.60592758507;5664.745086471119;5673.971630141823;5698.316202055738;4045.049413387881;4100.405441211008;4110.917781644351;4151.878992671992;4162.708610397273;5818.738156782955;5845.298564172284;5848.134414137042;5851.912221316684;5854.882902341972;4221.514525829569;4234.280172370704;4246.299360605527;4248.660107191417;4261.786910654455;4136.296829538986;4258;4280.400743895857;4284.957150428519;4299.828006879752;4805.495422980606;4809.302651115639;4830.2513110467935;4857.757112144379;4862.5380588844;5662.886742265132;5662.971685141548;5718.170865224519;5730.656160630376;5765.472149880285;1398.1331574423923;1448.2396741710475;1460.2844606142914;1469.7060587882465;1471.249662672045;917.5045475443186;934.0245381019096;948.0140653720053;950.7955789505152;953.537534295866;4860.760506071;4881.219004959261;4902.558769710743;4909.741518901345;4951.777170027357;5063.164577844688;5076.390833100052;5079.923801142986;5080.796768129308;5105.706819101732;4856.514348565186;4877.731724755173;4889.511048895082;4920.261960705851;5187.896242075213;3041.6197975253094;3088.181631867557;3088.7374573161082;3090.7218350348576;3105.6739042400673;6061.212042434543;6061.696915154224;6069.4522896661065;6089.208402907594;6104.084387341845;5691.26013618227;5912.979174161612;5945.851353716192;5988.6706231157705;5999.940000599938;6074.787382441594;6077.301110372364;6081.179040829431;6082.361352058058;6098.786542471081;5577.553795696415;5817.2146760188525;5864.530837532901;5892;5917.497012753965;5778.393268706725;5787.56593255516;5789.33422656383;5795.768169273182;5800.216970708901;6092.141896721542;6104.481119104926;6104.542159337979;6109.358517355791;6118.195132436031;3983.50206224222;4007.6574347593883;4197.349410841324;4198.6011328923705;4213.873583792491;5697.544196464356;5774;5812.593118481665;5825.417458254141;5826.504747096545;5649.4633009864;5701.857453563694;5711.228984087261;5723.170140329712;5766.913496297643;5428;5707.68607726579;5709.914351284651;5711.714414279187;5730.942690573208;5652.68910209926;5656.688882111359;5671.262735844314;5678.3186017678145;5679.801206957736;5639.54883609302;5737.196792449144;5738.397468265772;5741.392410125171;5762.7694892203845;5519.144532597294;5521.309836270466;5605.915911261416;5607.214989901336;5636.464535869086 22:41:59 INFO - PROCESS | 4264 | |5;yui.html;796.2197046894044;804.5373910001749;810.9432339736221;825.8885050518182;851.4550687559963;14459.421623135075;15518.534443966684;15528.68942621147;15544.53366399007;15564.53306400807;14515.564533063998;14854.257287135628;14857.108573485599;14859;14880;714.5248409807472;847.656699036888;848.7878030492377;868.0104680664048;872.7469033980138;872.4329186029069;874.5364956573027;875.0811647769848;875.1685898396516;875.1991927386434;928.6099838068028;929.8186853563558;930.0978051290238;930.4463844012802;931.2968708624978;916.2395211974078;916.6058594804232;916.8624706294042;917.0691747875911;917.2203626917134;676.9289224631392;678.2742465561802;680.1701923653135;680.4318394140898;681.7307163670372;14329.641758956006;14333.140011599337;14348.64128396788;14485.710285794225;14502.927485362612;15118.09291442506;15254.23728813555;15766.527004189893;15798.526044218692;15888.28502717369;12959.157654752411;13148.539801106916;13183.14309569875;13254.66863328415;13582.388792504362;4087.591240875918;4203.264428724991;4228.978855105735;4283.271843786564;4336.067745434715;82485;83661.58169209176;83682;84310.57844710798;84686;11255.549778008788;12114.394280285951;12271.815922761165;12376.566820161252;12383.938080309541;21307;21318.360449186548;21331.46671333198;21369.572608548053;21374.251901183696;8624.956875215585;8648.524331161721;8766.91233087661;8796.780080498038;8902.55487225649;4531.546845315508;4794;4812.8796780080775;4820.39745031871;4912.066707325596;3922.1763429680086;4110.506739191316;4123.484564429446;4125.752454852718;4128.483939507561;682.5665702279089;688.6212583079387;691.3086913086913;693.0643631097977;693.6427739714052;664.5281849886635;727.1637616740791;730.0691618186854;732.5244835652749;753.2392283793408;9204.447733136034;9301.674441394518;9875.310724875082;10342.327748696462;10620.309679870783;692.9861402772017;693.9375456208966;781.2460975158999;785.7721260834409;803.3894240377239;4882.853514394574;5108;5112.923306150411;5172.19830926207;5186.196139598368;78030.6098469504;99687;140153.29923350317;140311;145995;58961.705191474626;59118;59878.10182847261;81842;117625;54284;54634;54758.72620636951;83796.58101709452;88358;9079.182873541413;9130.413116061141;9601.203013509104;9748.561314740711;9990.450525221182;54306.72846635742;55830.72084639551;55980.72009639926;56138;56479.435205647416;71674;76787.61606192157;78207.21792782228;79910.60491673014;80326.59836700778;14081.929590351983;14158.92920535432;14401.567952961428;14570.635734106732;14578.34397452096;15088.698226035638;15492.690146197237;15641;16528.338866445207;17245;14348.426062957366;14885.479008235094;15307.631409921032;15446.150461724714;15479.690406191588;13635.113717608323;14082.084664496775;14375.712485750017;14407.06354086981;14675.339609717801;13349.733005340033;13653.86346136526;14242.430302787772;14361.425542978583;14419.85580144185;12098.637040888787;12748.61754147377;12824;13116.147450415692;13347.733045338844;11547.538098475967;12078.577249796215;12261.448234829633;12412.81380779271;12562.68170862035 22:41:59 INFO - PROCESS | 4264 | __end_tp_report 22:41:59 INFO - PROCESS | 4264 | __start_cc_report 22:41:59 INFO - PROCESS | 4264 | _x_x_mozilla_cycle_collect,1035 22:41:59 INFO - PROCESS | 4264 | __end_cc_report 22:41:59 INFO - PROCESS | 4264 | __startTimestamp1474522919138__endTimestamp 22:41:59 INFO - PROCESS | 4264 | 22:41:59 INFO - PROCESS | 4264 | ------- Summary: start ------- 22:41:59 INFO - PROCESS | 4264 | Number of tests: 6 22:41:59 INFO - PROCESS | 4264 | 22:41:59 INFO - PROCESS | 4264 | [#0] dojo.html Cycles:165 Average:5576.83 Median:5785.80 stddev:639.35 (11.1%) stddev-sans-first:613.42 22:41:59 INFO - PROCESS | 4264 | Values: 3196.0 3200.1 3210.0 3226.9 3251.5 5525.8 5598.6 5612.6 5616.2 5622.2 5560.8 5660.5 5664.8 5665.8 5666.6 5079.5 5106.9 5131.1 5207.7 5225.1 5035.4 5156.7 5193.2 5210.9 5219.7 5068.5 5119.5 5131.5 5131.9 5208.5 5220.5 5229.3 5229.9 5239.7 5272.3 4979.7 4989.8 5013.9 5119.7 5132.1 5481.7 5510.4 5513.3 5514.9 5533.5 5989.4 5992.9 5996.5 6001.2 6008.2 5967.5 5987.2 5988.8 6067.4 6079.1 5600.6 5649.5 5663.1 5685.1 5692.1 5920.6 6186.7 6189.8 6193.2 6196.7 5804.7 5804.8 5820.6 5893.5 5934.3 5984.9 5987.6 5991.4 5992.6 5995.2 5764.8 5968.0 5970.4 5979.3 5982.0 5928.1 5977.1 5992.3 6026.8 6031.9 4511.5 4524.8 4525.8 4553.5 4566.8 3938.8 4058.3 4059.3 4061.1 4092.3 5641.7 5692.7 5709.4 5732.0 5742.6 5964.1 5998.3 6000.0 6006.5 6013.8 5538.7 5668.7 5669.9 5672.7 5681.0 5144.7 5214.8 5228.7 5270.8 5290.9 6363.7 6370.6 6370.7 6376.7 6377.6 5853.7 6101.6 6115.6 6115.7 6116.8 6079.9 6107.9 6160.0 6228.8 6257.3 5849.3 5903.8 5905.8 5908.6 5937.3 5801.0 6009.6 6018.7 6020.5 6023.8 5714.5 5774.8 5793.6 5861.7 5909.5 5852.7 5862.1 5866.4 5868.4 5871.7 5688.7 5741.1 5784.9 5790.1 5795.6 5855.6 5874.7 5876.6 5936.9 5981.0 5775.3 5785.8 5794.8 5799.6 5801.6 22:41:59 INFO - PROCESS | 4264 | 22:41:59 INFO - PROCESS | 4264 | [#1] ext.html Cycles:180 Average:66505.38 Median:395577.51 stddev:136578.57 (34.5%) stddev-sans-first:136872.07 22:41:59 INFO - PROCESS | 4264 | Values: 600.7 601.5 602.2 626.5 648.4 34278.8 46122.0 47020.8 47143.0 50848.0 47937.5 48275.8 48453.8 48578.5 48594.3 8358.7 9179.7 9325.3 9326.3 10825.4 10564.7 11087.9 11092.6 11095.8 11106.5 11177.2 11214.2 11219.2 11229.1 11233.0 11067.9 11116.4 11118.2 11126.6 11134.4 11057.6 11107.2 11115.3 11122.6 11129.5 45446.8 45819.0 45903.0 45935.8 45938.5 3603.1 3774.8 3930.6 4002.3 4369.1 34704.3 34781.5 34874.3 34923.0 35029.3 4390.3 4411.1 4436.8 4438.8 4512.4 393620.0 395553.0 395602.0 396059.0 398060.0 63213.7 63538.0 63638.0 63744.7 63874.4 39061.8 39485.0 39509.2 42114.0 42125.6 17772.0 17906.5 17906.7 17916.2 17918.1 10658.5 13554.7 14637.9 14869.3 14944.9 20502.3 20523.2 20759.2 20766.0 20771.8 7174.7 7495.3 7563.6 7571.5 7704.6 979.3 1096.9 1124.7 1135.5 1152.8 34237.8 35006.6 35118.3 35119.6 35185.8 1128.0 1143.5 1148.9 1165.9 1174.2 18020.3 18107.6 18250.7 18349.6 18487.2 647312.0 654071.0 655204.0 656672.0 658429.0 62874.4 63257.7 63429.0 63489.0 63529.4 1275.7 1399.3 1436.7 1450.6 1462.7 33854.8 35292.5 35414.3 35580.8 35581.0 52694.2 53285.7 53443.0 53493.5 53563.7 452348.0 453563.0 453780.0 454017.0 454603.0 24954.3 27192.5 27253.2 29808.4 31210.7 49625.3 51118.0 51927.0 52323.2 52504.7 43210.8 44165.8 44395.8 44802.3 45489.3 43126.8 44217.3 44757.3 45032.5 45571.5 43718.6 44529.8 44540.8 44664.0 45200.5 36006.0 38748.6 39580.0 39750.0 40072.4 28760.7 29070.0 29088.4 29375.3 29432.7 22:41:59 INFO - PROCESS | 4264 | 22:41:59 INFO - PROCESS | 4264 | [#2] jquery.html Cycles:180 Average:30461.13 Median:21433.68 stddev:86130.21 (401.8%) stddev-sans-first:86342.30 22:41:59 INFO - PROCESS | 4264 | Values: 427.8 428.2 433.3 439.7 444.2 18944.9 20396.8 20483.3 21265.6 21601.8 19513.8 19849.9 20703.6 20922.4 20966.4 5780.5 6012.0 6027.8 6030.0 6034.9 5884.7 5900.7 6126.6 6157.0 6267.9 6027.7 6236.9 6239.5 6245.6 6248.7 6119.8 6169.4 6210.7 6226.9 6255.2 5871.4 6047.5 6068.5 6072.1 6105.4 14811.1 14983.1 15005.2 19732.6 24460.5 2333.4 2394.4 2560.7 2676.3 2753.6 10158.8 10771.9 10929.5 11042.5 15357.2 2022.5 2036.0 2037.0 2042.9 2079.5 82057.6 85402.6 86257.1 86820.6 88308.0 11519.6 11649.9 11664.3 11862.5 11982.5 16770.2 16779.7 16815.3 16826.7 16853.4 8339.5 8613.3 9083.3 9142.7 9209.2 8917.5 8933.0 8974.2 9124.8 9177.3 5062.7 5090.3 5100.4 5102.8 5270.5 1996.2 2028.8 2036.2 2070.0 2117.6 927.7 1014.4 1052.2 1053.1 1095.2 20030.0 20173.3 20325.0 20675.6 20762.9 957.0 1020.9 1039.4 1063.8 1096.1 10756.8 11477.4 11591.3 11595.7 11619.4 514322.0 516893.0 518884.0 530809.0 532413.0 78503.6 83024.0 98390.0 102052.0 102672.5 1038.5 1042.7 1078.9 1079.0 1081.1 18439.0 18792.4 18863.6 18898.2 19001.8 44110.0 44259.3 44630.3 44661.3 44730.8 58238.4 61554.7 61637.0 62937.7 64082.0 8424.7 8521.3 8614.2 8653.6 8912.1 9675.4 11722.5 12855.9 12986.8 13159.7 6378.3 11782.8 11864.8 11918.8 11960.8 11452.9 11458.0 11561.2 11766.5 11988.8 11820.3 11890.7 12027.3 12225.6 12243.7 8692.0 8782.0 8868.5 8906.8 9076.0 11076.8 11873.3 11987.3 11990.3 12097.3 22:41:59 INFO - PROCESS | 4264 | 22:41:59 INFO - PROCESS | 4264 | [#3] mootools.html Cycles:180 Average:6260.87 Median:4848.03 stddev:3628.75 (74.8%) stddev-sans-first:3620.63 22:41:59 INFO - PROCESS | 4264 | Values: 1412.3 1489.4 1539.4 1725.2 1765.8 9137.4 9814.0 9826.4 9827.3 9827.8 7865.8 9191.4 9400.0 9415.6 9465.0 2466.4 2487.5 2490.3 2494.2 2494.5 2469.8 2538.4 2541.8 2543.1 2546.1 2499.9 2513.9 2536.1 2537.5 2538.7 2435.1 2444.8 2446.1 2491.3 2492.6 2420.7 2432.4 2435.6 2450.1 2455.9 9547.8 9654.1 9671.2 9705.4 9720.5 5664.9 5813.2 5814.8 5821.5 5823.0 3432.8 3533.8 3551.0 3572.2 3578.8 1067.0 1102.9 1105.1 1149.1 1150.1 13565.6 13590.5 13595.2 13595.4 13597.9 4799.6 4911.5 4931.5 5002.6 5036.0 11276.3 11301.0 11307.8 11324.7 11326.4 5745.1 5763.3 5765.3 5768.3 5791.2 4425.9 4447.9 4896.5 5200.7 5318.4 4306.5 4315.4 4331.6 4408.4 4432.6 1353.6 1370.4 1380.5 1382.2 1384.0 953.0 975.6 991.3 993.4 994.2 6025.4 6145.6 6206.5 6258.2 6584.0 921.1 926.1 1000.2 1040.6 1058.4 4309.8 4431.2 4483.8 4489.2 4521.1 11911.3 12326.0 12326.8 12332.5 12364.1 11205.6 11753.5 12137.9 12139.4 12146.5 8783.8 8994.2 9019.5 9024.2 9025.4 6205.3 6230.0 6319.8 6396.8 6461.1 11153.8 11180.2 11233.7 11270.8 11336.7 10532.5 11035.4 11063.9 11137.1 11235.3 7605.2 8128.3 8271.4 8318.5 8441.8 8795.2 9047.0 9051.9 9052.8 9057.7 7909.6 8036.8 8134.8 8248.0 8394.7 8004.2 8089.6 8121.9 8142.5 8270.3 7275.2 7405.3 7467.7 7473.4 7512.8 5512.6 5596.7 5679.5 5783.1 5827.8 5374.3 5388.4 5445.8 5470.7 5696.2 22:41:59 INFO - PROCESS | 4264 | 22:41:59 INFO - PROCESS | 4264 | [#4] prototype.html Cycles:180 Average:4756.66 Median:5301.91 stddev:1389.91 (26.2%) stddev-sans-first:1353.80 22:41:59 INFO - PROCESS | 4264 | Values: 345.4 353.7 355.1 358.9 364.7 5419.7 5456.6 5504.8 5512.1 5526.8 5147.5 5187.2 5265.5 5304.0 5403.0 4242.5 4289.0 4302.3 4313.8 4321.6 4285.4 4292.5 4297.2 4300.1 4325.7 4126.0 4247.8 4283.4 4291.9 4337.8 4234.4 4251.3 4263.5 4269.8 4284.2 3785.1 3788.2 3792.9 3802.6 3806.0 5236.2 5248.5 5299.8 5326.6 5375.2 4836.5 4836.9 4851.5 4889.9 4890.6 5608.7 5629.6 5664.7 5674.0 5698.3 4045.0 4100.4 4110.9 4151.9 4162.7 5818.7 5845.3 5848.1 5851.9 5854.9 4221.5 4234.3 4246.3 4248.7 4261.8 4136.3 4258.0 4280.4 4285.0 4299.8 4805.5 4809.3 4830.3 4857.8 4862.5 5662.9 5663.0 5718.2 5730.7 5765.5 1398.1 1448.2 1460.3 1469.7 1471.2 917.5 934.0 948.0 950.8 953.5 4860.8 4881.2 4902.6 4909.7 4951.8 5063.2 5076.4 5079.9 5080.8 5105.7 4856.5 4877.7 4889.5 4920.3 5187.9 3041.6 3088.2 3088.7 3090.7 3105.7 6061.2 6061.7 6069.5 6089.2 6104.1 5691.3 5913.0 5945.9 5988.7 5999.9 6074.8 6077.3 6081.2 6082.4 6098.8 5577.6 5817.2 5864.5 5892.0 5917.5 5778.4 5787.6 5789.3 5795.8 5800.2 6092.1 6104.5 6104.5 6109.4 6118.2 3983.5 4007.7 4197.3 4198.6 4213.9 5697.5 5774.0 5812.6 5825.4 5826.5 5649.5 5701.9 5711.2 5723.2 5766.9 5428.0 5707.7 5709.9 5711.7 5730.9 5652.7 5656.7 5671.3 5678.3 5679.8 5639.5 5737.2 5738.4 5741.4 5762.8 5519.1 5521.3 5605.9 5607.2 5636.5 22:41:59 INFO - PROCESS | 4264 | 22:41:59 INFO - PROCESS | 4264 | [#5] yui.html Cycles:180 Average:20536.49 Median:4897.46 stddev:29274.83 (597.8%) stddev-sans-first:29319.43 22:41:59 INFO - PROCESS | 4264 | Values: 796.2 804.5 810.9 825.9 851.5 14459.4 15518.5 15528.7 15544.5 15564.5 14515.6 14854.3 14857.1 14859.0 14880.0 714.5 847.7 848.8 868.0 872.7 872.4 874.5 875.1 875.2 875.2 928.6 929.8 930.1 930.4 931.3 916.2 916.6 916.9 917.1 917.2 676.9 678.3 680.2 680.4 681.7 14329.6 14333.1 14348.6 14485.7 14502.9 15118.1 15254.2 15766.5 15798.5 15888.3 12959.2 13148.5 13183.1 13254.7 13582.4 4087.6 4203.3 4229.0 4283.3 4336.1 82485.0 83661.6 83682.0 84310.6 84686.0 11255.5 12114.4 12271.8 12376.6 12383.9 21307.0 21318.4 21331.5 21369.6 21374.3 8625.0 8648.5 8766.9 8796.8 8902.6 4531.5 4794.0 4812.9 4820.4 4912.1 3922.2 4110.5 4123.5 4125.8 4128.5 682.6 688.6 691.3 693.1 693.6 664.5 727.2 730.1 732.5 753.2 9204.4 9301.7 9875.3 10342.3 10620.3 693.0 693.9 781.2 785.8 803.4 4882.9 5108.0 5112.9 5172.2 5186.2 78030.6 99687.0 140153.3 140311.0 145995.0 58961.7 59118.0 59878.1 81842.0 117625.0 54284.0 54634.0 54758.7 83796.6 88358.0 9079.2 9130.4 9601.2 9748.6 9990.5 54306.7 55830.7 55980.7 56138.0 56479.4 71674.0 76787.6 78207.2 79910.6 80326.6 14081.9 14158.9 14401.6 14570.6 14578.3 15088.7 15492.7 15641.0 16528.3 17245.0 14348.4 14885.5 15307.6 15446.2 15479.7 13635.1 14082.1 14375.7 14407.1 14675.3 13349.7 13653.9 14242.4 14361.4 14419.9 12098.6 12748.6 12824.0 13116.1 13347.7 11547.5 12078.6 12261.4 12412.8 12562.7 22:41:59 INFO - PROCESS | 4264 | -------- Summary: end -------- 22:41:59 INFO - PROCESS | 4264 | 22:41:59 INFO - TEST-INFO | 4264: exit 0 22:41:59 INFO - TEST-OK | dromaeo_css | took 1111696ms 22:41:59 INFO - TEST-START | kraken 22:41:59 INFO - operating with platform_type : linux_ 22:41:59 INFO - Initialising browser for kraken test... 22:41:59 INFO - TEST-INFO | started process 4459 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIRIB8b/profile http://localhost:35964/getInfo.html) 22:42:03 INFO - PROCESS | 4459 | __metrics Screen width/height:1600/1200 22:42:03 INFO - PROCESS | 4459 | colorDepth:24 22:42:03 INFO - PROCESS | 4459 | Browser inner width/height: 1024/697 22:42:03 INFO - PROCESS | 4459 | __metrics 22:42:04 INFO - PROCESS | 4459 | 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" 22:42:04 INFO - PROCESS | 4459 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:42:04 INFO - TEST-INFO | 4459: exit 0 22:42:04 INFO - Browser initialized. 22:42:04 INFO - Running cycle 1/1 for kraken test... 22:42:04 INFO - TEST-INFO | started process 4548 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIRIB8b/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:42:16 INFO - PROCESS | 4548 | RSS: Main: 143671296 22:42:16 INFO - PROCESS | 4548 | 22:42:19 INFO - PROCESS | 4548 | Cycle 1(1): loaded http://localhost:35964/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35964/tests/kraken/driver.html?testName=audio-beat-detection) 22:42:20 INFO - PROCESS | 4548 | RSS: Main: 199245824 22:42:20 INFO - PROCESS | 4548 | 22:42:22 INFO - PROCESS | 4548 | Cycle 1(1): loaded http://localhost:35964/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35964/tests/kraken/driver.html?testName=audio-dft) 22:42:23 INFO - PROCESS | 4548 | RSS: Main: 183525376 22:42:23 INFO - PROCESS | 4548 | 22:42:26 INFO - PROCESS | 4548 | Cycle 1(1): loaded http://localhost:35964/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35964/tests/kraken/driver.html?testName=audio-fft) 22:42:26 INFO - PROCESS | 4548 | RSS: Main: 274313216 22:42:26 INFO - PROCESS | 4548 | 22:42:28 INFO - PROCESS | 4548 | Cycle 1(1): loaded http://localhost:35964/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35964/tests/kraken/driver.html?testName=audio-oscillator) 22:42:29 INFO - PROCESS | 4548 | RSS: Main: 179302400 22:42:29 INFO - PROCESS | 4548 | 22:42:31 INFO - PROCESS | 4548 | Cycle 1(1): loaded http://localhost:35964/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35964/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:42:31 INFO - PROCESS | 4548 | RSS: Main: 178528256 22:42:31 INFO - PROCESS | 4548 | 22:42:34 INFO - PROCESS | 4548 | Cycle 1(1): loaded http://localhost:35964/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35964/tests/kraken/driver.html?testName=imaging-darkroom) 22:42:35 INFO - PROCESS | 4548 | RSS: Main: 214773760 22:42:35 INFO - PROCESS | 4548 | 22:42:38 INFO - PROCESS | 4548 | Cycle 1(1): loaded http://localhost:35964/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35964/tests/kraken/driver.html?testName=imaging-desaturate) 22:42:39 INFO - PROCESS | 4548 | RSS: Main: 213495808 22:42:39 INFO - PROCESS | 4548 | 22:42:42 INFO - PROCESS | 4548 | Cycle 1(1): loaded http://localhost:35964/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35964/tests/kraken/driver.html?testName=json-parse-financial) 22:42:42 INFO - PROCESS | 4548 | RSS: Main: 213606400 22:42:42 INFO - PROCESS | 4548 | 22:42:44 INFO - PROCESS | 4548 | Cycle 1(1): loaded http://localhost:35964/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35964/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:42:44 INFO - PROCESS | 4548 | RSS: Main: 175742976 22:42:44 INFO - PROCESS | 4548 | 22:42:46 INFO - PROCESS | 4548 | Cycle 1(1): loaded http://localhost:35964/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35964/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:42:47 INFO - PROCESS | 4548 | RSS: Main: 182378496 22:42:47 INFO - PROCESS | 4548 | 22:42:49 INFO - PROCESS | 4548 | Cycle 1(1): loaded http://localhost:35964/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35964/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:42:49 INFO - PROCESS | 4548 | RSS: Main: 184766464 22:42:49 INFO - PROCESS | 4548 | 22:42:52 INFO - PROCESS | 4548 | Cycle 1(1): loaded http://localhost:35964/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35964/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:42:52 INFO - PROCESS | 4548 | RSS: Main: 184766464 22:42:52 INFO - PROCESS | 4548 | 22:42:55 INFO - PROCESS | 4548 | Cycle 1(1): loaded http://localhost:35964/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35964/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:42:56 INFO - PROCESS | 4548 | RSS: Main: 182366208 22:42:56 INFO - PROCESS | 4548 | 22:42:57 INFO - PROCESS | 4548 | Cycle 1(1): loaded http://localhost:35964/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35964/tests/kraken/driver.html?testName=ai-astar) 22:42:58 INFO - PROCESS | 4548 | RSS: Main: 233598976 22:42:58 INFO - PROCESS | 4548 | 22:42:58 INFO - PROCESS | 4548 | __start_tp_report 22:42:58 INFO - PROCESS | 4548 | _x_x_mozilla_page_load 22:42:58 INFO - PROCESS | 4548 | _x_x_mozilla_page_load_details 22:42:58 INFO - PROCESS | 4548 | |i|pagename|runs| 22:42:58 INFO - PROCESS | 4548 | |0;ai-astar;112;110;120;109;109;111;110;111;110;111 22:42:58 INFO - PROCESS | 4548 | |1;audio-beat-detection;146;118;146;169;117;140;141;121;184;118 22:42:58 INFO - PROCESS | 4548 | |2;audio-dft;161;161;161;161;162;161;160;161;161;163 22:42:58 INFO - PROCESS | 4548 | |3;audio-fft;74;73;76;167;76;76;74;75;160;75 22:42:58 INFO - PROCESS | 4548 | |4;audio-oscillator;83;83;156;83;85;84;142;82;84;140 22:42:58 INFO - PROCESS | 4548 | |5;imaging-gaussian-blur;116;116;117;116;116;116;116;116;117;117 22:42:58 INFO - PROCESS | 4548 | |6;imaging-darkroom;147;146;147;147;147;147;146;147;146;146 22:42:58 INFO - PROCESS | 4548 | |7;imaging-desaturate;95;95;95;94;95;94;95;95;95;94 22:42:58 INFO - PROCESS | 4548 | |8;json-parse-financial;50;50;56;50;49;52;51;50;51;51 22:42:58 INFO - PROCESS | 4548 | |9;json-stringify-tinderbox;60;61;62;61;62;61;61;61;61;61 22:42:58 INFO - PROCESS | 4548 | |10;stanford-crypto-aes;72;75;72;72;77;73;72;73;83;72 22:42:58 INFO - PROCESS | 4548 | |11;stanford-crypto-ccm;120;117;120;119;118;117;118;118;159;119 22:42:58 INFO - PROCESS | 4548 | |12;stanford-crypto-pbkdf2;155;152;152;158;153;151;150;152;155;157 22:42:58 INFO - PROCESS | 4548 | |13;stanford-crypto-sha256-iterative;62;67;62;62;61;63;61;62;59;60 22:42:58 INFO - PROCESS | 4548 | __end_tp_report 22:42:58 INFO - PROCESS | 4548 | __start_cc_report 22:42:58 INFO - PROCESS | 4548 | _x_x_mozilla_cycle_collect,1706 22:42:58 INFO - PROCESS | 4548 | __end_cc_report 22:42:58 INFO - PROCESS | 4548 | __startTimestamp1474522978048__endTimestamp 22:42:58 INFO - PROCESS | 4548 | 22:42:58 INFO - PROCESS | 4548 | ------- Summary: start ------- 22:42:58 INFO - PROCESS | 4548 | Number of tests: 14 22:42:58 INFO - PROCESS | 4548 | 22:42:58 INFO - PROCESS | 4548 | [#0] ai-astar Cycles:10 Average:111.30 Median:111.00 stddev:3.20 (2.9%) stddev-sans-first:3.38 22:42:58 INFO - PROCESS | 4548 | Values: 112.0 110.0 120.0 109.0 109.0 111.0 110.0 111.0 110.0 111.0 22:42:58 INFO - PROCESS | 4548 | 22:42:58 INFO - PROCESS | 4548 | [#1] audio-beat-detection Cycles:10 Average:140.00 Median:143.50 stddev:22.87 (15.9%) stddev-sans-first:24.16 22:42:58 INFO - PROCESS | 4548 | Values: 146.0 118.0 146.0 169.0 117.0 140.0 141.0 121.0 184.0 118.0 22:42:58 INFO - PROCESS | 4548 | 22:42:58 INFO - PROCESS | 4548 | [#2] audio-dft Cycles:10 Average:161.20 Median:161.00 stddev:0.79 (0.5%) stddev-sans-first:0.83 22:42:58 INFO - PROCESS | 4548 | Values: 161.0 161.0 161.0 161.0 162.0 161.0 160.0 161.0 161.0 163.0 22:42:58 INFO - PROCESS | 4548 | 22:42:58 INFO - PROCESS | 4548 | [#3] audio-fft Cycles:10 Average:92.60 Median:75.00 stddev:37.42 (49.9%) stddev-sans-first:39.08 22:42:58 INFO - PROCESS | 4548 | Values: 74.0 73.0 76.0 167.0 76.0 76.0 74.0 75.0 160.0 75.0 22:42:58 INFO - PROCESS | 4548 | 22:42:58 INFO - PROCESS | 4548 | [#4] audio-oscillator Cycles:10 Average:102.20 Median:83.00 stddev:30.51 (36.8%) stddev-sans-first:31.56 22:42:58 INFO - PROCESS | 4548 | Values: 83.0 83.0 156.0 83.0 85.0 84.0 142.0 82.0 84.0 140.0 22:42:58 INFO - PROCESS | 4548 | 22:42:58 INFO - PROCESS | 4548 | [#5] imaging-gaussian-blur Cycles:10 Average:116.30 Median:116.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 22:42:58 INFO - PROCESS | 4548 | Values: 116.0 116.0 117.0 116.0 116.0 116.0 116.0 116.0 117.0 117.0 22:42:58 INFO - PROCESS | 4548 | 22:42:58 INFO - PROCESS | 4548 | [#6] imaging-darkroom Cycles:10 Average:146.60 Median:147.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 22:42:58 INFO - PROCESS | 4548 | Values: 147.0 146.0 147.0 147.0 147.0 147.0 146.0 147.0 146.0 146.0 22:42:58 INFO - PROCESS | 4548 | 22:42:58 INFO - PROCESS | 4548 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 22:42:58 INFO - PROCESS | 4548 | Values: 95.0 95.0 95.0 94.0 95.0 94.0 95.0 95.0 95.0 94.0 22:42:58 INFO - PROCESS | 4548 | 22:42:58 INFO - PROCESS | 4548 | [#8] json-parse-financial Cycles:10 Average:51.00 Median:51.00 stddev:1.94 (3.8%) stddev-sans-first:2.03 22:42:58 INFO - PROCESS | 4548 | Values: 50.0 50.0 56.0 50.0 49.0 52.0 51.0 50.0 51.0 51.0 22:42:58 INFO - PROCESS | 4548 | 22:42:58 INFO - PROCESS | 4548 | [#9] json-stringify-tinderbox Cycles:10 Average:61.10 Median:61.00 stddev:0.57 (0.9%) stddev-sans-first:0.44 22:42:58 INFO - PROCESS | 4548 | Values: 60.0 61.0 62.0 61.0 62.0 61.0 61.0 61.0 61.0 61.0 22:42:58 INFO - PROCESS | 4548 | 22:42:58 INFO - PROCESS | 4548 | [#10] stanford-crypto-aes Cycles:10 Average:74.10 Median:73.00 stddev:3.54 (4.9%) stddev-sans-first:3.67 22:42:58 INFO - PROCESS | 4548 | Values: 72.0 75.0 72.0 72.0 77.0 73.0 72.0 73.0 83.0 72.0 22:42:58 INFO - PROCESS | 4548 | 22:42:58 INFO - PROCESS | 4548 | [#11] stanford-crypto-ccm Cycles:10 Average:122.50 Median:119.00 stddev:12.87 (10.8%) stddev-sans-first:13.62 22:42:58 INFO - PROCESS | 4548 | Values: 120.0 117.0 120.0 119.0 118.0 117.0 118.0 118.0 159.0 119.0 22:42:58 INFO - PROCESS | 4548 | 22:42:58 INFO - PROCESS | 4548 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.50 Median:154.00 stddev:2.64 (1.7%) stddev-sans-first:2.74 22:42:58 INFO - PROCESS | 4548 | Values: 155.0 152.0 152.0 158.0 153.0 151.0 150.0 152.0 155.0 157.0 22:42:58 INFO - PROCESS | 4548 | 22:42:58 INFO - PROCESS | 4548 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.90 Median:62.00 stddev:2.13 (3.4%) stddev-sans-first:2.26 22:42:58 INFO - PROCESS | 4548 | Values: 62.0 67.0 62.0 62.0 61.0 63.0 61.0 62.0 59.0 60.0 22:42:58 INFO - PROCESS | 4548 | -------- Summary: end -------- 22:42:58 INFO - PROCESS | 4548 | 22:42:59 INFO - TEST-INFO | 4548: exit 0 22:42:59 INFO - TEST-OK | kraken | took 59242ms 22:42:59 INFO - SUITE-END | took 1170s 22:42:59 INFO - Completed test suite (00:19:31) 22:42:59 INFO - javascript benchmark 22:42:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3195.952060719089, 3200.0879749271453, 3209.9518507222383, 3226.8547915343806, 3251.4797632378823, 5525.751341189646, 5598.628336057672, 5612.579056570753, 5616.213730077788, 5622.184783206438, 5560.77756889725, 5660.490555849972, 5664.830055098343, 5665.801696940608, 5666.603337766358, 5079.492050794928, 5106.923396149052, 5131.076406246874, 5207.661502002357, 5225.059489291926, 5035.420926593452, 5156.664816786897, 5193.246979188016, 5210.947890521084, 5219.686818790866, 5068.493150684921, 5119.513646203604, 5131.512506311894, 5131.897362052757, 5208.531232189102, 5220.451852555484, 5229.320188375486, 5229.921551176735, 5239.73801309933, 5272.314599102092, 4979.651424400294, 4989.800407983676, 5013.874653133665, 5119.66722163062, 5132.076226279269, 5481.698506582136, 5510.393856675762, 5513.3108361454815, 5514.862128446781, 5533.474319939599, 5989.3711160327975, 5992.8501787455225, 5996.490298324604, 6001.159837622737, 6008.218931538916, 5967.465625140464, 5987.191729116557, 5988.790392336291, 6067.403408552816, 6079.148919151322, 5600.60795744294, 5649.4633009864, 5663.093904975225, 5685.090385538336, 5692.060809966392, 5920.644761314335, 6186.721597528122, 6189.783357582463, 6193.163922870445, 6196.721147548371, 5804.65172089676, 5804.796832110856, 5820.592558520948, 5893.499052580579, 5934.287885453773, 5984.850378740566, 5987.550933679991, 5991.400859913973, 5992.580519363602, 5995.190649262381, 5764.827055188351, 5968.045112781934, 5970.432808883149, 5979.342272350038, 5981.970090149522, 5928.051511758098, 5977.1333156692035, 5992.280926288873, 6026.819195424145, 6031.8793624127275, 4511.458624965026, 4524.773761311922, 4525.79633916468, 4553.453585569753, 4566.840160594364, 3938.822752976123, 4058.2695114879016, 4059.3099173140636, 4061.0659548304056, 4092.304308267542, 5641.661500309994, 5692.743826527816, 5709.400512946164, 5732.0, 5742.569307301969, 5964.135200396005, 5998.25021872266, 6000.039993601003, 6006.5495087868585, 6013.819585412357, 5538.723063846792, 5668.688222147822, 5669.858253543694, 5672.744726487318, 5681.034224181816, 5144.6913185209005, 5214.843554693365, 5228.686278823283, 5270.7628156733035, 5290.947090529123, 6363.713632886439, 6370.649614271259, 6370.6814659267775, 6376.74493020274, 6377.585456945284, 5853.736581853912, 6101.60339577926, 6115.602485838406, 6115.663638499926, 6116.847078823065, 6079.939200607937, 6107.938920610737, 6160.045192995093, 6228.844278893064, 6257.28041275241, 5849.268841394825, 5903.763849445974, 5905.822825315247, 5908.586398952032, 5937.2578427696535, 5801.0, 6009.609375390585, 6018.699065046642, 6020.518358531394, 6023.759049638141, 5714.485696287354, 5774.797882074107, 5793.594448388572, 5861.706914654335, 5909.497692695997, 5852.678102704393, 5862.062070068769, 5866.41335866427, 5868.4424979626865, 5871.735771890189, 5688.687122208153, 5741.081426971665, 5784.855378615568, 5790.073588225863, 5795.623284486664, 5855.560832937462, 5874.706264686834, 5876.588638795243, 5936.940630593812, 5980.970095149496, 5775.27809023872, 5785.797497087582, 5794.82615521535, 5799.623024503562, 5801.622894511843], "value": 5532.908521883808, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [600.6606267458885, 601.4512629977809, 602.1690067706564, 626.5426238845643, 648.3807963394959, 34278.828605856965, 46122.0, 47020.764896175475, 47142.999999999956, 50848.0, 47937.52062479374, 48275.75862120693, 48453.75773121129, 48578.51421485784, 48594.27108593374, 8358.707445239417, 9179.678711245107, 9325.300602454809, 9326.300527460433, 10825.404602746847, 10564.735881602945, 11087.944560277187, 11092.55629774812, 11095.778084438307, 11106.500207490682, 11177.161712871526, 11214.215004949658, 11219.214654974156, 11229.101671866272, 11233.0, 11067.944660276688, 11116.388598627072, 11118.166137539716, 11126.61056863014, 11134.443277836075, 11057.557697692082, 11107.166962477766, 11115.333080015225, 11122.55509779608, 11129.499172537255, 45446.77276613629, 45819.0, 45903.0, 45935.77032114851, 45938.54061459376, 3603.09064664839, 3774.7735135891935, 3930.6265904739003, 4002.2795896738576, 4369.082492676542, 34704.305913881835, 34781.47827782585, 34874.30251394958, 34923.0, 35029.29941401183, 4390.27560452525, 4411.117776444692, 4436.822527098912, 4438.822447102112, 4512.368268442421, 393620.0, 395553.02223488985, 395602.0, 396059.0, 398060.0, 63213.683931580046, 63538.0469292961, 63638.0, 63744.68127659332, 63874.36125638777, 39061.80469097693, 39485.0, 39509.20981580352, 42114.0, 42125.57874421278, 17772.0, 17906.462806115836, 17906.731399029024, 17916.193771280323, 17918.104094795468, 10658.467076646137, 13554.661133471742, 14637.853621463863, 14869.330880110205, 14944.925275373553, 20502.282420115225, 20523.179072837218, 20759.169633214504, 20766.0, 20771.792282077287, 7174.713011479483, 7495.250474952571, 7563.621818909032, 7571.523552907241, 7704.5762483062845, 979.2655508368723, 1096.8848270931437, 1124.656979621207, 1135.5401062569672, 1152.815549512074, 34237.82881085579, 35006.64993350034, 35118.29763404718, 35119.64880351215, 35185.82407087999, 1127.9566401079132, 1143.4911464398263, 1148.8965993060665, 1165.854268216473, 1174.2015429508017, 18020.28807263308, 18107.637847243244, 18250.726239106425, 18349.633007339777, 18487.16807743655, 647312.0, 654071.0, 655204.0, 656672.0, 658429.0, 62874.37125628685, 63257.68371158115, 63429.0, 63489.0, 63529.364706352346, 1275.7448510297793, 1399.258393051664, 1436.719839631262, 1450.6445920749484, 1462.6635873748885, 33854.830725846216, 35292.47061294031, 35414.29171416609, 35580.82209588936, 35581.0, 52694.20958685546, 53285.7335713319, 53443.0, 53493.46506534885, 53563.73218133884, 452348.0, 453563.0, 453780.0, 454017.0, 454603.0, 24954.34821025309, 27192.456150877268, 27253.182404527895, 29808.403831922806, 31210.68789312078, 49625.255621164986, 51118.0, 51927.0, 52323.21515177199, 52504.73747631237, 43210.78394608007, 44165.77917110523, 44395.77802110969, 44802.3279650799, 45489.31766023446, 43126.78436607922, 44217.33673994957, 44757.328640069776, 45032.549674502836, 45571.54428455673, 43718.56281437145, 44529.777351113036, 44540.77729611331, 44664.0, 45200.547994519635, 36006.0, 38748.6125138745, 39580.0, 39750.0, 40072.3989140169, 28760.712392875805, 29070.0, 29088.41823163567, 29375.265618359714, 29432.705672942997], "value": 21653.418850768478, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [427.8074866310161, 428.22775445009006, 433.25263919738444, 439.70320033977066, 444.1782702001296, 18944.905275473622, 20396.796032039656, 20483.283085092007, 21265.57468850622, 21601.783982160174, 19513.804861951376, 19849.900750496265, 20703.585928281424, 20922.372328830123, 20966.37100886976, 5780.450857168573, 6012.0, 6027.78902738402, 6029.969850150743, 6034.939650603482, 5884.6763428011445, 5900.704964751745, 6126.632402055869, 6157.0, 6267.937320626804, 6027.7287522061615, 6236.906446403307, 6239.469645080174, 6245.594036387643, 6248.6875656217235, 6119.785807496739, 6169.413905678954, 6210.720517576698, 6226.937730622681, 6255.155554000197, 5871.442212989759, 6047.485963693091, 6068.453839154453, 6072.149899014142, 6105.389461053904, 14811.111333319928, 14983.101013939198, 15005.24973751308, 19732.605347893106, 24460.510789784283, 2333.381653861728, 2394.4492766663766, 2560.6927168739685, 2676.330917270682, 2753.6282601848893, 10158.796824063478, 10771.946140269327, 10929.508172132273, 11042.503087361089, 15357.232138393036, 2022.4842665120448, 2035.9898200508903, 2036.9592608147902, 2042.9489262768402, 2079.5443189767225, 82057.58971205166, 85402.57298713528, 86257.13742862554, 86820.56589717075, 88308.0, 11519.596814111466, 11649.941750291364, 11664.300141991507, 11862.525498979945, 11982.502187226597, 16770.245338959776, 16779.664406711796, 16815.327386904388, 16826.747598786027, 16853.410130645367, 8339.45793523419, 8613.26787223093, 9083.273338132933, 9142.680006199751, 9209.21721653667, 8917.464952102764, 8932.955335223412, 8974.19232269099, 9124.81750364989, 9177.265818734486, 5062.670926389773, 5090.312807770978, 5100.438951715309, 5102.836270928778, 5270.49930256625, 1996.160846602931, 2028.7971202879594, 2036.1651722793583, 2069.968950465744, 2117.5764847030655, 927.735595355334, 1014.4319181258371, 1052.1898138433517, 1053.089077947585, 1095.2442814457997, 20030.0, 20173.293934712216, 20325.0, 20675.58648827015, 20762.896185518977, 956.9665061722807, 1020.8621836052187, 1039.3889471319424, 1063.824468962628, 1096.0683419093707, 10756.838647420294, 11477.426128693533, 11591.304521728724, 11595.652130436101, 11619.419029048513, 514322.0, 516893.0, 518884.0, 530809.0, 532413.0, 78503.60748196223, 83024.0, 98390.0, 102052.0, 102672.48663756634, 1038.5222797513077, 1042.7028296935337, 1078.8975047370486, 1078.9676309710721, 1081.070279559594, 18439.0, 18792.436226913214, 18863.622727545648, 18898.244070237586, 19001.80998190056, 44110.0, 44259.33610995902, 44630.3305450425, 44661.330080049476, 44730.77634611806, 58238.4176158233, 61554.69222653858, 61637.0, 62937.685311574984, 64082.0, 8424.663013479638, 8521.275691566287, 8614.18165274298, 8653.610587523703, 8912.064233255674, 9675.419474831533, 11722.531098755955, 12855.935720321339, 12986.805197922227, 13159.736805264032, 6378.266499352634, 11782.823257651313, 11864.830288250234, 11918.821217681569, 11960.820587691018, 11452.94273528627, 11458.0, 11561.248518846249, 11766.52933882635, 11988.820167697666, 11820.349880756297, 11890.702732431759, 12027.338496382434, 12225.633231003085, 12243.69390765238, 8692.043875173853, 8781.956090219508, 8868.512231827053, 8906.821863562822, 9076.047015063323, 11076.778464430505, 11873.346965916957, 11987.34069626179, 11990.340531270514, 12097.334646594521], "value": 9629.753873528745, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1412.3079690951433, 1489.3595753825855, 1539.4227164813194, 1725.2236493577877, 1765.823417658235, 9137.406068605536, 9813.950930245348, 9826.410415375074, 9827.262955278347, 9827.75430614235, 7865.842683146334, 9191.402558833672, 9399.953000235006, 9415.57629906654, 9464.952675236615, 2466.371075375777, 2487.502499500098, 2490.2653717153485, 2494.226789695191, 2494.463690306584, 2469.839460435075, 2538.352720056383, 2541.796656267494, 2543.0972004938258, 2546.096135871767, 2499.8875050622673, 2513.949721005579, 2536.087008676875, 2537.4544472938314, 2538.733432989528, 2435.1233555919853, 2444.841085329448, 2446.0704932125855, 2491.3397949543387, 2492.5887228607257, 2420.673209116782, 2432.3797431654816, 2435.561598912195, 2450.0812195426715, 2455.9263222103364, 9547.761305967337, 9654.082862128087, 9671.177949874269, 9705.417674939526, 9720.513974301257, 5664.915026274609, 5813.186153938452, 5814.825555233349, 5821.53427725781, 5823.039198532237, 3432.7940323580665, 3533.752637315389, 3550.9822450887837, 3572.1783989682517, 3578.76738012031, 1066.9679909602646, 1102.862142232221, 1105.1214284643727, 1149.0635132367095, 1150.0799360511558, 13565.593032208952, 13590.45638174462, 13595.184288942695, 13595.388207530586, 13597.932010339984, 4799.592034677022, 4911.45973942866, 4931.5315045070665, 5002.649814513022, 5035.974820125876, 11276.32342059479, 11301.0, 11307.773844523228, 11324.716882078013, 11326.37704926237, 5745.109508026263, 5763.337216220105, 5765.279340082489, 5768.307803063659, 5791.189233507313, 4425.867223983286, 4447.90988959688, 4896.461389247252, 5200.687958722413, 5318.441563635763, 4306.5262821089655, 4315.395844581761, 4331.5884990925815, 4408.404865343202, 4432.623227025739, 1353.5635855460132, 1370.41757253167, 1380.5030189131905, 1382.2052319916088, 1383.9723205535834, 952.9904700953041, 975.6146322202691, 991.2813210422386, 993.3841018568534, 994.1649014827435, 6025.367336429699, 6145.600535965237, 6206.479412543866, 6258.249010118815, 6584.0, 921.138735282513, 926.073926073926, 1000.2048371544639, 1040.5837664934088, 1058.433737950193, 4309.806058727365, 4431.180231657154, 4483.75339356332, 4489.191945449785, 4521.050579378368, 11911.344876031728, 12326.0, 12326.81509777354, 12332.50669973191, 12364.072694547946, 11205.60780372683, 11753.4710938008, 12137.939310303569, 12139.393030348447, 12146.45340959659, 8783.824323513621, 8994.235489983424, 9019.458832470073, 9024.187823095954, 9025.41334813235, 6205.2553693557065, 6229.96885015572, 6319.8104056878365, 6396.808095757135, 6461.127747753993, 11153.776924461628, 11180.217384782987, 11233.71915702114, 11270.83093753578, 11336.716582085513, 10532.473376331, 11035.448227588427, 11063.944680276547, 11137.10903127764, 11235.269707468988, 7605.201453847267, 8128.309093726863, 8271.421000529905, 8318.45930014547, 8441.83116337682, 8795.208431240962, 9046.959599645861, 9051.909480905106, 9052.818943621223, 9057.68298109563, 7909.604519773873, 8036.808902433651, 8134.796630084295, 8247.95876020616, 8394.748157555283, 8004.239597238253, 8089.595520224083, 8121.878171827309, 8142.47073940192, 8270.2556769891, 7275.163356213891, 7405.333519983227, 7467.738629147953, 7473.364763994904, 7512.774616761506, 5512.614117011771, 5596.692181930033, 5679.460451257124, 5783.103618939072, 5827.79602713903, 5374.274472946102, 5388.407275199646, 5445.7549410277425, 5470.72646367688, 5696.20253164549], "value": 4967.575687725996, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [345.4369377914, 353.70112255144403, 355.1334743226527, 358.86183819229586, 364.74285628631816, 5419.729013549317, 5456.590755693329, 5504.779808807648, 5512.1456174293, 5526.82207730555, 5147.48525147486, 5187.221916712495, 5265.526102650761, 5303.973480132594, 5402.9729851350785, 4242.490901091874, 4288.97855510722, 4302.290122129845, 4313.849015284466, 4321.573880619388, 4285.35719642053, 4292.527821939584, 4297.228287754397, 4300.053988122608, 4325.675574331922, 4125.979370103145, 4247.766372849492, 4283.421738065368, 4291.892702682427, 4337.826486940535, 4234.407182994383, 4251.27728286189, 4263.531011588722, 4269.807858646368, 4284.207421627009, 3785.1483416231404, 3788.1855401088624, 3792.924141517182, 3802.6007269236607, 3805.9809700951596, 5236.162214045772, 5248.475152484721, 5299.8410047698635, 5326.627136100475, 5375.16684913839, 4836.51634836517, 4836.879078023042, 4851.466338702775, 4889.902201955941, 4890.559849613552, 5608.691521966291, 5629.60592758507, 5664.745086471119, 5673.971630141823, 5698.316202055738, 4045.049413387881, 4100.405441211008, 4110.917781644351, 4151.878992671992, 4162.708610397273, 5818.738156782955, 5845.298564172284, 5848.134414137042, 5851.912221316684, 5854.882902341972, 4221.514525829569, 4234.280172370704, 4246.299360605527, 4248.660107191417, 4261.786910654455, 4136.296829538986, 4258.0, 4280.400743895857, 4284.957150428519, 4299.828006879752, 4805.495422980606, 4809.302651115639, 4830.2513110467935, 4857.757112144379, 4862.5380588844, 5662.886742265132, 5662.971685141548, 5718.170865224519, 5730.656160630376, 5765.472149880285, 1398.1331574423923, 1448.2396741710475, 1460.2844606142914, 1469.7060587882465, 1471.249662672045, 917.5045475443186, 934.0245381019096, 948.0140653720053, 950.7955789505152, 953.537534295866, 4860.760506071, 4881.219004959261, 4902.558769710743, 4909.741518901345, 4951.777170027357, 5063.164577844688, 5076.390833100052, 5079.923801142986, 5080.796768129308, 5105.706819101732, 4856.514348565186, 4877.731724755173, 4889.511048895082, 4920.261960705851, 5187.896242075213, 3041.6197975253094, 3088.181631867557, 3088.7374573161082, 3090.7218350348576, 3105.6739042400673, 6061.212042434543, 6061.696915154224, 6069.4522896661065, 6089.208402907594, 6104.084387341845, 5691.26013618227, 5912.979174161612, 5945.851353716192, 5988.6706231157705, 5999.940000599938, 6074.787382441594, 6077.301110372364, 6081.179040829431, 6082.361352058058, 6098.786542471081, 5577.553795696415, 5817.2146760188525, 5864.530837532901, 5892.0, 5917.497012753965, 5778.393268706725, 5787.56593255516, 5789.33422656383, 5795.768169273182, 5800.216970708901, 6092.141896721542, 6104.481119104926, 6104.542159337979, 6109.358517355791, 6118.195132436031, 3983.50206224222, 4007.6574347593883, 4197.349410841324, 4198.6011328923705, 4213.873583792491, 5697.544196464356, 5774.0, 5812.593118481665, 5825.417458254141, 5826.504747096545, 5649.4633009864, 5701.857453563694, 5711.228984087261, 5723.170140329712, 5766.913496297643, 5428.0, 5707.68607726579, 5709.914351284651, 5711.714414279187, 5730.942690573208, 5652.68910209926, 5656.688882111359, 5671.262735844314, 5678.3186017678145, 5679.801206957736, 5639.54883609302, 5737.196792449144, 5738.397468265772, 5741.392410125171, 5762.7694892203845, 5519.144532597294, 5521.309836270466, 5605.915911261416, 5607.214989901336, 5636.464535869086], "value": 4317.479513977559, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [796.2197046894044, 804.5373910001749, 810.9432339736221, 825.8885050518182, 851.4550687559963, 14459.421623135075, 15518.534443966684, 15528.68942621147, 15544.53366399007, 15564.53306400807, 14515.564533063998, 14854.257287135628, 14857.108573485599, 14859.0, 14880.0, 714.5248409807472, 847.656699036888, 848.7878030492377, 868.0104680664048, 872.7469033980138, 872.4329186029069, 874.5364956573027, 875.0811647769848, 875.1685898396516, 875.1991927386434, 928.6099838068028, 929.8186853563558, 930.0978051290238, 930.4463844012802, 931.2968708624978, 916.2395211974078, 916.6058594804232, 916.8624706294042, 917.0691747875911, 917.2203626917134, 676.9289224631392, 678.2742465561802, 680.1701923653135, 680.4318394140898, 681.7307163670372, 14329.641758956006, 14333.140011599337, 14348.64128396788, 14485.710285794225, 14502.927485362612, 15118.09291442506, 15254.23728813555, 15766.527004189893, 15798.526044218692, 15888.28502717369, 12959.157654752411, 13148.539801106916, 13183.14309569875, 13254.66863328415, 13582.388792504362, 4087.591240875918, 4203.264428724991, 4228.978855105735, 4283.271843786564, 4336.067745434715, 82485.0, 83661.58169209176, 83682.0, 84310.57844710798, 84686.0, 11255.549778008788, 12114.394280285951, 12271.815922761165, 12376.566820161252, 12383.938080309541, 21307.0, 21318.360449186548, 21331.46671333198, 21369.572608548053, 21374.251901183696, 8624.956875215585, 8648.524331161721, 8766.91233087661, 8796.780080498038, 8902.55487225649, 4531.546845315508, 4794.0, 4812.8796780080775, 4820.39745031871, 4912.066707325596, 3922.1763429680086, 4110.506739191316, 4123.484564429446, 4125.752454852718, 4128.483939507561, 682.5665702279089, 688.6212583079387, 691.3086913086913, 693.0643631097977, 693.6427739714052, 664.5281849886635, 727.1637616740791, 730.0691618186854, 732.5244835652749, 753.2392283793408, 9204.447733136034, 9301.674441394518, 9875.310724875082, 10342.327748696462, 10620.309679870783, 692.9861402772017, 693.9375456208966, 781.2460975158999, 785.7721260834409, 803.3894240377239, 4882.853514394574, 5108.0, 5112.923306150411, 5172.19830926207, 5186.196139598368, 78030.6098469504, 99687.0, 140153.29923350317, 140311.0, 145995.0, 58961.705191474626, 59118.0, 59878.10182847261, 81842.0, 117625.0, 54284.0, 54634.0, 54758.72620636951, 83796.58101709452, 88358.0, 9079.182873541413, 9130.413116061141, 9601.203013509104, 9748.561314740711, 9990.450525221182, 54306.72846635742, 55830.72084639551, 55980.72009639926, 56138.0, 56479.435205647416, 71674.0, 76787.61606192157, 78207.21792782228, 79910.60491673014, 80326.59836700778, 14081.929590351983, 14158.92920535432, 14401.567952961428, 14570.635734106732, 14578.34397452096, 15088.698226035638, 15492.690146197237, 15641.0, 16528.338866445207, 17245.0, 14348.426062957366, 14885.479008235094, 15307.631409921032, 15446.150461724714, 15479.690406191588, 13635.113717608323, 14082.084664496775, 14375.712485750017, 14407.06354086981, 14675.339609717801, 13349.733005340033, 13653.86346136526, 14242.430302787772, 14361.425542978583, 14419.85580144185, 12098.637040888787, 12748.61754147377, 12824.0, 13116.147450415692, 13347.733045338844, 11547.538098475967, 12078.577249796215, 12261.448234829633, 12412.81380779271, 12562.68170862035], "value": 7876.2716524196385, "unit": "score"}], "name": "dromaeo_css", "value": 7614.461580816664, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 110.0, 120.0, 109.0, 109.0, 111.0, 110.0, 111.0, 110.0, 111.0], "value": 111.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 118.0, 146.0, 169.0, 117.0, 140.0, 141.0, 121.0, 184.0, 118.0], "value": 140.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 161.0, 161.0, 162.0, 161.0, 160.0, 161.0, 161.0, 163.0], "value": 161.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 73.0, 76.0, 167.0, 76.0, 76.0, 74.0, 75.0, 160.0, 75.0], "value": 92.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 156.0, 83.0, 85.0, 84.0, 142.0, 82.0, 84.0, 140.0], "value": 102.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 117.0, 116.0, 116.0, 116.0, 116.0, 116.0, 117.0, 117.0], "value": 116.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 147.0, 147.0, 147.0, 147.0, 146.0, 147.0, 146.0, 146.0], "value": 146.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 94.0, 95.0, 94.0, 95.0, 95.0, 95.0, 94.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 56.0, 50.0, 49.0, 52.0, 51.0, 50.0, 51.0, 51.0], "value": 51.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 62.0, 61.0, 62.0, 61.0, 61.0, 61.0, 61.0, 61.0], "value": 61.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 75.0, 72.0, 72.0, 77.0, 73.0, 72.0, 73.0, 83.0, 72.0], "value": 74.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 117.0, 120.0, 119.0, 118.0, 117.0, 118.0, 118.0, 159.0, 119.0], "value": 122.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 152.0, 152.0, 158.0, 153.0, 151.0, 150.0, 152.0, 155.0, 157.0], "value": 153.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 67.0, 62.0, 62.0, 61.0, 63.0, 61.0, 62.0, 59.0, 60.0], "value": 61.9, "unit": "score"}], "name": "kraken", "value": 1489.0, "alertThreshold": 2.0}]} 22:42:59 INFO - Return code: 0 22:42:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:42:59 INFO - # TBPL SUCCESS # 22:42:59 INFO - Running post-action listener: _resource_record_post_action 22:42:59 INFO - [mozharness: 2016-09-22 05:42:59.368929Z] Finished run-tests step (success) 22:42:59 INFO - Running post-run listener: _resource_record_post_run 22:42:59 INFO - Total resource usage - Wall time: 1185s; CPU: 13.0%; Read bytes: 4096; Write bytes: 279142400; Read time: 16; Write time: 192912 22:42:59 INFO - TinderboxPrint: CPU usage
12.9% 22:42:59 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 22:42:59 INFO - TinderboxPrint: I/O write bytes / time
279,142,400 / 192,912 22:42:59 INFO - TinderboxPrint: CPU idle
8,240.2 (87.1%) 22:42:59 INFO - TinderboxPrint: CPU user
1,177.7 (12.4%) 22:42:59 INFO - TinderboxPrint: Swap in / out
0 / 0 22:42:59 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 71249920; Read time: 0; Write time: 38736 22:42:59 INFO - run-tests - Wall time: 1171s; CPU: 13.0%; Read bytes: 0; Write bytes: 207892480; Read time: 0; Write time: 154176 22:42:59 INFO - Running post-run listener: _upload_blobber_files 22:42:59 INFO - Blob upload gear active. 22:42:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:42:59 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 22:42:59 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-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:42:59 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-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:43:00 INFO - (blobuploader) - INFO - Open directory for files ... 22:43:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:43:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:43:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:43:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:43:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:43:01 INFO - (blobuploader) - INFO - Done attempting. 22:43:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:43:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:43:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:43:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:43:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:43:02 INFO - (blobuploader) - INFO - Done attempting. 22:43:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:43:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:43:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:43:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:43:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:43:08 INFO - (blobuploader) - INFO - Done attempting. 22:43:08 INFO - (blobuploader) - INFO - Iteration through files over. 22:43:08 INFO - Return code: 0 22:43:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:43:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:43:08 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c9b9ff4630264b5102b2a4c71cc77096e71e440d051e8a9e47824cd6df170874bb1d9a2c249a225dc5a602f7570b846c8f0879bec16898a17bd965ef55d9302b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3c67b1df07b23e73f62cc3cefab2a11114e0999c8352f83bf860bb71fcd36763486ae4fc7bc132358b8dc2fca793839a83549e9958f127cae5acb8e41a9f3809", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/949d555266e3ff37e4022ab9f844c44d6b9036016f150ad59205e608de2e75e79ee168d8997f25cda6299e6ee195dfa46883603e8d77b276abbb0683b261b397"} 22:43:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:43:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:43:08 INFO - Contents: 22:43:08 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c9b9ff4630264b5102b2a4c71cc77096e71e440d051e8a9e47824cd6df170874bb1d9a2c249a225dc5a602f7570b846c8f0879bec16898a17bd965ef55d9302b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3c67b1df07b23e73f62cc3cefab2a11114e0999c8352f83bf860bb71fcd36763486ae4fc7bc132358b8dc2fca793839a83549e9958f127cae5acb8e41a9f3809", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/949d555266e3ff37e4022ab9f844c44d6b9036016f150ad59205e608de2e75e79ee168d8997f25cda6299e6ee195dfa46883603e8d77b276abbb0683b261b397"} 22:43:08 INFO - Running post-run listener: copy_logs_to_upload_dir 22:43:08 INFO - Copying logs to upload dir... 22:43:08 INFO - mkdir: /builds/slave/test/build/upload/logs 22:43:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1677.127412 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 57 secs) (at 2016-09-21 22:43:08.030689) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-21 22:43:08.034098) ========= 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=0333a256a5e4f052382dd180000001b1-1474521280.714703-1036006715 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-Beta/sha512/c9b9ff4630264b5102b2a4c71cc77096e71e440d051e8a9e47824cd6df170874bb1d9a2c249a225dc5a602f7570b846c8f0879bec16898a17bd965ef55d9302b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3c67b1df07b23e73f62cc3cefab2a11114e0999c8352f83bf860bb71fcd36763486ae4fc7bc132358b8dc2fca793839a83549e9958f127cae5acb8e41a9f3809", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/949d555266e3ff37e4022ab9f844c44d6b9036016f150ad59205e608de2e75e79ee168d8997f25cda6299e6ee195dfa46883603e8d77b276abbb0683b261b397"} build_url:https://queue.taskcluster.net/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011457 build_url: 'https://queue.taskcluster.net/v1/task/VUvTZINfTUmh2xwPkwMQ7g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c9b9ff4630264b5102b2a4c71cc77096e71e440d051e8a9e47824cd6df170874bb1d9a2c249a225dc5a602f7570b846c8f0879bec16898a17bd965ef55d9302b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3c67b1df07b23e73f62cc3cefab2a11114e0999c8352f83bf860bb71fcd36763486ae4fc7bc132358b8dc2fca793839a83549e9958f127cae5acb8e41a9f3809", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/949d555266e3ff37e4022ab9f844c44d6b9036016f150ad59205e608de2e75e79ee168d8997f25cda6299e6ee195dfa46883603e8d77b276abbb0683b261b397"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-21 22:43:08.064190) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 22:43:08.065052) ========= 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=0333a256a5e4f052382dd180000001b1-1474521280.714703-1036006715 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004452 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 22:43:08.147779) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-21 22:43:08.148496) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-21 22:43:08.149207) ========= ========= Total master_lag: 0.18 =========