builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-059 starttime: 1473590148.75 results: success (0) buildid: 20160911004011 builduid: a215e513ab524819816f7fde56c95a64 revision: 8663d41e015f3c9933a08a65c8349b86f2263354 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-11 03:35:48.750847) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-11 03:35:48.751338) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-11 03:35:48.751672) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-11 03:35:48.762668) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-11 03:35:48.762968) ========= 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=3a3620fb28aea1604ab609ef000001d2-1473590150.41679-1659892745 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-11 03:35:50-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 111M=0s 2016-09-11 03:35:50 (111 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.087122 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-11 03:35:48.861911) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-11 03:35:48.862272) ========= 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=3a3620fb28aea1604ab609ef000001d2-1473590150.41679-1659892745 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018078 ========= master_lag: 0.14 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-11 03:35:49.015504) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-11 03:35:49.015910) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 8663d41e015f3c9933a08a65c8349b86f2263354 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 8663d41e015f3c9933a08a65c8349b86f2263354 --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=3a3620fb28aea1604ab609ef000001d2-1473590150.41679-1659892745 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-11 03:35:51,070 truncating revision to first 12 chars 2016-09-11 03:35:51,070 Setting DEBUG logging. 2016-09-11 03:35:51,070 attempt 1/10 2016-09-11 03:35:51,070 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/8663d41e015f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-11 03:35:51,616 unpacking tar archive at: mozilla-aurora-8663d41e015f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.862931 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-11 03:35:49.892653) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-11 03:35:49.893009) ========= script_repo_revision: 8663d41e015f3c9933a08a65c8349b86f2263354 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-11 03:35:49.893450) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-11 03:35:49.893777) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-11 03:35:49.903192) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 42 secs) (at 2016-09-11 03:35:49.903500) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=3a3620fb28aea1604ab609ef000001d2-1473590150.41679-1659892745 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:35:52 INFO - MultiFileLogger online at 20160911 03:35:52 in /builds/slave/test 03:35:52 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 03:35:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:35:52 INFO - {'append_to_log': False, 03:35:52 INFO - 'base_work_dir': '/builds/slave/test', 03:35:52 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:35:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:35:52 INFO - 'branch': 'Mozilla-Aurora', 03:35:52 INFO - 'buildbot_json_path': 'buildprops.json', 03:35:52 INFO - 'config_files': ('talos/linux_config.py',), 03:35:52 INFO - 'default_actions': ('clobber', 03:35:52 INFO - 'read-buildbot-config', 03:35:52 INFO - 'download-and-extract', 03:35:52 INFO - 'populate-webroot', 03:35:52 INFO - 'create-virtualenv', 03:35:52 INFO - 'install', 03:35:52 INFO - 'run-tests'), 03:35:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:35:52 INFO - 'download_minidump_stackwalk': True, 03:35:52 INFO - 'download_symbols': 'ondemand', 03:35:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:35:52 INFO - 'tooltool.py': '/tools/tooltool.py', 03:35:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:35:52 INFO - '/tools/misc-python/virtualenv.py')}, 03:35:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:35:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:35:52 INFO - 'installer_path': 'installer.exe', 03:35:52 INFO - 'log_level': 'info', 03:35:52 INFO - 'log_name': 'talos', 03:35:52 INFO - 'log_to_console': True, 03:35:52 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:35:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:35:52 INFO - 'opt_config_files': (), 03:35:52 INFO - 'pip_index': False, 03:35:52 INFO - 'sps_profile': False, 03:35:52 INFO - 'sps_profile_interval': 0, 03:35:52 INFO - 'suite': 'dromaeojs', 03:35:52 INFO - 'system_bits': '32', 03:35:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:35:52 INFO - 'title': 'talos-linux64-ix-059', 03:35:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:35:52 INFO - 'use_talos_json': True, 03:35:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:35:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:35:52 INFO - 'work_dir': 'build'} 03:35:52 INFO - [mozharness: 2016-09-11 10:35:52.049783Z] Running clobber step. 03:35:52 INFO - Running pre-action listener: _resource_record_pre_action 03:35:52 INFO - Running main action method: clobber 03:35:52 INFO - rmtree: /builds/slave/test/build 03:35:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:35:52 INFO - Running post-action listener: _resource_record_post_action 03:35:52 INFO - [mozharness: 2016-09-11 10:35:52.510181Z] Finished clobber step (success) 03:35:52 INFO - [mozharness: 2016-09-11 10:35:52.510282Z] Running read-buildbot-config step. 03:35:52 INFO - Running pre-action listener: _resource_record_pre_action 03:35:52 INFO - Running main action method: read_buildbot_config 03:35:52 INFO - Using buildbot properties: 03:35:52 INFO - { 03:35:52 INFO - "project": "", 03:35:52 INFO - "product": "firefox", 03:35:52 INFO - "script_repo_revision": "production", 03:35:52 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 03:35:52 INFO - "repository": "", 03:35:52 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 03:35:52 INFO - "buildid": "20160911004011", 03:35:52 INFO - "stage_platform": "linux64-pgo", 03:35:52 INFO - "basedir": "/builds/slave/test", 03:35:52 INFO - "nightly_build": "True", 03:35:52 INFO - "buildnumber": 745, 03:35:52 INFO - "slavename": "talos-linux64-ix-059", 03:35:52 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 03:35:52 INFO - "platform": "ubuntu64_hw", 03:35:52 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 03:35:52 INFO - "branch": "mozilla-aurora", 03:35:52 INFO - "repo_path": "releases/mozilla-aurora", 03:35:52 INFO - "revision": "8663d41e015f3c9933a08a65c8349b86f2263354", 03:35:52 INFO - "pgo_build": "True", 03:35:52 INFO - "builduid": "a215e513ab524819816f7fde56c95a64", 03:35:52 INFO - "slavebuilddir": "test" 03:35:52 INFO - } 03:35:52 INFO - Found installer url https://queue.taskcluster.net/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 03:35:52 INFO - Running post-action listener: _resource_record_post_action 03:35:52 INFO - [mozharness: 2016-09-11 10:35:52.512796Z] Finished read-buildbot-config step (success) 03:35:52 INFO - [mozharness: 2016-09-11 10:35:52.512886Z] Running download-and-extract step. 03:35:52 INFO - Running pre-action listener: _resource_record_pre_action 03:35:52 INFO - Running main action method: download_and_extract 03:35:52 INFO - mkdir: /builds/slave/test/build/tests 03:35:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:35:52 INFO - https://queue.taskcluster.net/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 03:35:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:35:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:35:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:35:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 03:35:52 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:35:52 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:35:52 INFO - retry: Failed, sleeping 30 seconds before retrying 03:36:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #2 03:36:22 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:36:22 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:36:22 INFO - retry: Failed, sleeping 60 seconds before retrying 03:37:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #3 03:37:22 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:37:22 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:37:22 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json! 03:37:22 INFO - Caught exception: HTTP Error 404: Not Found 03:37:22 INFO - Caught exception: HTTP Error 404: Not Found 03:37:22 INFO - Caught exception: HTTP Error 404: Not Found 03:37:22 INFO - trying https://queue.taskcluster.net/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:37:22 INFO - Downloading https://queue.taskcluster.net/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:37:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 03:37:23 INFO - Downloaded 1361 bytes. 03:37:23 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:37:23 INFO - Using the following test package requirements: 03:37:23 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 03:37:23 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:37:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 03:37:23 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:37:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 03:37:23 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:37:23 INFO - u'jsshell-linux-x86_64.zip'], 03:37:23 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:37:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 03:37:23 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 03:37:23 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:37:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 03:37:23 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:37:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 03:37:23 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:37:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 03:37:23 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:37:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 03:37:23 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:37:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:37:23 INFO - https://queue.taskcluster.net/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:37:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:37:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:37:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:37:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:37:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:37:23 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:37:23 INFO - retry: Failed, sleeping 30 seconds before retrying 03:37:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 03:37:53 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:37:53 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:37:53 INFO - retry: Failed, sleeping 60 seconds before retrying 03:38:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 03:38:53 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:38:53 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:38:53 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 03:38:53 INFO - Caught exception: HTTP Error 404: Not Found 03:38:53 INFO - Caught exception: HTTP Error 404: Not Found 03:38:53 INFO - Caught exception: HTTP Error 404: Not Found 03:38:53 INFO - trying https://queue.taskcluster.net/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:38:53 INFO - Downloading https://queue.taskcluster.net/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:38:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:38:54 INFO - Downloaded 21770552 bytes. 03:38:54 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:38:54 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 03:38:55 INFO - Return code: 0 03:38:55 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 03:38:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:38:55 INFO - https://queue.taskcluster.net/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:38:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:38:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:38:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:38:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:38:55 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:38:55 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:38:55 INFO - retry: Failed, sleeping 30 seconds before retrying 03:39:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 03:39:25 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:39:25 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:39:25 INFO - retry: Failed, sleeping 60 seconds before retrying 03:40:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 03:40:25 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:40:25 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:40:25 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 03:40:25 INFO - Caught exception: HTTP Error 404: Not Found 03:40:25 INFO - Caught exception: HTTP Error 404: Not Found 03:40:25 INFO - Caught exception: HTTP Error 404: Not Found 03:40:25 INFO - trying https://queue.taskcluster.net/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:40:25 INFO - Downloading https://queue.taskcluster.net/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:40:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:40:26 INFO - Downloaded 21770552 bytes. 03:40:26 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:40:26 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 03:40:27 INFO - Return code: 0 03:40: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')]} 03:40:27 INFO - https://queue.taskcluster.net/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 03:40:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:40:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:40:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:40:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 03:40:27 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:40:27 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:40:27 INFO - retry: Failed, sleeping 30 seconds before retrying 03:40:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 03:40:57 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:40:57 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:40:57 INFO - retry: Failed, sleeping 60 seconds before retrying 03:41:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 03:41:57 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:41:57 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:41:57 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip! 03:41:57 INFO - Caught exception: HTTP Error 404: Not Found 03:41:57 INFO - Caught exception: HTTP Error 404: Not Found 03:41:57 INFO - Caught exception: HTTP Error 404: Not Found 03:41:57 INFO - trying https://queue.taskcluster.net/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:41:57 INFO - Downloading https://queue.taskcluster.net/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:41:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 03:41:58 INFO - Downloaded 14413740 bytes. 03:41:58 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:41:58 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 03:41:58 INFO - Return code: 0 03:41:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:41:58 INFO - https://queue.taskcluster.net/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 03:41:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:41:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:41:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:41:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 03:41:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:41:58 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:41:58 INFO - retry: Failed, sleeping 30 seconds before retrying 03:42:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #2 03:42:28 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:42:28 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:42:28 INFO - retry: Failed, sleeping 60 seconds before retrying 03:43:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #3 03:43:28 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:43:28 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:43:28 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2! 03:43:28 INFO - Caught exception: HTTP Error 404: Not Found 03:43:28 INFO - Caught exception: HTTP Error 404: Not Found 03:43:28 INFO - Caught exception: HTTP Error 404: Not Found 03:43:28 INFO - trying https://queue.taskcluster.net/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:43:28 INFO - Downloading https://queue.taskcluster.net/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:43:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 03:43:30 INFO - Downloaded 63886264 bytes. 03:43:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:43:30 INFO - mkdir: /builds/slave/test/properties 03:43:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:43:30 INFO - Writing to file /builds/slave/test/properties/build_url 03:43:30 INFO - Contents: 03:43:30 INFO - build_url:https://queue.taskcluster.net/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:43:30 INFO - Running post-action listener: _resource_record_post_action 03:43:30 INFO - Running post-action listener: set_extra_try_arguments 03:43:30 INFO - [mozharness: 2016-09-11 10:43:30.783199Z] Finished download-and-extract step (success) 03:43:30 INFO - [mozharness: 2016-09-11 10:43:30.783432Z] Running populate-webroot step. 03:43:30 INFO - Running pre-action listener: _resource_record_pre_action 03:43:30 INFO - Running main action method: populate_webroot 03:43:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:43:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:43:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:43:30 INFO - '--tppagecycles', 03:43:30 INFO - '7'], 03:43:30 INFO - 'tests': ['tsvgm']}}, 03:43:30 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:43:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:43:30 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:43:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:43:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:43:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:43:30 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:43:30 INFO - 'talos_options': ['--disable-e10s'], 03:43:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:43:30 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:43:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:43:30 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:43:30 INFO - 'talos_options': ['--disable-e10s'], 03:43:30 INFO - 'tests': ['damp', 'tps']}, 03:43:30 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:43:30 INFO - 'tests': ['damp', 'tps']}, 03:43:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:43:30 INFO - 'tests': ['dromaeo_dom']}, 03:43:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:43:30 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:43:30 INFO - 'tests': ['basic_compositor_video']}, 03:43:30 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:43:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:43:30 INFO - 'tests': ['a11yr', 03:43:30 INFO - 'ts_paint', 03:43:30 INFO - 'tpaint', 03:43:30 INFO - 'sessionrestore', 03:43:30 INFO - 'sessionrestore_no_auto_restore', 03:43:30 INFO - 'tabpaint']}, 03:43:30 INFO - 'other-e10s': {'tests': ['a11yr', 03:43:30 INFO - 'ts_paint', 03:43:30 INFO - 'tpaint', 03:43:30 INFO - 'sessionrestore', 03:43:30 INFO - 'sessionrestore_no_auto_restore', 03:43:30 INFO - 'tabpaint']}, 03:43:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:43:30 INFO - 'ts_paint', 03:43:30 INFO - 'tpaint', 03:43:30 INFO - 'sessionrestore', 03:43:30 INFO - 'sessionrestore_no_auto_restore', 03:43:30 INFO - 'tabpaint']}, 03:43:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:43:30 INFO - 'ts_paint', 03:43:30 INFO - 'tpaint', 03:43:30 INFO - 'sessionrestore', 03:43:30 INFO - 'sessionrestore_no_auto_restore', 03:43:30 INFO - 'tabpaint']}, 03:43:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:43:30 INFO - 'tests': ['a11yr', 03:43:30 INFO - 'ts_paint', 03:43:30 INFO - 'tpaint', 03:43:30 INFO - 'sessionrestore', 03:43:30 INFO - 'sessionrestore_no_auto_restore', 03:43:30 INFO - 'tabpaint']}, 03:43:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:43:30 INFO - 'tests': ['a11yr', 03:43:30 INFO - 'ts_paint', 03:43:30 INFO - 'tpaint', 03:43:30 INFO - 'sessionrestore', 03:43:30 INFO - 'sessionrestore_no_auto_restore', 03:43:30 INFO - 'tabpaint']}, 03:43:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:43:30 INFO - 'tests': ['tsvgx', 03:43:30 INFO - 'tsvgr_opacity', 03:43:30 INFO - 'tart', 03:43:30 INFO - 'tscrollx', 03:43:30 INFO - 'cart']}, 03:43:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:43:30 INFO - 'tsvgr_opacity', 03:43:30 INFO - 'tart', 03:43:30 INFO - 'tscrollx', 03:43:30 INFO - 'cart']}, 03:43:30 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:43:30 INFO - 'talos_options': ['--disable-e10s'], 03:43:30 INFO - 'tests': ['tp5o']}, 03:43:30 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:43:30 INFO - 'tests': ['tp5o']}, 03:43:30 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:43:30 INFO - 'talos_options': ['--disable-e10s', 03:43:30 INFO - '--xperf_path', 03:43:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:43:30 INFO - 'tests': ['tp5n']}, 03:43:30 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:43:30 INFO - 'talos_options': ['--xperf_path', 03:43:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:43:30 INFO - 'tests': ['tp5n']}}, 03:43:30 INFO - 'talos.zip': {'path': '', 03:43:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:43:30 INFO - Running post-action listener: _resource_record_post_action 03:43:30 INFO - [mozharness: 2016-09-11 10:43:30.802956Z] Finished populate-webroot step (success) 03:43:30 INFO - [mozharness: 2016-09-11 10:43:30.803046Z] Running create-virtualenv step. 03:43:30 INFO - Running pre-action listener: _resource_record_pre_action 03:43:30 INFO - Running main action method: create_virtualenv 03:43:30 INFO - Creating virtualenv /builds/slave/test/build/venv 03:43:30 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:43:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:43:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:43:30 INFO - Using real prefix '/usr' 03:43:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:43:31 INFO - Installing distribute.............................................................................................................................................................................................done. 03:43:33 INFO - Installing pip.................done. 03:43:33 INFO - Return code: 0 03:43:33 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:43:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:43:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:43:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:33 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 0xfbb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x111e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x127c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x127a3b0>, '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 0x127b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x127b740>, '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': '3a3620fb28aea1604ab609ef000001d2-1473590150.41679-1659892745', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:43:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:43:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:43:33 INFO - Using env: {'DISPLAY': ':0', 03:43:33 INFO - 'HOME': '/home/cltbld', 03:43:33 INFO - 'LANG': 'en_US.UTF-8', 03:43:33 INFO - 'LANGUAGE': 'en_US:en', 03:43:33 INFO - 'LOGNAME': 'cltbld', 03:43:33 INFO - 'MAIL': '/var/mail/cltbld', 03:43:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:33 INFO - 'MOZ_NO_REMOTE': '1', 03:43:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:43:33 INFO - 'NO_EM_RESTART': '1', 03:43:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:43:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:33 INFO - 'PWD': '/builds/slave/test', 03:43:33 INFO - 'SHELL': '/bin/bash', 03:43:33 INFO - 'SHLVL': '1', 03:43:33 INFO - 'TERM': 'linux', 03:43:33 INFO - 'TMOUT': '86400', 03:43:33 INFO - 'USER': 'cltbld', 03:43:33 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1473590150.41679-1659892745', 03:43:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:33 INFO - '_': '/tools/buildbot/bin/python'} 03:43:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:43:33 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 03:43:33 INFO - Cleaning up... 03:43:33 INFO - Return code: 0 03:43:33 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:43:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:43:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:43:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:33 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 0xfbb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x111e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x127c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x127a3b0>, '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 0x127b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x127b740>, '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': '3a3620fb28aea1604ab609ef000001d2-1473590150.41679-1659892745', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:43:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:43:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:43:33 INFO - Using env: {'DISPLAY': ':0', 03:43:33 INFO - 'HOME': '/home/cltbld', 03:43:33 INFO - 'LANG': 'en_US.UTF-8', 03:43:33 INFO - 'LANGUAGE': 'en_US:en', 03:43:33 INFO - 'LOGNAME': 'cltbld', 03:43:33 INFO - 'MAIL': '/var/mail/cltbld', 03:43:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:33 INFO - 'MOZ_NO_REMOTE': '1', 03:43:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:43:33 INFO - 'NO_EM_RESTART': '1', 03:43:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:43:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:33 INFO - 'PWD': '/builds/slave/test', 03:43:33 INFO - 'SHELL': '/bin/bash', 03:43:33 INFO - 'SHLVL': '1', 03:43:33 INFO - 'TERM': 'linux', 03:43:33 INFO - 'TMOUT': '86400', 03:43:33 INFO - 'USER': 'cltbld', 03:43:33 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1473590150.41679-1659892745', 03:43:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:33 INFO - '_': '/tools/buildbot/bin/python'} 03:43:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:43:33 INFO - Downloading/unpacking psutil>=3.1.1 03:43:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:43:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:43:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:43:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:43:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:43:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:43:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:43:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:43:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:43:35 INFO - Installing collected packages: psutil 03:43:35 INFO - Running setup.py install for psutil 03:43:35 INFO - building 'psutil._psutil_linux' extension 03:43:35 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 03:43:36 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 03:43:36 INFO - building 'psutil._psutil_posix' extension 03:43:36 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 03:43:36 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 03:43:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:43:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:43:36 INFO - Successfully installed psutil 03:43:36 INFO - Cleaning up... 03:43:36 INFO - Return code: 0 03:43:36 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:43:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:43:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:43:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:36 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 0xfbb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x111e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x127c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x127a3b0>, '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 0x127b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x127b740>, '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': '3a3620fb28aea1604ab609ef000001d2-1473590150.41679-1659892745', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:43:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:43:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:43:36 INFO - Using env: {'DISPLAY': ':0', 03:43:36 INFO - 'HOME': '/home/cltbld', 03:43:36 INFO - 'LANG': 'en_US.UTF-8', 03:43:36 INFO - 'LANGUAGE': 'en_US:en', 03:43:36 INFO - 'LOGNAME': 'cltbld', 03:43:36 INFO - 'MAIL': '/var/mail/cltbld', 03:43:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:36 INFO - 'MOZ_NO_REMOTE': '1', 03:43:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:43:36 INFO - 'NO_EM_RESTART': '1', 03:43:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:43:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:36 INFO - 'PWD': '/builds/slave/test', 03:43:36 INFO - 'SHELL': '/bin/bash', 03:43:36 INFO - 'SHLVL': '1', 03:43:36 INFO - 'TERM': 'linux', 03:43:36 INFO - 'TMOUT': '86400', 03:43:36 INFO - 'USER': 'cltbld', 03:43:36 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1473590150.41679-1659892745', 03:43:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:36 INFO - '_': '/tools/buildbot/bin/python'} 03:43:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:43:36 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:43:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:43:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:43:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:43:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:43:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:43:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:43:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:43:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:43:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:43:38 INFO - Installing collected packages: mozsystemmonitor 03:43:38 INFO - Running setup.py install for mozsystemmonitor 03:43:38 INFO - Successfully installed mozsystemmonitor 03:43:38 INFO - Cleaning up... 03:43:38 INFO - Return code: 0 03:43:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:43:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:43:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:43:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:38 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 0xfbb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x111e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x127c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x127a3b0>, '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 0x127b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x127b740>, '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': '3a3620fb28aea1604ab609ef000001d2-1473590150.41679-1659892745', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:43:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:43:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:43:38 INFO - Using env: {'DISPLAY': ':0', 03:43:38 INFO - 'HOME': '/home/cltbld', 03:43:38 INFO - 'LANG': 'en_US.UTF-8', 03:43:38 INFO - 'LANGUAGE': 'en_US:en', 03:43:38 INFO - 'LOGNAME': 'cltbld', 03:43:38 INFO - 'MAIL': '/var/mail/cltbld', 03:43:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:38 INFO - 'MOZ_NO_REMOTE': '1', 03:43:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:43:38 INFO - 'NO_EM_RESTART': '1', 03:43:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:43:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:38 INFO - 'PWD': '/builds/slave/test', 03:43:38 INFO - 'SHELL': '/bin/bash', 03:43:38 INFO - 'SHLVL': '1', 03:43:38 INFO - 'TERM': 'linux', 03:43:38 INFO - 'TMOUT': '86400', 03:43:38 INFO - 'USER': 'cltbld', 03:43:38 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1473590150.41679-1659892745', 03:43:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:38 INFO - '_': '/tools/buildbot/bin/python'} 03:43:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:43:38 INFO - Downloading/unpacking jsonschema==2.5.1 03:43:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:43:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:43:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:43:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:43:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:43:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:43:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:43:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:43:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:43:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:43:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:43:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:43:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:43:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:43:40 INFO - Downloading functools32-3.2.3-2.tar.gz 03:43:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:43:40 INFO - warning: no files found matching '*.txt' 03:43:40 INFO - no previously-included directories found matching 'build' 03:43:40 INFO - no previously-included directories found matching 'dist' 03:43:40 INFO - no previously-included directories found matching '.git*' 03:43:40 INFO - Installing collected packages: jsonschema, functools32 03:43:41 INFO - Running setup.py install for functools32 03:43:41 INFO - warning: no files found matching '*.txt' 03:43:41 INFO - no previously-included directories found matching 'build' 03:43:41 INFO - no previously-included directories found matching 'dist' 03:43:41 INFO - no previously-included directories found matching '.git*' 03:43:41 INFO - Successfully installed jsonschema functools32 03:43:41 INFO - Cleaning up... 03:43:41 INFO - Return code: 0 03:43:41 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:43:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:43:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:43:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:41 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 0xfbb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x111e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x127c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x127a3b0>, '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 0x127b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x127b740>, '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': '3a3620fb28aea1604ab609ef000001d2-1473590150.41679-1659892745', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:43:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:43:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:43:41 INFO - Using env: {'DISPLAY': ':0', 03:43:41 INFO - 'HOME': '/home/cltbld', 03:43:41 INFO - 'LANG': 'en_US.UTF-8', 03:43:41 INFO - 'LANGUAGE': 'en_US:en', 03:43:41 INFO - 'LOGNAME': 'cltbld', 03:43:41 INFO - 'MAIL': '/var/mail/cltbld', 03:43:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:41 INFO - 'MOZ_NO_REMOTE': '1', 03:43:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:43:41 INFO - 'NO_EM_RESTART': '1', 03:43:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:43:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:41 INFO - 'PWD': '/builds/slave/test', 03:43:41 INFO - 'SHELL': '/bin/bash', 03:43:41 INFO - 'SHLVL': '1', 03:43:41 INFO - 'TERM': 'linux', 03:43:41 INFO - 'TMOUT': '86400', 03:43:41 INFO - 'USER': 'cltbld', 03:43:41 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1473590150.41679-1659892745', 03:43:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:41 INFO - '_': '/tools/buildbot/bin/python'} 03:43:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:43:41 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:43:41 INFO - Cleaning up... 03:43:41 INFO - Return code: 0 03:43:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:43:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:43:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:43:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:41 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 0xfbb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x111e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x127c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x127a3b0>, '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 0x127b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x127b740>, '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': '3a3620fb28aea1604ab609ef000001d2-1473590150.41679-1659892745', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:43:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:43:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:43:41 INFO - Using env: {'DISPLAY': ':0', 03:43:41 INFO - 'HOME': '/home/cltbld', 03:43:41 INFO - 'LANG': 'en_US.UTF-8', 03:43:41 INFO - 'LANGUAGE': 'en_US:en', 03:43:41 INFO - 'LOGNAME': 'cltbld', 03:43:41 INFO - 'MAIL': '/var/mail/cltbld', 03:43:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:41 INFO - 'MOZ_NO_REMOTE': '1', 03:43:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:43:41 INFO - 'NO_EM_RESTART': '1', 03:43:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:43:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:41 INFO - 'PWD': '/builds/slave/test', 03:43:41 INFO - 'SHELL': '/bin/bash', 03:43:41 INFO - 'SHLVL': '1', 03:43:41 INFO - 'TERM': 'linux', 03:43:41 INFO - 'TMOUT': '86400', 03:43:41 INFO - 'USER': 'cltbld', 03:43:41 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1473590150.41679-1659892745', 03:43:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:41 INFO - '_': '/tools/buildbot/bin/python'} 03:43:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:43:41 INFO - Downloading/unpacking blobuploader==1.2.4 03:43:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:43:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:43:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:43:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:43:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:43:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:43:43 INFO - Downloading blobuploader-1.2.4.tar.gz 03:43:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:43:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:43:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:43:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:43:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:43:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:43:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:43:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:43:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:43:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:43:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:43:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:43:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:43:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:43:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:43:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:43:44 INFO - Downloading docopt-0.6.1.tar.gz 03:43:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:43:44 INFO - Installing collected packages: blobuploader, requests, docopt 03:43:44 INFO - Running setup.py install for blobuploader 03:43:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:43:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:43:44 INFO - Running setup.py install for requests 03:43:44 INFO - Running setup.py install for docopt 03:43:44 INFO - Successfully installed blobuploader requests docopt 03:43:44 INFO - Cleaning up... 03:43:44 INFO - Return code: 0 03:43:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:43:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:43:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:43:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:44 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 0xfbb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x111e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x127c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x127a3b0>, '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 0x127b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x127b740>, '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': '3a3620fb28aea1604ab609ef000001d2-1473590150.41679-1659892745', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:43:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:43:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:43:44 INFO - Using env: {'DISPLAY': ':0', 03:43:44 INFO - 'HOME': '/home/cltbld', 03:43:44 INFO - 'LANG': 'en_US.UTF-8', 03:43:44 INFO - 'LANGUAGE': 'en_US:en', 03:43:44 INFO - 'LOGNAME': 'cltbld', 03:43:44 INFO - 'MAIL': '/var/mail/cltbld', 03:43:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:44 INFO - 'MOZ_NO_REMOTE': '1', 03:43:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:43:44 INFO - 'NO_EM_RESTART': '1', 03:43:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:43:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:44 INFO - 'PWD': '/builds/slave/test', 03:43:44 INFO - 'SHELL': '/bin/bash', 03:43:44 INFO - 'SHLVL': '1', 03:43:44 INFO - 'TERM': 'linux', 03:43:44 INFO - 'TMOUT': '86400', 03:43:44 INFO - 'USER': 'cltbld', 03:43:44 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1473590150.41679-1659892745', 03:43:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:44 INFO - '_': '/tools/buildbot/bin/python'} 03:43:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:43:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:43:45 INFO - Running setup.py (path:/tmp/pip-5f05IT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:43:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:43:45 INFO - Running setup.py (path:/tmp/pip-1Q82PV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:43:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:43:45 INFO - Running setup.py (path:/tmp/pip-4IxwrG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:43:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:43:45 INFO - Running setup.py (path:/tmp/pip-RncyW2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:43:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:43:45 INFO - Running setup.py (path:/tmp/pip-6TMlHw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:43:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:43:45 INFO - Running setup.py (path:/tmp/pip-ZwWIej-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:43:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:43:45 INFO - Running setup.py (path:/tmp/pip-AIKYJ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:43:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:43:45 INFO - Running setup.py (path:/tmp/pip-Yy9QM7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:43:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:43:45 INFO - Running setup.py (path:/tmp/pip-WYxWSi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:43:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:43:45 INFO - Running setup.py (path:/tmp/pip-pf_Qfl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:43:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:43:45 INFO - Running setup.py (path:/tmp/pip-aBCk7k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:43:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:43:45 INFO - Running setup.py (path:/tmp/pip-t_VjEI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:43:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:43:45 INFO - Running setup.py (path:/tmp/pip-Rd6EI1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:43:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:43:46 INFO - Running setup.py (path:/tmp/pip-dx9OGq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:43:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:43:46 INFO - Running setup.py (path:/tmp/pip-ybedXO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:43:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:43:46 INFO - Running setup.py (path:/tmp/pip-TgQ4dG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:43:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:43:46 INFO - Running setup.py (path:/tmp/pip-fZTp9h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:43:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:43:46 INFO - Running setup.py install for manifestparser 03:43:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:43:46 INFO - Running setup.py install for mozcrash 03:43:46 INFO - Running setup.py install for mozdebug 03:43:46 INFO - Running setup.py install for mozdevice 03:43:46 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:43:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:43:46 INFO - Running setup.py install for mozfile 03:43:46 INFO - Running setup.py install for mozhttpd 03:43:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:43:46 INFO - Running setup.py install for mozinfo 03:43:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:43:47 INFO - Running setup.py install for mozInstall 03:43:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:43:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:43:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:43:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:43:47 INFO - Running setup.py install for mozleak 03:43:47 INFO - Running setup.py install for mozlog 03:43:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:43:47 INFO - Running setup.py install for moznetwork 03:43:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:43:47 INFO - Running setup.py install for mozprocess 03:43:47 INFO - Running setup.py install for mozprofile 03:43:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:43:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:43:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:43:47 INFO - Running setup.py install for mozrunner 03:43:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:43:47 INFO - Running setup.py install for mozscreenshot 03:43:47 INFO - Running setup.py install for moztest 03:43:47 INFO - Running setup.py install for mozversion 03:43:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:43:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:43:48 INFO - Cleaning up... 03:43:48 INFO - Return code: 0 03:43:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:43: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')]} 03:43:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43: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')]} 03:43:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:48 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 0xfbb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x111e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x127c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x127a3b0>, '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 0x127b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x127b740>, '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': '3a3620fb28aea1604ab609ef000001d2-1473590150.41679-1659892745', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:43:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:43:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:43:48 INFO - Using env: {'DISPLAY': ':0', 03:43:48 INFO - 'HOME': '/home/cltbld', 03:43:48 INFO - 'LANG': 'en_US.UTF-8', 03:43:48 INFO - 'LANGUAGE': 'en_US:en', 03:43:48 INFO - 'LOGNAME': 'cltbld', 03:43:48 INFO - 'MAIL': '/var/mail/cltbld', 03:43:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:48 INFO - 'MOZ_NO_REMOTE': '1', 03:43:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:43:48 INFO - 'NO_EM_RESTART': '1', 03:43:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:43:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:48 INFO - 'PWD': '/builds/slave/test', 03:43:48 INFO - 'SHELL': '/bin/bash', 03:43:48 INFO - 'SHLVL': '1', 03:43:48 INFO - 'TERM': 'linux', 03:43:48 INFO - 'TMOUT': '86400', 03:43:48 INFO - 'USER': 'cltbld', 03:43:48 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1473590150.41679-1659892745', 03:43:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:48 INFO - '_': '/tools/buildbot/bin/python'} 03:43:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:43:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:43:48 INFO - Running setup.py (path:/tmp/pip-EbicZL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:43:48 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 03:43:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:43:48 INFO - Running setup.py (path:/tmp/pip-b3QHJo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:43:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:43:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:43:48 INFO - Running setup.py (path:/tmp/pip-x6w8Nq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:43:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:43:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:43:48 INFO - Running setup.py (path:/tmp/pip-bCRwAD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:43:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:43:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:43:48 INFO - Running setup.py (path:/tmp/pip-ipvo8S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:43:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 03:43:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:43:48 INFO - Running setup.py (path:/tmp/pip-2HwtFv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:43:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 03:43:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:43:48 INFO - Running setup.py (path:/tmp/pip-q3C6kd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:43:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 03:43:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:43:48 INFO - Running setup.py (path:/tmp/pip-E3Ioit-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:43:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:43:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:43:48 INFO - Running setup.py (path:/tmp/pip-XPw1eH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:43:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 03:43:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:43:48 INFO - Running setup.py (path:/tmp/pip-8xjRQv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:43:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:43:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:43:49 INFO - Running setup.py (path:/tmp/pip-NqC1G1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:43:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 03:43:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:43:49 INFO - Running setup.py (path:/tmp/pip-Mq3D9r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:43:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 03:43:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:43:49 INFO - Running setup.py (path:/tmp/pip-qtfutx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:43:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 03:43:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:43:49 INFO - Running setup.py (path:/tmp/pip-UBcT1y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:43:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 03:43:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:43:49 INFO - Running setup.py (path:/tmp/pip-PQVvub-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:43:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 03:43:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:43:49 INFO - Running setup.py (path:/tmp/pip-GocfSZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:43:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 03:43:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:43:49 INFO - Running setup.py (path:/tmp/pip-Wa6lDN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:43:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 03:43:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:43:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:43:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:43:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:43:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:43:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:43:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:43:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:43:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:43:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:43:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:43:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:43:51 INFO - Downloading blessings-1.6.tar.gz 03:43:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:43:51 INFO - Installing collected packages: blessings 03:43:51 INFO - Running setup.py install for blessings 03:43:51 INFO - Successfully installed blessings 03:43:51 INFO - Cleaning up... 03:43:51 INFO - Return code: 0 03:43:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:43:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:43:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:43:51 INFO - Reading from file tmpfile_stdout 03:43:51 INFO - Current package versions: 03:43:51 INFO - argparse == 1.2.1 03:43:51 INFO - blessings == 1.6 03:43:51 INFO - blobuploader == 1.2.4 03:43:51 INFO - docopt == 0.6.1 03:43:51 INFO - functools32 == 3.2.3-2 03:43:51 INFO - jsonschema == 2.5.1 03:43:51 INFO - manifestparser == 1.1 03:43:51 INFO - mozInstall == 1.12 03:43:51 INFO - mozcrash == 0.17 03:43:51 INFO - mozdebug == 0.1 03:43:51 INFO - mozdevice == 0.48 03:43:51 INFO - mozfile == 1.2 03:43:51 INFO - mozhttpd == 0.7 03:43:51 INFO - mozinfo == 0.9 03:43:51 INFO - mozleak == 0.1 03:43:51 INFO - mozlog == 3.3 03:43:51 INFO - moznetwork == 0.27 03:43:51 INFO - mozprocess == 0.23 03:43:51 INFO - mozprofile == 0.28 03:43:51 INFO - mozrunner == 6.12 03:43:51 INFO - mozscreenshot == 0.1 03:43:51 INFO - mozsystemmonitor == 0.3 03:43:51 INFO - moztest == 0.7 03:43:51 INFO - mozversion == 1.4 03:43:51 INFO - psutil == 3.1.1 03:43:51 INFO - requests == 1.2.3 03:43:51 INFO - wsgiref == 0.1.2 03:43:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:43:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:43:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:43:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:51 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 0xfbb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x111e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x127c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x127a3b0>, '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 0x127b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x127b740>, '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': '3a3620fb28aea1604ab609ef000001d2-1473590150.41679-1659892745', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:43:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 03:43:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:43:51 INFO - Using env: {'DISPLAY': ':0', 03:43:51 INFO - 'HOME': '/home/cltbld', 03:43:51 INFO - 'LANG': 'en_US.UTF-8', 03:43:51 INFO - 'LANGUAGE': 'en_US:en', 03:43:51 INFO - 'LOGNAME': 'cltbld', 03:43:51 INFO - 'MAIL': '/var/mail/cltbld', 03:43:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:51 INFO - 'MOZ_NO_REMOTE': '1', 03:43:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:43:51 INFO - 'NO_EM_RESTART': '1', 03:43:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:43:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:51 INFO - 'PWD': '/builds/slave/test', 03:43:51 INFO - 'SHELL': '/bin/bash', 03:43:51 INFO - 'SHLVL': '1', 03:43:51 INFO - 'TERM': 'linux', 03:43:51 INFO - 'TMOUT': '86400', 03:43:51 INFO - 'USER': 'cltbld', 03:43:51 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1473590150.41679-1659892745', 03:43:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:51 INFO - '_': '/tools/buildbot/bin/python'} 03:43:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:43:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:43:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 03:43:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 03:43:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:43:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 03:43:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 03:43:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 03:43:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 03:43:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 03:43:52 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:43:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:43:52 INFO - Cleaning up... 03:43:52 INFO - Return code: 0 03:43:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:43:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:43:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:43:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:52 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 0xfbb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x111e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x127c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x127a3b0>, '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 0x127b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x127b740>, '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': '3a3620fb28aea1604ab609ef000001d2-1473590150.41679-1659892745', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:43:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:43:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:43:52 INFO - Using env: {'DISPLAY': ':0', 03:43:52 INFO - 'HOME': '/home/cltbld', 03:43:52 INFO - 'LANG': 'en_US.UTF-8', 03:43:52 INFO - 'LANGUAGE': 'en_US:en', 03:43:52 INFO - 'LOGNAME': 'cltbld', 03:43:52 INFO - 'MAIL': '/var/mail/cltbld', 03:43:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:52 INFO - 'MOZ_NO_REMOTE': '1', 03:43:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:43:52 INFO - 'NO_EM_RESTART': '1', 03:43:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:43:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:52 INFO - 'PWD': '/builds/slave/test', 03:43:52 INFO - 'SHELL': '/bin/bash', 03:43:52 INFO - 'SHLVL': '1', 03:43:52 INFO - 'TERM': 'linux', 03:43:52 INFO - 'TMOUT': '86400', 03:43:52 INFO - 'USER': 'cltbld', 03:43:52 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1473590150.41679-1659892745', 03:43:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:52 INFO - '_': '/tools/buildbot/bin/python'} 03:43:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:43:52 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:43:52 INFO - Cleaning up... 03:43:52 INFO - Return code: 0 03:43:52 INFO - Running post-action listener: _resource_record_post_action 03:43:52 INFO - Running post-action listener: _start_resource_monitoring 03:43:52 INFO - Starting resource monitoring. 03:43:52 INFO - [mozharness: 2016-09-11 10:43:52.410341Z] Finished create-virtualenv step (success) 03:43:52 INFO - [mozharness: 2016-09-11 10:43:52.410781Z] Running install step. 03:43:52 INFO - Running pre-action listener: _resource_record_pre_action 03:43:52 INFO - Running main action method: install 03:43:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:43:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:43:52 INFO - Reading from file tmpfile_stdout 03:43:52 INFO - Detecting whether we're running mozinstall >=1.0... 03:43:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:43:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:43:52 INFO - Reading from file tmpfile_stdout 03:43:52 INFO - Output received: 03:43:52 INFO - Usage: mozinstall [options] installer 03:43:52 INFO - Options: 03:43:52 INFO - -h, --help show this help message and exit 03:43:52 INFO - -d DEST, --destination=DEST 03:43:52 INFO - Directory to install application into. [default: 03:43:52 INFO - "/builds/slave/test"] 03:43:52 INFO - --app=APP Application being installed. [default: firefox] 03:43:52 INFO - mkdir: /builds/slave/test/build/application 03:43:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 03:43:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 03:44:08 INFO - Reading from file tmpfile_stdout 03:44:08 INFO - Output received: 03:44:08 INFO - /builds/slave/test/build/application/firefox/firefox 03:44:08 INFO - Running post-action listener: _resource_record_post_action 03:44:08 INFO - [mozharness: 2016-09-11 10:44:08.729750Z] Finished install step (success) 03:44:08 INFO - [mozharness: 2016-09-11 10:44:08.730030Z] Running run-tests step. 03:44:08 INFO - Running pre-action listener: _resource_record_pre_action 03:44:08 INFO - Running main action method: run_tests 03:44:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:44:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:44:08 INFO - Python 2.7.3 03:44:08 INFO - Return code: 0 03:44:08 INFO - grabbing minidump binary from tooltool 03:44:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:44:08 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 0x127a3b0>, '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 0x127b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x127b740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:44:08 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 03:44:08 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 03:44:08 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 03:44:08 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:44:08 INFO - Return code: 0 03:44:08 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:44:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:44:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:44:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:44:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:44:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-059', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 03:44:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-059 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 03:44:08 INFO - Using env: {'DISPLAY': ':0', 03:44:08 INFO - 'HOME': '/home/cltbld', 03:44:08 INFO - 'LANG': 'en_US.UTF-8', 03:44:08 INFO - 'LANGUAGE': 'en_US:en', 03:44:08 INFO - 'LOGNAME': 'cltbld', 03:44:08 INFO - 'MAIL': '/var/mail/cltbld', 03:44:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:44:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:44:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:44:08 INFO - 'MOZ_NO_REMOTE': '1', 03:44:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:44:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:44:08 INFO - 'NO_EM_RESTART': '1', 03:44:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:44:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:44:08 INFO - 'PWD': '/builds/slave/test', 03:44:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:44:08 INFO - 'SHELL': '/bin/bash', 03:44:08 INFO - 'SHLVL': '1', 03:44:08 INFO - 'TERM': 'linux', 03:44:08 INFO - 'TMOUT': '86400', 03:44:08 INFO - 'USER': 'cltbld', 03:44:08 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1473590150.41679-1659892745', 03:44:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:44:08 INFO - '_': '/tools/buildbot/bin/python'} 03:44:08 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-059', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 03:44:08 INFO - mozversion application_buildid: 20160911004011 03:44:08 INFO - mozversion application_changeset: 8663d41e015f3c9933a08a65c8349b86f2263354 03:44:08 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:44:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:44:08 INFO - mozversion application_name: Firefox 03:44:08 INFO - mozversion application_remotingname: firefox-dev 03:44:08 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:44:08 INFO - mozversion application_vendor: Mozilla 03:44:09 INFO - mozversion application_version: 50.0a2 03:44:09 INFO - mozversion platform_buildid: 20160911004011 03:44:09 INFO - mozversion platform_changeset: 8663d41e015f3c9933a08a65c8349b86f2263354 03:44:09 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:44:09 INFO - mozversion platform_version: 50.0a2 03:44:09 INFO - using testdate: 1473590648 03:44:09 INFO - actual date: 1473590648 03:44:09 INFO - starting webserver on 'localhost:46588' 03:44:09 INFO - SUITE-START | Running 2 tests 03:44:09 INFO - TEST-START | dromaeo_css 03:44:09 INFO - operating with platform_type : linux_ 03:44:09 INFO - Initialising browser for dromaeo_css test... 03:44:09 INFO - TEST-INFO | started process 9598 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAlL5ZT/profile http://localhost:46588/getInfo.html) 03:44:12 INFO - PROCESS | 9598 | __metrics Screen width/height:1600/1200 03:44:12 INFO - PROCESS | 9598 | colorDepth:24 03:44:12 INFO - PROCESS | 9598 | Browser inner width/height: 1024/697 03:44:12 INFO - PROCESS | 9598 | __metrics 03:44:13 INFO - PROCESS | 9598 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:44:13 INFO - PROCESS | 9598 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:44:14 INFO - TEST-INFO | 9598: exit 0 03:44:14 INFO - Browser initialized. 03:44:14 INFO - Running cycle 1/1 for dromaeo_css test... 03:44:14 INFO - TEST-INFO | started process 9684 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAlL5ZT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:44:26 INFO - PROCESS | 9684 | RSS: Main: 145829888 03:44:26 INFO - PROCESS | 9684 | 03:45:17 INFO - PROCESS | 9684 | 1473590717127 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] 03:47:26 INFO - PROCESS | 9684 | Cycle 1(1): loaded http://localhost:46588/tests/dromaeo/cssquery-dojo.html (next: http://localhost:46588/tests/dromaeo/cssquery-ext.html) 03:47:27 INFO - PROCESS | 9684 | RSS: Main: 221200384 03:47:27 INFO - PROCESS | 9684 | 03:50:29 INFO - PROCESS | 9684 | Cycle 1(1): loaded http://localhost:46588/tests/dromaeo/cssquery-ext.html (next: http://localhost:46588/tests/dromaeo/cssquery-jquery.html) 03:50:30 INFO - PROCESS | 9684 | RSS: Main: 239386624 03:50:30 INFO - PROCESS | 9684 | 03:53:32 INFO - PROCESS | 9684 | Cycle 1(1): loaded http://localhost:46588/tests/dromaeo/cssquery-jquery.html (next: http://localhost:46588/tests/dromaeo/cssquery-mootools.html) 03:53:32 INFO - PROCESS | 9684 | RSS: Main: 175316992 03:53:32 INFO - PROCESS | 9684 | 03:56:34 INFO - PROCESS | 9684 | Cycle 1(1): loaded http://localhost:46588/tests/dromaeo/cssquery-mootools.html (next: http://localhost:46588/tests/dromaeo/cssquery-prototype.html) 03:56:35 INFO - PROCESS | 9684 | RSS: Main: 227860480 03:56:35 INFO - PROCESS | 9684 | 03:59:37 INFO - PROCESS | 9684 | Cycle 1(1): loaded http://localhost:46588/tests/dromaeo/cssquery-prototype.html (next: http://localhost:46588/tests/dromaeo/cssquery-yui.html) 03:59:38 INFO - PROCESS | 9684 | RSS: Main: 177573888 03:59:38 INFO - PROCESS | 9684 | 04:02:40 INFO - PROCESS | 9684 | Cycle 1(1): loaded http://localhost:46588/tests/dromaeo/cssquery-yui.html (next: http://localhost:46588/tests/dromaeo/cssquery-dojo.html) 04:02:41 INFO - PROCESS | 9684 | RSS: Main: 264491008 04:02:41 INFO - PROCESS | 9684 | 04:02:41 INFO - PROCESS | 9684 | __start_tp_report 04:02:41 INFO - PROCESS | 9684 | _x_x_mozilla_page_load 04:02:41 INFO - PROCESS | 9684 | _x_x_mozilla_page_load_details 04:02:41 INFO - PROCESS | 9684 | |i|pagename|runs| 04:02:41 INFO - PROCESS | 9684 | |0;dojo.html;3226.1128189747815;3233.337165880994;3251.9024429267115;3274.8690052397906;3301.7523685723577;5533.197686335484;5626.409227031164;5638.689872057035;5653.575981801361;5661.179129026289;5630.40880707525;5706.486416222539;5707.001274776908;5709.343425506068;5715.828525144252;4977.10412125819;5073.391193056838;5153.845384638467;5164.561012313949;5217.660852044605;5116.130257856174;5125.923111153317;5134.563562097226;5180.922286165711;5191.844244672666;5071.949280507185;5074.391073071237;5086.008228395464;5112.616553758465;5127.359080114986;5150.381954165506;5165.992631436871;5202.92195617066;5220.504052115081;5230.503102205284;4934.210526315772;4955.776990035421;4960.826371076995;5123.87190320245;5124.026434977342;5497.670139791625;5504.669719816824;5508.614396992212;5516;5547.694876781776;6023.638581685069;6040.154378387029;6047.15339852421;6047.21386219793;6049.183360246355;5846.3861294563885;6021.337652858182;6027.789027384064;6075.817725468199;6095.390460953913;5717.914231286534;5717.914231286576;5719.8570035749435;5730.885382292373;5744.023516002248;6206.472449841724;6237.376262373727;6239.313675495692;6240.719167637377;6246.937530624636;5771.826845194651;5804.332501762267;5895.823125306248;5900.20347253124;5970.820875373745;6003.0695242237525;6012.759489620366;6015.789447369409;6016.879662406727;6018.939810601925;5966.791162309299;5986.880262394728;5989.460948514655;5990.251218597676;5993.250843644544;5792.478676919098;5916.615419997773;5938.049912014143;5941.257342832146;6042.939570604326;4597.103564804898;4599.609033232213;4601.74690392025;4609.792559334838;4611.377464042378;4118;4134.503859536815;4134.669226461936;4142.357934520154;4168.333066709312;5533.6403133796175;5604.271444712243;5639.887202256014;5649.689267090267;5697.857553561194;6027.6986150693165;6028.367021462771;6033.456988871023;6037.849053773691;6038;5648.378678345379;5653.971730141323;5654.9151762723595;5663;5666.348369937427;5028.094942910238;5114.207297868837;5151.150060240018;5151.381834179848;5219.4780521947505;6208.410201030985;6221.440070393596;6222.875542489125;6223.533235007392;6229.875402491924;5881.941250574938;5961.926853166472;6015.218021657157;6018.428249316308;6026.90959635597;6010.248718910137;6017.819465416044;6116.974953844038;6133.233345831771;6189.257289125334;5895.882082358415;5896.469317761422;5899.085641725539;5916.585838991229;5920.082387229986;6004.099385092271;6008.819735407945;6010.4891084258325;6019.307779605401;6020.969895150496;5725.141228815711;5787.768489260382;5822.097574875901;5829.358770535153;5918.378570250234;5757.9424205757405;5795.623284486495;5796.188533605383;5797.072468405035;5805.738741756547;5690.430956904442;5709.029464990878;5746.798862039808;5760.711964401679;5763.51010164124;5657.43425657431;5762.971185144047;5779.566532509994;5872.236609240771;5952.166696662384;5748.051571490657;5761.913571296518;5774.797882074107;5778.508826749605;5787.710614469175 04:02:41 INFO - PROCESS | 9684 | |1;ext.html;588.1207594646005;634.6255709131613;655.3151956205764;665.9667016649167;668.8561959178778;34596.4810527842;47042;47737.28394074083;47861.521384786145;48002.279965800495;48101.27848082282;48826.75586622062;48898.75550622242;48919.51080489194;48937.51062489374;8664.653413863469;9368.95315523423;9515.143637072662;9522.714318570454;11164.553417863275;11190.496427660733;11325.603603873866;11335.37655428951;11336.489857956412;11339.886601133965;11442.084633229364;11481.196316257865;11481.655550333462;11493.712657183554;11501.194916355858;8630.611622476972;11191.888081119247;11255.606053788078;11392.829107563393;11456.140789440784;11099.223054386197;11188.66434006981;11210.551577936872;11216.214864959456;11224.943875280653;44192.779036104934;44512.3323150153;44589;44618.776906115585;44641;3963;4021.336479480882;4033.3748269018056;4088.7751173685438;4359.378026311223;34389.14027149316;34696.30607387838;34700.30599388023;34739.30521389558;34865.651343486745;4687.226607609774;4705.106029854317;4725.31482934976;4739.502352253033;4806.951930480685;373958;375197.99999999726;378038;378066.4386712278;380771;63071.68464157742;63499.682501587195;63551;63629.681851590445;63756.68121659362;37100.44349334762;37520.43719344212;37557.24885502275;39720.60279397169;59639;19650.70523942142;19876.801231987785;19893.204271828963;19908.402747917586;19911.800881991283;13405.932970335281;13704.65738356549;13981.161130332213;14012.089214201043;15124.092554446768;20792;20918.47703807417;21170.470738231666;21195.8940205298;21223;7368.189499155089;7470.3276705096805;7575.772726818204;7603.7338693145475;7703.306702396699;1021.0300214795974;1071.9624813131502;1120.2158489057529;1156.525824411987;1156.9521946155257;32428;34861.30277394489;35399.991000764705;35465.29069418597;35570.288594228485;1142.782871254459;1170.3329102411715;1174.864890537582;1228.3244215681339;1237.2638280223252;17535.386261480788;18371.357002504847;18434.539136521693;18443.72334414985;18443.72334414985;591823;598651;599375;599456;599492;65680.34319656838;66439.33560664428;66457;66475.33524664691;66540;1362.4413990263945;1397.3013493253372;1424.1668623854953;1486.7992820969248;1497.3336865095146;33116.503252451716;37031.8148409267;37253.62746372502;37286.813565931996;37536.812315938245;55232.72383638217;55841.44158558363;55961.72019139878;56081.439185609255;56096.719516402154;361929;362154;364727;365377;366108;26454.735452645225;31026.224344390666;31579.526307104952;31818.204544885637;33388.16529586683;54291.18563221476;54295.728521358724;54372.72813635907;55175.17237241365;55328.72335638296;45039.54960450353;45934.08131837278;47125.29312060391;47405.288920666906;48848.5115148844;46969;47067.29399059086;47160.52839471561;47166.52833471621;47663.76168119137;45688.31467528056;46875.296870546284;46919.530804692884;46976.7651161742;47250.52749472599;38147.04632384213;39952.800235998635;40560.98597535085;40810.387844181765;41052.589474104876;30313.393732124794;30743.846280769347;30988.69011309948;31207.219819504695;31468 04:02:41 INFO - PROCESS | 9684 | |2;jquery.html;395.9489842615252;412.4596778220533;425.9680523960703;427.17341943339636;439.2510769138619;16049.83950160498;21074.89462552687;22176;22331.330060098182;22380.552388952212;20509.589808203826;21331.25340613079;21374.679379809317;21428.25001124957;21472.677909831364;5738.42615738427;5763.481286684198;5916.171735956969;5958.046712525997;5965.49293310067;5840.386759390245;5883.588148829584;5884.352721200686;5895.528357731371;5903.911441328383;5670.659760414368;5817.62185457946;5849.122631605272;5855.970720146393;5873.383294754053;5887.322957859858;5916.615419997686;5920.526357891358;5933.317668468117;5937.7921772737745;5270.130428479335;5294.576433885319;5328.6269961102735;5336.439673834269;5348.866278343034;25232;25268.494630107296;25319.367015824566;25492.872535637387;25531.48937021249;2315.3632750993443;2600.6749156355454;2639.0499420208707;2679.531082060651;3023.7883348165637;10616.309939853885;10797.514111864984;10894.67315980522;11133.777324453547;12248.081393895494;1901.6196760647788;2000.839932805372;2090.9336238518313;2104.084723145437;2105.589410065038;80800.19199807986;89551;91158;91389;91606.54196729041;10828.675139745821;11040.558377664804;11298.830517542243;11439.714007149887;12492;15819.446319378996;16034.919825400799;16202.837971620133;16319.59201019984;16342.93770904911;8569.100244474364;8882.955585222033;8965.193132618097;8965.910340896638;9172.036936121745;8185.836283274301;8732.257758090633;8767.21095101444;8795.648174072965;8981.955090224506;4949.183384741549;5034.597232221414;5055.797768089235;5121.615878809104;5170.1986192140275;2091.498040470276;2100.510894589885;2126.606577783115;2130.957380852374;2138.5936672032262;474.9667523273338;482.2115840600573;980.8234517786867;1046.7383154211448;1087.8368244763349;12260.570880019157;12498.625041248777;12499.250044997147;12533.623991280276;12534.435950382254;478.3207844860307;494.84165067178157;496.51093672732327;505.5525859614194;509.54904909155664;6436.098946147543;6505.0892874997535;6608.570442921291;6633.402993730588;6754.696038678271;545895;548878;550886;570761;572491;94300.81345526062;101834;102288;103942.48028759808;104189;1042.926995110335;1067.6156583629886;1092.3937214845678;1100.419516234429;1101.1961268274204;13425.731485370434;18315.54211144732;18720;19102;19152.52118696988;32016.67983320137;44515.777421113984;44537.88655283644;45678.77160614176;45852.54147458616;60024.39975600363;60822.39177608167;61206;61227.387726123954;62586.061209082814;8269.95865020671;8309.086000540057;8343.207395297437;8350.457220280905;8454.64585738333;11019.724506887393;12719.427625756678;12861.871381286443;12862;13040.217586944813;10995.230333876552;11004;11035.889641103486;11198;11307.717307067389;11064.778704426028;11185.217034807485;11218.775624487627;11318.660440186808;11787.528498859949;10767.946160269463;10847.45762711877;11290.94354528255;11330.546778128783;11422.885771142182;7951.602419879098;8083.595820208849;8084.39367047462;8314.168583141638;8494.277986371228;9469.337146399686;10742.140628749838;10837.403942783223;10839.403832789272;11182.273152245078 04:02:41 INFO - PROCESS | 9684 | |3;mootools.html;1550.6356006338508;1553.1457698265954;1775.3165031462884;1856.5173055005712;1888.688366419541;9232.353735238528;9904.860940991788;9997.800043999116;9999.200063994882;10002.349847259922;8414.873776893352;9160.496172710498;9480.288978326618;9531.28515361349;9652.903470965288;2468.210172744718;2500.987495062522;2506.385935445818;2508.2600632813273;2509.2095989763166;2462.7660372264604;2523.0538548044483;2527.7851382632407;2529.2412276317123;2531.620256961452;2483.081259933827;2488.8924428629266;2515.2328539795353;2524.7096583892812;2526.418923647562;2396.5446565152565;2404.158544509425;2412.95174096518;2441.121196369306;2443.8655873926928;2372.7034120734907;2381.2856143156982;2383.5232953409386;2386;2452.497238066192;9667.323287369887;9718;9780.951095244549;9789.510524473819;9789.951050244774;5691.089425691869;5855.648661080349;5860;5861.88276234473;5869.2369991901205;3484.9128771780656;3639.490471334015;3667.541557305337;3670.229251857113;3693.0582701411236;1216.8520311842815;1217.2696382170716;1229.5081967213098;1267.575362253646;1271.2245530226555;14028.088174268742;14076.71846563063;14077.859221407758;14100.788488172686;14110;4935.925961110586;5080.974595127074;5114.590832733373;5124.718140502308;5195.337492002523;11636.767264654829;11666.241694289844;11669.35818529972;11670.416479176178;11673.241239319586;5871.3541510433815;5873.911891321633;5877.23595932526;5877.52979761618;5881.558883083786;4743.715377077387;4792.95207047932;5240.371155461369;5320;5372.1941708744;4377.233984052778;4379.474463064453;4434.312681534303;4478.126765280804;4624.12141693084;1385.1066062389702;1394.8186735724291;1397.1267957526545;1401.8177636907137;1424.2593851197319;1059.8834128245887;1063.2504084620325;1073.903348698621;1082.1072615092423;1083.090204228436;6238.719257633326;6391.09871513013;6547.148870646881;6583.473322134218;6667.49993750461;445.3053236950368;449.19145538032063;455.93388958600815;458.5987261146497;463.2102265637081;4186.372044193396;4210.5578914213565;4254.659627229814;4255.170241802881;4270.359446083113;11942.223755455867;12414.317212553216;12435.875641243652;12461.439235234437;12482.43829027696;12358.691032724253;12377.876221237853;12379.62861114168;12393.938030309972;12406.25562466255;8651.437656552303;8926.955365223132;9034.683786067457;9128.771780705403;9293.95353023244;6586.407223349922;6668;6722.764703235363;6832.145981752281;6961.269066747918;10782.24524283324;11511.827322590001;11596.594119205787;11630.185886987829;11745.941270293593;11307.773844522899;11324.150688698215;11512.59705910289;11555.42222888869;11575.073994080285;8517.829643407222;8910.420822646507;8960.731178064669;9066.727998160066;9077.500737459239;9345.532723363669;9397.671081512115;9407.717768466957;9426.387284826464;9486.288528360263;8667.089955554828;8673.392862499564;8690.78273043179;8793.252573531321;8881.689140880038;8443.408961372645;8558.144185581392;8654.22112009922;8682.869756953525;8863.955680221557;7544.47188696786;7669.309762121436;7669.654865531176;7670.386369090571;7674.232576742281;5145.4340022598235;5222.164453687392;5251.422343542131;5280.1815718563685;5512.3385193777;5642;5687.77248910039;5709.942900570941;5759.596828221985;5770.61505238748 04:02:41 INFO - PROCESS | 9684 | |4;prototype.html;343.2945297413815;350.561797752809;354.19420817639866;355.71542765787365;357.04134399138314;5597.216389705444;5650.378458369576;5674.779922316701;5703.942960570393;5710.257666503359;5225;5338.145896656536;5458.972705136479;5540.944590554093;5545.750441230144;4345.760983145926;4393.692441529094;4411.7794110294435;4417.249067658506;4417.73493590384;4400.845970391037;4440.778785833905;4442.244818380883;4449.621782148505;4450;4278.4010238566625;4322.298563397876;4349.195398851207;4375.781210939456;4399.824007039714;4331.242032644306;4331.3719510670935;4339.153864996327;4341.131773645284;4354.847580334705;3859.6333348331864;3870.78710670913;3879.2047630235734;3880.72834901557;3905.5313362396696;5263.842084737464;5355.437679043722;5452.345718513764;5472.646761892384;5497.917531237034;4974.129527332702;4982.451930287665;4996.300517927493;5006.524380183877;5033.119204139298;5757.942420575824;5777.913331300034;5783.5084017858535;5783.5084017858535;5800.216970708942;3967.226390853785;4173.749575025508;4212.7472351659;4221.324588065895;4285;6011.4289142531825;6017.5185985121525;6025.427584379477;6031.336552979169;6038;4293.552674975296;4316.719413238129;4320.962968887445;4325;4329.15581461612;4174.666026717855;4338.414314067561;4361.563843615613;4363.869083927487;4380.956190438055;4907.1167189905445;4920.188168952082;4927.679700819435;4940.456549779521;4953.331300274489;5849.1226316052935;5868.94131058684;5869.7945571904775;5911.940880591225;5929.466348028698;1414.3988804757937;1441.0592875973607;1460.9634759131106;1494.1184701026446;1502.5342143935416;998.502246630055;1017.6641708236265;1027.6403258859339;1029.351508549609;1041.2607048995146;5555.444455554493;5572.498475137257;5595.944040559623;5605.439456054443;5621.887562248732;5901.645901245939;5904.232449781501;5920.940790592125;5950.4049595041015;5955.553333500005;5427.267318911975;5536;5541.889162216733;5550.195221692832;5594.566363631833;2731.729745668234;2776.9170023690776;2790.9023184188454;2806.4948309304114;2818.7872520072056;5294.973525132426;5314.016906872241;5343.839684809462;5380.632521690522;5401.351837779492;4945.576667433004;5253.816116435906;5253.868653283698;5268.367795864444;5269.235960785664;5212.635115541876;5229.686218826807;5248.790048398174;5250.503606472137;5263.210498435835;5098.79604815818;5169.379674439091;5169.638125331191;5179.559737422362;5230.986070362862;4818.975905120452;5155.845324640267;5177.870553236199;5192.532672059382;5192.688438693691;5305.602079844104;5311.362636483492;5346.25152478661;5353.384360798558;5379;3897.649211570972;3901.4147877818555;3917.6102577427177;3964.563897971163;3975.4633124528978;4976.576990955809;5264.605154613496;5271.472852714851;5272.657277276965;5285.497877701612;5654.8690261948595;5771.480566749013;5792.436042268648;5837.314835175866;5869.706514674334;5812.95366833957;5829.09649004405;5838.708064596668;5844.590878638624;5906.527477801851;5643;5772.451617096369;5786.913196302143;5810.535157187331;5820.565866138998;5842.620229685057;5866.5893387462465;5872.500837428866;5872.794452194153;5895.263092113486;5572.665640061609;5756.366799651951;5756.395578464201;5771.97114014444;5781.913271300849 04:02:41 INFO - PROCESS | 9684 | |5;yui.html;831.280941985183;836.4186890111373;873.3188113271648;882.616062013024;909.2316992141638;16383.344666213352;17962.550936226602;18013.459596212102;18050.007249601284;18099.095045247734;16079.517614471555;16283.430079947204;16287.83712162878;16295.674086518262;16322.673546529062;751.9248075192492;884.9778755531099;893.6157452295511;897.4929165021741;900.7973206028624;894.2577660541734;894.4767311122969;895.3463971300954;896.041235877608;896.3904544909459;939.0843927171022;940.0928104379307;942.5569982108398;942.9387089839173;944.2446043165475;783.4985609210111;917.2478567574592;931.529577563333;936.2089034765612;938.5166639180827;677.610374034927;679.6397909108131;679.6635665345636;680.2007641021779;680.2075581947025;14586.34361453737;14628.122312661275;14697.33861976203;14705.338259778335;14717.70564588713;15789.921050394789;16031.519054428385;16153.27310271029;16320.75518867218;16380;12590.496380144783;12673.366331683379;12687.365631718378;14412.207328596924;14904.776428353583;4756.595689366408;4835.153848076572;4837.854864354075;4841.709497430165;4950.727709975949;84157.57921210355;85219.57390213071;85245.14754852497;85419.57290213571;85453.57273213657;12377.690557735948;12476.937615311865;12522.81215781764;12535.435905384098;12597.685057873443;21615.135394584355;22117.66823497649;22244.332670019925;22252;22300.888495557418;9381.343305968654;9403;9413.105754953405;9460.47967361788;9524.571394287274;5266.131088370376;5266.7366631668265;5350.27771250884;5442.428545002717;5632.070708333079;3906.4726261954274;4048.655864251513;4066.573009833984;4069.5116586009867;4083.89790255246;689.899964505154;712.593821521738;714.2464699741822;724.6557885004686;725.6807004917924;736.260058641073;751.5828715063083;834.7746108550657;846.9449485783405;849.8597731374253;10385.983741776534;10784.083352914933;11272.54909803615;11491.195616306946;12210.511579536718;726.6439444672072;758.4463341760546;788.8264581792112;841.3521588376916;850.8085680721788;4919.179244019726;5170.353705786777;5212.296339994128;5216.895662086737;5481.477780830831;113330;114653.4267328658;115655.42172289085;118836;119599.40200298943;103867.48066259772;106440;106906.8650625787;107062.46468767607;107231;54211.45788542143;70190.64904675545;87185.56407217923;88388;88799;10317.535710892878;10388.896111038794;10584.96652434734;10661;10727;53315.53449025801;53674;54282.7285863584;54330;55431.722841385534;71946.64026679833;78833.05834708357;82267.58866205631;83838.58080709557;84340;13442.529511467052;13705.588832334646;13976.860231397555;14413.495527656481;14469.204193769443;13641.795373069213;16901.830981690026;16929.74605380945;17024.23390947435;17123.486295411156;13421.32893355348;13609.523666672016;15662.608434789221;15801.20993950321;15975;13394.129381590166;13514;15147.54557363281;15395.307211175748;15517.30172142279;14421.134731916118;15573.532794016197;15736.370545178523;15851.365945362057;16325.102119383548;11885.524579016743;12246.142770006014;12357.814632780695;12554.623361299176;12686.619401417973;12463.314517701614;12625.747485050064;12777.680557986125;12815.935920320711;12843.743125137631 04:02:41 INFO - PROCESS | 9684 | __end_tp_report 04:02:41 INFO - PROCESS | 9684 | __start_cc_report 04:02:41 INFO - PROCESS | 9684 | _x_x_mozilla_cycle_collect,999 04:02:41 INFO - PROCESS | 9684 | __end_cc_report 04:02:41 INFO - PROCESS | 9684 | __startTimestamp1473591761243__endTimestamp 04:02:41 INFO - PROCESS | 9684 | 04:02:41 INFO - PROCESS | 9684 | ------- Summary: start ------- 04:02:41 INFO - PROCESS | 9684 | Number of tests: 6 04:02:41 INFO - PROCESS | 9684 | 04:02:41 INFO - PROCESS | 9684 | [#0] dojo.html Cycles:165 Average:5569.55 Median:5760.71 stddev:623.81 (10.8%) stddev-sans-first:598.02 04:02:41 INFO - PROCESS | 9684 | Values: 3226.1 3233.3 3251.9 3274.9 3301.8 5533.2 5626.4 5638.7 5653.6 5661.2 5630.4 5706.5 5707.0 5709.3 5715.8 4977.1 5073.4 5153.8 5164.6 5217.7 5116.1 5125.9 5134.6 5180.9 5191.8 5071.9 5074.4 5086.0 5112.6 5127.4 5150.4 5166.0 5202.9 5220.5 5230.5 4934.2 4955.8 4960.8 5123.9 5124.0 5497.7 5504.7 5508.6 5516.0 5547.7 6023.6 6040.2 6047.2 6047.2 6049.2 5846.4 6021.3 6027.8 6075.8 6095.4 5717.9 5717.9 5719.9 5730.9 5744.0 6206.5 6237.4 6239.3 6240.7 6246.9 5771.8 5804.3 5895.8 5900.2 5970.8 6003.1 6012.8 6015.8 6016.9 6018.9 5966.8 5986.9 5989.5 5990.3 5993.3 5792.5 5916.6 5938.0 5941.3 6042.9 4597.1 4599.6 4601.7 4609.8 4611.4 4118.0 4134.5 4134.7 4142.4 4168.3 5533.6 5604.3 5639.9 5649.7 5697.9 6027.7 6028.4 6033.5 6037.8 6038.0 5648.4 5654.0 5654.9 5663.0 5666.3 5028.1 5114.2 5151.2 5151.4 5219.5 6208.4 6221.4 6222.9 6223.5 6229.9 5881.9 5961.9 6015.2 6018.4 6026.9 6010.2 6017.8 6117.0 6133.2 6189.3 5895.9 5896.5 5899.1 5916.6 5920.1 6004.1 6008.8 6010.5 6019.3 6021.0 5725.1 5787.8 5822.1 5829.4 5918.4 5757.9 5795.6 5796.2 5797.1 5805.7 5690.4 5709.0 5746.8 5760.7 5763.5 5657.4 5763.0 5779.6 5872.2 5952.2 5748.1 5761.9 5774.8 5778.5 5787.7 04:02:41 INFO - PROCESS | 9684 | 04:02:41 INFO - PROCESS | 9684 | [#1] ext.html Cycles:180 Average:62692.22 Median:207797.62 stddev:121608.21 (58.5%) stddev-sans-first:121859.96 04:02:41 INFO - PROCESS | 9684 | Values: 588.1 634.6 655.3 666.0 668.9 34596.5 47042.0 47737.3 47861.5 48002.3 48101.3 48826.8 48898.8 48919.5 48937.5 8664.7 9369.0 9515.1 9522.7 11164.6 11190.5 11325.6 11335.4 11336.5 11339.9 11442.1 11481.2 11481.7 11493.7 11501.2 8630.6 11191.9 11255.6 11392.8 11456.1 11099.2 11188.7 11210.6 11216.2 11224.9 44192.8 44512.3 44589.0 44618.8 44641.0 3963.0 4021.3 4033.4 4088.8 4359.4 34389.1 34696.3 34700.3 34739.3 34865.7 4687.2 4705.1 4725.3 4739.5 4807.0 373958.0 375198.0 378038.0 378066.4 380771.0 63071.7 63499.7 63551.0 63629.7 63756.7 37100.4 37520.4 37557.2 39720.6 59639.0 19650.7 19876.8 19893.2 19908.4 19911.8 13405.9 13704.7 13981.2 14012.1 15124.1 20792.0 20918.5 21170.5 21195.9 21223.0 7368.2 7470.3 7575.8 7603.7 7703.3 1021.0 1072.0 1120.2 1156.5 1157.0 32428.0 34861.3 35400.0 35465.3 35570.3 1142.8 1170.3 1174.9 1228.3 1237.3 17535.4 18371.4 18434.5 18443.7 18443.7 591823.0 598651.0 599375.0 599456.0 599492.0 65680.3 66439.3 66457.0 66475.3 66540.0 1362.4 1397.3 1424.2 1486.8 1497.3 33116.5 37031.8 37253.6 37286.8 37536.8 55232.7 55841.4 55961.7 56081.4 56096.7 361929.0 362154.0 364727.0 365377.0 366108.0 26454.7 31026.2 31579.5 31818.2 33388.2 54291.2 54295.7 54372.7 55175.2 55328.7 45039.5 45934.1 47125.3 47405.3 48848.5 46969.0 47067.3 47160.5 47166.5 47663.8 45688.3 46875.3 46919.5 46976.8 47250.5 38147.0 39952.8 40561.0 40810.4 41052.6 30313.4 30743.8 30988.7 31207.2 31468.0 04:02:41 INFO - PROCESS | 9684 | 04:02:41 INFO - PROCESS | 9684 | [#2] jquery.html Cycles:180 Average:31357.55 Median:16206.31 stddev:92050.34 (568.0%) stddev-sans-first:92279.21 04:02:41 INFO - PROCESS | 9684 | Values: 395.9 412.5 426.0 427.2 439.3 16049.8 21074.9 22176.0 22331.3 22380.6 20509.6 21331.3 21374.7 21428.3 21472.7 5738.4 5763.5 5916.2 5958.0 5965.5 5840.4 5883.6 5884.4 5895.5 5903.9 5670.7 5817.6 5849.1 5856.0 5873.4 5887.3 5916.6 5920.5 5933.3 5937.8 5270.1 5294.6 5328.6 5336.4 5348.9 25232.0 25268.5 25319.4 25492.9 25531.5 2315.4 2600.7 2639.0 2679.5 3023.8 10616.3 10797.5 10894.7 11133.8 12248.1 1901.6 2000.8 2090.9 2104.1 2105.6 80800.2 89551.0 91158.0 91389.0 91606.5 10828.7 11040.6 11298.8 11439.7 12492.0 15819.4 16034.9 16202.8 16319.6 16342.9 8569.1 8883.0 8965.2 8965.9 9172.0 8185.8 8732.3 8767.2 8795.6 8982.0 4949.2 5034.6 5055.8 5121.6 5170.2 2091.5 2100.5 2126.6 2131.0 2138.6 475.0 482.2 980.8 1046.7 1087.8 12260.6 12498.6 12499.3 12533.6 12534.4 478.3 494.8 496.5 505.6 509.5 6436.1 6505.1 6608.6 6633.4 6754.7 545895.0 548878.0 550886.0 570761.0 572491.0 94300.8 101834.0 102288.0 103942.5 104189.0 1042.9 1067.6 1092.4 1100.4 1101.2 13425.7 18315.5 18720.0 19102.0 19152.5 32016.7 44515.8 44537.9 45678.8 45852.5 60024.4 60822.4 61206.0 61227.4 62586.1 8270.0 8309.1 8343.2 8350.5 8454.6 11019.7 12719.4 12861.9 12862.0 13040.2 10995.2 11004.0 11035.9 11198.0 11307.7 11064.8 11185.2 11218.8 11318.7 11787.5 10767.9 10847.5 11290.9 11330.5 11422.9 7951.6 8083.6 8084.4 8314.2 8494.3 9469.3 10742.1 10837.4 10839.4 11182.3 04:02:41 INFO - PROCESS | 9684 | 04:02:41 INFO - PROCESS | 9684 | [#3] mootools.html Cycles:180 Average:6423.26 Median:2463.66 stddev:3779.92 (153.4%) stddev-sans-first:3772.78 04:02:41 INFO - PROCESS | 9684 | Values: 1550.6 1553.1 1775.3 1856.5 1888.7 9232.4 9904.9 9997.8 9999.2 10002.3 8414.9 9160.5 9480.3 9531.3 9652.9 2468.2 2501.0 2506.4 2508.3 2509.2 2462.8 2523.1 2527.8 2529.2 2531.6 2483.1 2488.9 2515.2 2524.7 2526.4 2396.5 2404.2 2413.0 2441.1 2443.9 2372.7 2381.3 2383.5 2386.0 2452.5 9667.3 9718.0 9781.0 9789.5 9790.0 5691.1 5855.6 5860.0 5861.9 5869.2 3484.9 3639.5 3667.5 3670.2 3693.1 1216.9 1217.3 1229.5 1267.6 1271.2 14028.1 14076.7 14077.9 14100.8 14110.0 4935.9 5081.0 5114.6 5124.7 5195.3 11636.8 11666.2 11669.4 11670.4 11673.2 5871.4 5873.9 5877.2 5877.5 5881.6 4743.7 4793.0 5240.4 5320.0 5372.2 4377.2 4379.5 4434.3 4478.1 4624.1 1385.1 1394.8 1397.1 1401.8 1424.3 1059.9 1063.3 1073.9 1082.1 1083.1 6238.7 6391.1 6547.1 6583.5 6667.5 445.3 449.2 455.9 458.6 463.2 4186.4 4210.6 4254.7 4255.2 4270.4 11942.2 12414.3 12435.9 12461.4 12482.4 12358.7 12377.9 12379.6 12393.9 12406.3 8651.4 8927.0 9034.7 9128.8 9294.0 6586.4 6668.0 6722.8 6832.1 6961.3 10782.2 11511.8 11596.6 11630.2 11745.9 11307.8 11324.2 11512.6 11555.4 11575.1 8517.8 8910.4 8960.7 9066.7 9077.5 9345.5 9397.7 9407.7 9426.4 9486.3 8667.1 8673.4 8690.8 8793.3 8881.7 8443.4 8558.1 8654.2 8682.9 8864.0 7544.5 7669.3 7669.7 7670.4 7674.2 5145.4 5222.2 5251.4 5280.2 5512.3 5642.0 5687.8 5709.9 5759.6 5770.6 04:02:41 INFO - PROCESS | 9684 | 04:02:41 INFO - PROCESS | 9684 | [#4] prototype.html Cycles:180 Average:4737.25 Median:5252.16 stddev:1361.76 (25.9%) stddev-sans-first:1325.04 04:02:41 INFO - PROCESS | 9684 | Values: 343.3 350.6 354.2 355.7 357.0 5597.2 5650.4 5674.8 5703.9 5710.3 5225.0 5338.1 5459.0 5540.9 5545.8 4345.8 4393.7 4411.8 4417.2 4417.7 4400.8 4440.8 4442.2 4449.6 4450.0 4278.4 4322.3 4349.2 4375.8 4399.8 4331.2 4331.4 4339.2 4341.1 4354.8 3859.6 3870.8 3879.2 3880.7 3905.5 5263.8 5355.4 5452.3 5472.6 5497.9 4974.1 4982.5 4996.3 5006.5 5033.1 5757.9 5777.9 5783.5 5783.5 5800.2 3967.2 4173.7 4212.7 4221.3 4285.0 6011.4 6017.5 6025.4 6031.3 6038.0 4293.6 4316.7 4321.0 4325.0 4329.2 4174.7 4338.4 4361.6 4363.9 4381.0 4907.1 4920.2 4927.7 4940.5 4953.3 5849.1 5868.9 5869.8 5911.9 5929.5 1414.4 1441.1 1461.0 1494.1 1502.5 998.5 1017.7 1027.6 1029.4 1041.3 5555.4 5572.5 5595.9 5605.4 5621.9 5901.6 5904.2 5920.9 5950.4 5955.6 5427.3 5536.0 5541.9 5550.2 5594.6 2731.7 2776.9 2790.9 2806.5 2818.8 5295.0 5314.0 5343.8 5380.6 5401.4 4945.6 5253.8 5253.9 5268.4 5269.2 5212.6 5229.7 5248.8 5250.5 5263.2 5098.8 5169.4 5169.6 5179.6 5231.0 4819.0 5155.8 5177.9 5192.5 5192.7 5305.6 5311.4 5346.3 5353.4 5379.0 3897.6 3901.4 3917.6 3964.6 3975.5 4976.6 5264.6 5271.5 5272.7 5285.5 5654.9 5771.5 5792.4 5837.3 5869.7 5813.0 5829.1 5838.7 5844.6 5906.5 5643.0 5772.5 5786.9 5810.5 5820.6 5842.6 5866.6 5872.5 5872.8 5895.3 5572.7 5756.4 5756.4 5772.0 5781.9 04:02:41 INFO - PROCESS | 9684 | 04:02:41 INFO - PROCESS | 9684 | [#5] yui.html Cycles:180 Average:21983.92 Median:3977.56 stddev:30864.30 (776.0%) stddev-sans-first:30910.02 04:02:41 INFO - PROCESS | 9684 | Values: 831.3 836.4 873.3 882.6 909.2 16383.3 17962.6 18013.5 18050.0 18099.1 16079.5 16283.4 16287.8 16295.7 16322.7 751.9 885.0 893.6 897.5 900.8 894.3 894.5 895.3 896.0 896.4 939.1 940.1 942.6 942.9 944.2 783.5 917.2 931.5 936.2 938.5 677.6 679.6 679.7 680.2 680.2 14586.3 14628.1 14697.3 14705.3 14717.7 15789.9 16031.5 16153.3 16320.8 16380.0 12590.5 12673.4 12687.4 14412.2 14904.8 4756.6 4835.2 4837.9 4841.7 4950.7 84157.6 85219.6 85245.1 85419.6 85453.6 12377.7 12476.9 12522.8 12535.4 12597.7 21615.1 22117.7 22244.3 22252.0 22300.9 9381.3 9403.0 9413.1 9460.5 9524.6 5266.1 5266.7 5350.3 5442.4 5632.1 3906.5 4048.7 4066.6 4069.5 4083.9 689.9 712.6 714.2 724.7 725.7 736.3 751.6 834.8 846.9 849.9 10386.0 10784.1 11272.5 11491.2 12210.5 726.6 758.4 788.8 841.4 850.8 4919.2 5170.4 5212.3 5216.9 5481.5 113330.0 114653.4 115655.4 118836.0 119599.4 103867.5 106440.0 106906.9 107062.5 107231.0 54211.5 70190.6 87185.6 88388.0 88799.0 10317.5 10388.9 10585.0 10661.0 10727.0 53315.5 53674.0 54282.7 54330.0 55431.7 71946.6 78833.1 82267.6 83838.6 84340.0 13442.5 13705.6 13976.9 14413.5 14469.2 13641.8 16901.8 16929.7 17024.2 17123.5 13421.3 13609.5 15662.6 15801.2 15975.0 13394.1 13514.0 15147.5 15395.3 15517.3 14421.1 15573.5 15736.4 15851.4 16325.1 11885.5 12246.1 12357.8 12554.6 12686.6 12463.3 12625.7 12777.7 12815.9 12843.7 04:02:41 INFO - PROCESS | 9684 | -------- Summary: end -------- 04:02:41 INFO - PROCESS | 9684 | 04:02:41 INFO - TEST-INFO | 9684: exit 0 04:02:41 INFO - TEST-OK | dromaeo_css | took 1112973ms 04:02:41 INFO - TEST-START | kraken 04:02:41 INFO - operating with platform_type : linux_ 04:02:41 INFO - Initialising browser for kraken test... 04:02:41 INFO - TEST-INFO | started process 9887 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpy3QJle/profile http://localhost:46588/getInfo.html) 04:02:45 INFO - PROCESS | 9887 | __metrics Screen width/height:1600/1200 04:02:45 INFO - PROCESS | 9887 | colorDepth:24 04:02:45 INFO - PROCESS | 9887 | Browser inner width/height: 1024/697 04:02:45 INFO - PROCESS | 9887 | __metrics 04:02:46 INFO - PROCESS | 9887 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:02:46 INFO - PROCESS | 9887 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:02:47 INFO - TEST-INFO | 9887: exit 0 04:02:47 INFO - Browser initialized. 04:02:47 INFO - Running cycle 1/1 for kraken test... 04:02:47 INFO - TEST-INFO | started process 9976 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpy3QJle/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:02:59 INFO - PROCESS | 9976 | RSS: Main: 145387520 04:02:59 INFO - PROCESS | 9976 | 04:03:01 INFO - PROCESS | 9976 | Cycle 1(1): loaded http://localhost:46588/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:46588/tests/kraken/driver.html?testName=audio-beat-detection) 04:03:02 INFO - PROCESS | 9976 | RSS: Main: 201224192 04:03:02 INFO - PROCESS | 9976 | 04:03:05 INFO - PROCESS | 9976 | Cycle 1(1): loaded http://localhost:46588/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:46588/tests/kraken/driver.html?testName=audio-dft) 04:03:05 INFO - PROCESS | 9976 | RSS: Main: 185102336 04:03:05 INFO - PROCESS | 9976 | 04:03:08 INFO - PROCESS | 9976 | Cycle 1(1): loaded http://localhost:46588/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:46588/tests/kraken/driver.html?testName=audio-fft) 04:03:09 INFO - PROCESS | 9976 | RSS: Main: 276570112 04:03:09 INFO - PROCESS | 9976 | 04:03:11 INFO - PROCESS | 9976 | Cycle 1(1): loaded http://localhost:46588/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:46588/tests/kraken/driver.html?testName=audio-oscillator) 04:03:11 INFO - PROCESS | 9976 | RSS: Main: 182050816 04:03:11 INFO - PROCESS | 9976 | 04:03:14 INFO - PROCESS | 9976 | Cycle 1(1): loaded http://localhost:46588/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:46588/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:03:14 INFO - PROCESS | 9976 | RSS: Main: 180776960 04:03:14 INFO - PROCESS | 9976 | 04:03:17 INFO - PROCESS | 9976 | Cycle 1(1): loaded http://localhost:46588/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:46588/tests/kraken/driver.html?testName=imaging-darkroom) 04:03:18 INFO - PROCESS | 9976 | RSS: Main: 216887296 04:03:18 INFO - PROCESS | 9976 | 04:03:21 INFO - PROCESS | 9976 | Cycle 1(1): loaded http://localhost:46588/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:46588/tests/kraken/driver.html?testName=imaging-desaturate) 04:03:22 INFO - PROCESS | 9976 | RSS: Main: 215810048 04:03:22 INFO - PROCESS | 9976 | 04:03:24 INFO - PROCESS | 9976 | Cycle 1(1): loaded http://localhost:46588/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:46588/tests/kraken/driver.html?testName=json-parse-financial) 04:03:25 INFO - PROCESS | 9976 | RSS: Main: 215814144 04:03:25 INFO - PROCESS | 9976 | 04:03:27 INFO - PROCESS | 9976 | Cycle 1(1): loaded http://localhost:46588/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:46588/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:03:27 INFO - PROCESS | 9976 | RSS: Main: 178204672 04:03:27 INFO - PROCESS | 9976 | 04:03:29 INFO - PROCESS | 9976 | Cycle 1(1): loaded http://localhost:46588/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:46588/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:03:30 INFO - PROCESS | 9976 | RSS: Main: 184414208 04:03:30 INFO - PROCESS | 9976 | 04:03:31 INFO - PROCESS | 9976 | Cycle 1(1): loaded http://localhost:46588/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:46588/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:03:32 INFO - PROCESS | 9976 | RSS: Main: 186871808 04:03:32 INFO - PROCESS | 9976 | 04:03:35 INFO - PROCESS | 9976 | Cycle 1(1): loaded http://localhost:46588/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:46588/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:03:35 INFO - PROCESS | 9976 | RSS: Main: 180965376 04:03:35 INFO - PROCESS | 9976 | 04:03:38 INFO - PROCESS | 9976 | Cycle 1(1): loaded http://localhost:46588/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:46588/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:03:39 INFO - PROCESS | 9976 | RSS: Main: 184664064 04:03:39 INFO - PROCESS | 9976 | 04:03:40 INFO - PROCESS | 9976 | Cycle 1(1): loaded http://localhost:46588/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:46588/tests/kraken/driver.html?testName=ai-astar) 04:03:40 INFO - PROCESS | 9976 | RSS: Main: 235327488 04:03:40 INFO - PROCESS | 9976 | 04:03:40 INFO - PROCESS | 9976 | __start_tp_report 04:03:40 INFO - PROCESS | 9976 | _x_x_mozilla_page_load 04:03:40 INFO - PROCESS | 9976 | _x_x_mozilla_page_load_details 04:03:40 INFO - PROCESS | 9976 | |i|pagename|runs| 04:03:40 INFO - PROCESS | 9976 | |0;ai-astar;110;110;117;109;108;111;109;109;109;110 04:03:40 INFO - PROCESS | 9976 | |1;audio-beat-detection;146;115;141;169;116;149;143;116;167;115 04:03:40 INFO - PROCESS | 9976 | |2;audio-dft;160;160;160;160;163;161;161;161;161;161 04:03:40 INFO - PROCESS | 9976 | |3;audio-fft;75;74;75;178;76;75;74;77;161;77 04:03:40 INFO - PROCESS | 9976 | |4;audio-oscillator;84;83;157;83;83;83;134;83;82;147 04:03:40 INFO - PROCESS | 9976 | |5;imaging-gaussian-blur;116;117;117;117;118;116;117;117;117;117 04:03:40 INFO - PROCESS | 9976 | |6;imaging-darkroom;145;146;146;146;147;145;145;146;146;146 04:03:40 INFO - PROCESS | 9976 | |7;imaging-desaturate;95;95;95;95;95;95;95;95;95;94 04:03:40 INFO - PROCESS | 9976 | |8;json-parse-financial;50;50;50;50;51;49;49;49;50;49 04:03:40 INFO - PROCESS | 9976 | |9;json-stringify-tinderbox;59;59;60;60;68;59;59;59;60;59 04:03:40 INFO - PROCESS | 9976 | |10;stanford-crypto-aes;73;74;73;72;77;73;73;72;72;73 04:03:40 INFO - PROCESS | 9976 | |11;stanford-crypto-ccm;119;123;116;118;122;129;116;117;186;117 04:03:40 INFO - PROCESS | 9976 | |12;stanford-crypto-pbkdf2;153;153;150;149;151;158;157;156;150;149 04:03:40 INFO - PROCESS | 9976 | |13;stanford-crypto-sha256-iterative;62;63;63;68;61;60;65;61;59;60 04:03:40 INFO - PROCESS | 9976 | __end_tp_report 04:03:40 INFO - PROCESS | 9976 | __start_cc_report 04:03:40 INFO - PROCESS | 9976 | _x_x_mozilla_cycle_collect,1686 04:03:40 INFO - PROCESS | 9976 | __end_cc_report 04:03:40 INFO - PROCESS | 9976 | __startTimestamp1473591820923__endTimestamp 04:03:40 INFO - PROCESS | 9976 | 04:03:40 INFO - PROCESS | 9976 | ------- Summary: start ------- 04:03:40 INFO - PROCESS | 9976 | Number of tests: 14 04:03:40 INFO - PROCESS | 9976 | 04:03:40 INFO - PROCESS | 9976 | [#0] ai-astar Cycles:10 Average:110.20 Median:110.00 stddev:2.53 (2.3%) stddev-sans-first:2.68 04:03:40 INFO - PROCESS | 9976 | Values: 110.0 110.0 117.0 109.0 108.0 111.0 109.0 109.0 109.0 110.0 04:03:40 INFO - PROCESS | 9976 | 04:03:40 INFO - PROCESS | 9976 | [#1] audio-beat-detection Cycles:10 Average:137.70 Median:144.50 stddev:21.20 (14.7%) stddev-sans-first:22.28 04:03:40 INFO - PROCESS | 9976 | Values: 146.0 115.0 141.0 169.0 116.0 149.0 143.0 116.0 167.0 115.0 04:03:40 INFO - PROCESS | 9976 | 04:03:40 INFO - PROCESS | 9976 | [#2] audio-dft Cycles:10 Average:160.80 Median:161.00 stddev:0.92 (0.6%) stddev-sans-first:0.93 04:03:40 INFO - PROCESS | 9976 | Values: 160.0 160.0 160.0 160.0 163.0 161.0 161.0 161.0 161.0 161.0 04:03:40 INFO - PROCESS | 9976 | 04:03:40 INFO - PROCESS | 9976 | [#3] audio-fft Cycles:10 Average:94.20 Median:75.00 stddev:39.90 (53.2%) stddev-sans-first:41.71 04:03:40 INFO - PROCESS | 9976 | Values: 75.0 74.0 75.0 178.0 76.0 75.0 74.0 77.0 161.0 77.0 04:03:40 INFO - PROCESS | 9976 | 04:03:40 INFO - PROCESS | 9976 | [#4] audio-oscillator Cycles:10 Average:101.90 Median:83.00 stddev:30.92 (37.2%) stddev-sans-first:32.11 04:03:40 INFO - PROCESS | 9976 | Values: 84.0 83.0 157.0 83.0 83.0 83.0 134.0 83.0 82.0 147.0 04:03:40 INFO - PROCESS | 9976 | 04:03:40 INFO - PROCESS | 9976 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.50 04:03:40 INFO - PROCESS | 9976 | Values: 116.0 117.0 117.0 117.0 118.0 116.0 117.0 117.0 117.0 117.0 04:03:40 INFO - PROCESS | 9976 | 04:03:40 INFO - PROCESS | 9976 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.63 (0.4%) stddev-sans-first:0.60 04:03:40 INFO - PROCESS | 9976 | Values: 145.0 146.0 146.0 146.0 147.0 145.0 145.0 146.0 146.0 146.0 04:03:40 INFO - PROCESS | 9976 | 04:03:40 INFO - PROCESS | 9976 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 04:03:40 INFO - PROCESS | 9976 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 94.0 04:03:40 INFO - PROCESS | 9976 | 04:03:40 INFO - PROCESS | 9976 | [#8] json-parse-financial Cycles:10 Average:49.70 Median:50.00 stddev:0.67 (1.3%) stddev-sans-first:0.71 04:03:40 INFO - PROCESS | 9976 | Values: 50.0 50.0 50.0 50.0 51.0 49.0 49.0 49.0 50.0 49.0 04:03:40 INFO - PROCESS | 9976 | 04:03:40 INFO - PROCESS | 9976 | [#9] json-stringify-tinderbox Cycles:10 Average:60.20 Median:59.50 stddev:2.78 (4.7%) stddev-sans-first:2.92 04:03:40 INFO - PROCESS | 9976 | Values: 59.0 59.0 60.0 60.0 68.0 59.0 59.0 59.0 60.0 59.0 04:03:40 INFO - PROCESS | 9976 | 04:03:40 INFO - PROCESS | 9976 | [#10] stanford-crypto-aes Cycles:10 Average:73.20 Median:73.00 stddev:1.48 (2.0%) stddev-sans-first:1.56 04:03:40 INFO - PROCESS | 9976 | Values: 73.0 74.0 73.0 72.0 77.0 73.0 73.0 72.0 72.0 73.0 04:03:40 INFO - PROCESS | 9976 | 04:03:40 INFO - PROCESS | 9976 | [#11] stanford-crypto-ccm Cycles:10 Average:126.30 Median:120.50 stddev:21.36 (17.7%) stddev-sans-first:22.50 04:03:40 INFO - PROCESS | 9976 | Values: 119.0 123.0 116.0 118.0 122.0 129.0 116.0 117.0 186.0 117.0 04:03:40 INFO - PROCESS | 9976 | 04:03:40 INFO - PROCESS | 9976 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.60 Median:153.00 stddev:3.37 (2.2%) stddev-sans-first:3.57 04:03:40 INFO - PROCESS | 9976 | Values: 153.0 153.0 150.0 149.0 151.0 158.0 157.0 156.0 150.0 149.0 04:03:40 INFO - PROCESS | 9976 | 04:03:40 INFO - PROCESS | 9976 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.20 Median:62.50 stddev:2.70 (4.3%) stddev-sans-first:2.86 04:03:40 INFO - PROCESS | 9976 | Values: 62.0 63.0 63.0 68.0 61.0 60.0 65.0 61.0 59.0 60.0 04:03:40 INFO - PROCESS | 9976 | -------- Summary: end -------- 04:03:40 INFO - PROCESS | 9976 | 04:03:41 INFO - TEST-INFO | 9976: exit 0 04:03:41 INFO - TEST-OK | kraken | took 60006ms 04:03:41 INFO - SUITE-END | took 1172s 04:03:42 INFO - Completed test suite (00:19:33) 04:03:42 INFO - javascript benchmark 04:03:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3226.1128189747815, 3233.337165880994, 3251.9024429267115, 3274.8690052397906, 3301.7523685723577, 5533.197686335484, 5626.409227031164, 5638.689872057035, 5653.575981801361, 5661.179129026289, 5630.40880707525, 5706.486416222539, 5707.001274776908, 5709.343425506068, 5715.828525144252, 4977.10412125819, 5073.391193056838, 5153.845384638467, 5164.561012313949, 5217.660852044605, 5116.130257856174, 5125.923111153317, 5134.563562097226, 5180.922286165711, 5191.844244672666, 5071.949280507185, 5074.391073071237, 5086.008228395464, 5112.616553758465, 5127.359080114986, 5150.381954165506, 5165.992631436871, 5202.92195617066, 5220.504052115081, 5230.503102205284, 4934.210526315772, 4955.776990035421, 4960.826371076995, 5123.87190320245, 5124.026434977342, 5497.670139791625, 5504.669719816824, 5508.614396992212, 5516.0, 5547.694876781776, 6023.638581685069, 6040.154378387029, 6047.15339852421, 6047.21386219793, 6049.183360246355, 5846.3861294563885, 6021.337652858182, 6027.789027384064, 6075.817725468199, 6095.390460953913, 5717.914231286534, 5717.914231286576, 5719.8570035749435, 5730.885382292373, 5744.023516002248, 6206.472449841724, 6237.376262373727, 6239.313675495692, 6240.719167637377, 6246.937530624636, 5771.826845194651, 5804.332501762267, 5895.823125306248, 5900.20347253124, 5970.820875373745, 6003.0695242237525, 6012.759489620366, 6015.789447369409, 6016.879662406727, 6018.939810601925, 5966.791162309299, 5986.880262394728, 5989.460948514655, 5990.251218597676, 5993.250843644544, 5792.478676919098, 5916.615419997773, 5938.049912014143, 5941.257342832146, 6042.939570604326, 4597.103564804898, 4599.609033232213, 4601.74690392025, 4609.792559334838, 4611.377464042378, 4118.0, 4134.503859536815, 4134.669226461936, 4142.357934520154, 4168.333066709312, 5533.6403133796175, 5604.271444712243, 5639.887202256014, 5649.689267090267, 5697.857553561194, 6027.6986150693165, 6028.367021462771, 6033.456988871023, 6037.849053773691, 6038.0, 5648.378678345379, 5653.971730141323, 5654.9151762723595, 5663.0, 5666.348369937427, 5028.094942910238, 5114.207297868837, 5151.150060240018, 5151.381834179848, 5219.4780521947505, 6208.410201030985, 6221.440070393596, 6222.875542489125, 6223.533235007392, 6229.875402491924, 5881.941250574938, 5961.926853166472, 6015.218021657157, 6018.428249316308, 6026.90959635597, 6010.248718910137, 6017.819465416044, 6116.974953844038, 6133.233345831771, 6189.257289125334, 5895.882082358415, 5896.469317761422, 5899.085641725539, 5916.585838991229, 5920.082387229986, 6004.099385092271, 6008.819735407945, 6010.4891084258325, 6019.307779605401, 6020.969895150496, 5725.141228815711, 5787.768489260382, 5822.097574875901, 5829.358770535153, 5918.378570250234, 5757.9424205757405, 5795.623284486495, 5796.188533605383, 5797.072468405035, 5805.738741756547, 5690.430956904442, 5709.029464990878, 5746.798862039808, 5760.711964401679, 5763.51010164124, 5657.43425657431, 5762.971185144047, 5779.566532509994, 5872.236609240771, 5952.166696662384, 5748.051571490657, 5761.913571296518, 5774.797882074107, 5778.508826749605, 5787.710614469175], "value": 5528.003391732272, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [588.1207594646005, 634.6255709131613, 655.3151956205764, 665.9667016649167, 668.8561959178778, 34596.4810527842, 47042.0, 47737.28394074083, 47861.521384786145, 48002.279965800495, 48101.27848082282, 48826.75586622062, 48898.75550622242, 48919.51080489194, 48937.51062489374, 8664.653413863469, 9368.95315523423, 9515.143637072662, 9522.714318570454, 11164.553417863275, 11190.496427660733, 11325.603603873866, 11335.37655428951, 11336.489857956412, 11339.886601133965, 11442.084633229364, 11481.196316257865, 11481.655550333462, 11493.712657183554, 11501.194916355858, 8630.611622476972, 11191.888081119247, 11255.606053788078, 11392.829107563393, 11456.140789440784, 11099.223054386197, 11188.66434006981, 11210.551577936872, 11216.214864959456, 11224.943875280653, 44192.779036104934, 44512.3323150153, 44589.0, 44618.776906115585, 44641.0, 3963.0, 4021.336479480882, 4033.3748269018056, 4088.7751173685438, 4359.378026311223, 34389.14027149316, 34696.30607387838, 34700.30599388023, 34739.30521389558, 34865.651343486745, 4687.226607609774, 4705.106029854317, 4725.31482934976, 4739.502352253033, 4806.951930480685, 373958.0, 375197.99999999726, 378038.0, 378066.4386712278, 380771.0, 63071.68464157742, 63499.682501587195, 63551.0, 63629.681851590445, 63756.68121659362, 37100.44349334762, 37520.43719344212, 37557.24885502275, 39720.60279397169, 59639.0, 19650.70523942142, 19876.801231987785, 19893.204271828963, 19908.402747917586, 19911.800881991283, 13405.932970335281, 13704.65738356549, 13981.161130332213, 14012.089214201043, 15124.092554446768, 20792.0, 20918.47703807417, 21170.470738231666, 21195.8940205298, 21223.0, 7368.189499155089, 7470.3276705096805, 7575.772726818204, 7603.7338693145475, 7703.306702396699, 1021.0300214795974, 1071.9624813131502, 1120.2158489057529, 1156.525824411987, 1156.9521946155257, 32428.0, 34861.30277394489, 35399.991000764705, 35465.29069418597, 35570.288594228485, 1142.782871254459, 1170.3329102411715, 1174.864890537582, 1228.3244215681339, 1237.2638280223252, 17535.386261480788, 18371.357002504847, 18434.539136521693, 18443.72334414985, 18443.72334414985, 591823.0, 598651.0, 599375.0, 599456.0, 599492.0, 65680.34319656838, 66439.33560664428, 66457.0, 66475.33524664691, 66540.0, 1362.4413990263945, 1397.3013493253372, 1424.1668623854953, 1486.7992820969248, 1497.3336865095146, 33116.503252451716, 37031.8148409267, 37253.62746372502, 37286.813565931996, 37536.812315938245, 55232.72383638217, 55841.44158558363, 55961.72019139878, 56081.439185609255, 56096.719516402154, 361929.0, 362154.0, 364727.0, 365377.0, 366108.0, 26454.735452645225, 31026.224344390666, 31579.526307104952, 31818.204544885637, 33388.16529586683, 54291.18563221476, 54295.728521358724, 54372.72813635907, 55175.17237241365, 55328.72335638296, 45039.54960450353, 45934.08131837278, 47125.29312060391, 47405.288920666906, 48848.5115148844, 46969.0, 47067.29399059086, 47160.52839471561, 47166.52833471621, 47663.76168119137, 45688.31467528056, 46875.296870546284, 46919.530804692884, 46976.7651161742, 47250.52749472599, 38147.04632384213, 39952.800235998635, 40560.98597535085, 40810.387844181765, 41052.589474104876, 30313.393732124794, 30743.846280769347, 30988.69011309948, 31207.219819504695, 31468.0], "value": 22017.40650360485, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [395.9489842615252, 412.4596778220533, 425.9680523960703, 427.17341943339636, 439.2510769138619, 16049.83950160498, 21074.89462552687, 22176.0, 22331.330060098182, 22380.552388952212, 20509.589808203826, 21331.25340613079, 21374.679379809317, 21428.25001124957, 21472.677909831364, 5738.42615738427, 5763.481286684198, 5916.171735956969, 5958.046712525997, 5965.49293310067, 5840.386759390245, 5883.588148829584, 5884.352721200686, 5895.528357731371, 5903.911441328383, 5670.659760414368, 5817.62185457946, 5849.122631605272, 5855.970720146393, 5873.383294754053, 5887.322957859858, 5916.615419997686, 5920.526357891358, 5933.317668468117, 5937.7921772737745, 5270.130428479335, 5294.576433885319, 5328.6269961102735, 5336.439673834269, 5348.866278343034, 25232.0, 25268.494630107296, 25319.367015824566, 25492.872535637387, 25531.48937021249, 2315.3632750993443, 2600.6749156355454, 2639.0499420208707, 2679.531082060651, 3023.7883348165637, 10616.309939853885, 10797.514111864984, 10894.67315980522, 11133.777324453547, 12248.081393895494, 1901.6196760647788, 2000.839932805372, 2090.9336238518313, 2104.084723145437, 2105.589410065038, 80800.19199807986, 89551.0, 91158.0, 91389.0, 91606.54196729041, 10828.675139745821, 11040.558377664804, 11298.830517542243, 11439.714007149887, 12492.0, 15819.446319378996, 16034.919825400799, 16202.837971620133, 16319.59201019984, 16342.93770904911, 8569.100244474364, 8882.955585222033, 8965.193132618097, 8965.910340896638, 9172.036936121745, 8185.836283274301, 8732.257758090633, 8767.21095101444, 8795.648174072965, 8981.955090224506, 4949.183384741549, 5034.597232221414, 5055.797768089235, 5121.615878809104, 5170.1986192140275, 2091.498040470276, 2100.510894589885, 2126.606577783115, 2130.957380852374, 2138.5936672032262, 474.9667523273338, 482.2115840600573, 980.8234517786867, 1046.7383154211448, 1087.8368244763349, 12260.570880019157, 12498.625041248777, 12499.250044997147, 12533.623991280276, 12534.435950382254, 478.3207844860307, 494.84165067178157, 496.51093672732327, 505.5525859614194, 509.54904909155664, 6436.098946147543, 6505.0892874997535, 6608.570442921291, 6633.402993730588, 6754.696038678271, 545895.0, 548878.0, 550886.0, 570761.0, 572491.0, 94300.81345526062, 101834.0, 102288.0, 103942.48028759808, 104189.0, 1042.926995110335, 1067.6156583629886, 1092.3937214845678, 1100.419516234429, 1101.1961268274204, 13425.731485370434, 18315.54211144732, 18720.0, 19102.0, 19152.52118696988, 32016.67983320137, 44515.777421113984, 44537.88655283644, 45678.77160614176, 45852.54147458616, 60024.39975600363, 60822.39177608167, 61206.0, 61227.387726123954, 62586.061209082814, 8269.95865020671, 8309.086000540057, 8343.207395297437, 8350.457220280905, 8454.64585738333, 11019.724506887393, 12719.427625756678, 12861.871381286443, 12862.0, 13040.217586944813, 10995.230333876552, 11004.0, 11035.889641103486, 11198.0, 11307.717307067389, 11064.778704426028, 11185.217034807485, 11218.775624487627, 11318.660440186808, 11787.528498859949, 10767.946160269463, 10847.45762711877, 11290.94354528255, 11330.546778128783, 11422.885771142182, 7951.602419879098, 8083.595820208849, 8084.39367047462, 8314.168583141638, 8494.277986371228, 9469.337146399686, 10742.140628749838, 10837.403942783223, 10839.403832789272, 11182.273152245078], "value": 9064.852772846227, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1550.6356006338508, 1553.1457698265954, 1775.3165031462884, 1856.5173055005712, 1888.688366419541, 9232.353735238528, 9904.860940991788, 9997.800043999116, 9999.200063994882, 10002.349847259922, 8414.873776893352, 9160.496172710498, 9480.288978326618, 9531.28515361349, 9652.903470965288, 2468.210172744718, 2500.987495062522, 2506.385935445818, 2508.2600632813273, 2509.2095989763166, 2462.7660372264604, 2523.0538548044483, 2527.7851382632407, 2529.2412276317123, 2531.620256961452, 2483.081259933827, 2488.8924428629266, 2515.2328539795353, 2524.7096583892812, 2526.418923647562, 2396.5446565152565, 2404.158544509425, 2412.95174096518, 2441.121196369306, 2443.8655873926928, 2372.7034120734907, 2381.2856143156982, 2383.5232953409386, 2386.0, 2452.497238066192, 9667.323287369887, 9718.0, 9780.951095244549, 9789.510524473819, 9789.951050244774, 5691.089425691869, 5855.648661080349, 5860.0, 5861.88276234473, 5869.2369991901205, 3484.9128771780656, 3639.490471334015, 3667.541557305337, 3670.229251857113, 3693.0582701411236, 1216.8520311842815, 1217.2696382170716, 1229.5081967213098, 1267.575362253646, 1271.2245530226555, 14028.088174268742, 14076.71846563063, 14077.859221407758, 14100.788488172686, 14110.0, 4935.925961110586, 5080.974595127074, 5114.590832733373, 5124.718140502308, 5195.337492002523, 11636.767264654829, 11666.241694289844, 11669.35818529972, 11670.416479176178, 11673.241239319586, 5871.3541510433815, 5873.911891321633, 5877.23595932526, 5877.52979761618, 5881.558883083786, 4743.715377077387, 4792.95207047932, 5240.371155461369, 5320.0, 5372.1941708744, 4377.233984052778, 4379.474463064453, 4434.312681534303, 4478.126765280804, 4624.12141693084, 1385.1066062389702, 1394.8186735724291, 1397.1267957526545, 1401.8177636907137, 1424.2593851197319, 1059.8834128245887, 1063.2504084620325, 1073.903348698621, 1082.1072615092423, 1083.090204228436, 6238.719257633326, 6391.09871513013, 6547.148870646881, 6583.473322134218, 6667.49993750461, 445.3053236950368, 449.19145538032063, 455.93388958600815, 458.5987261146497, 463.2102265637081, 4186.372044193396, 4210.5578914213565, 4254.659627229814, 4255.170241802881, 4270.359446083113, 11942.223755455867, 12414.317212553216, 12435.875641243652, 12461.439235234437, 12482.43829027696, 12358.691032724253, 12377.876221237853, 12379.62861114168, 12393.938030309972, 12406.25562466255, 8651.437656552303, 8926.955365223132, 9034.683786067457, 9128.771780705403, 9293.95353023244, 6586.407223349922, 6668.0, 6722.764703235363, 6832.145981752281, 6961.269066747918, 10782.24524283324, 11511.827322590001, 11596.594119205787, 11630.185886987829, 11745.941270293593, 11307.773844522899, 11324.150688698215, 11512.59705910289, 11555.42222888869, 11575.073994080285, 8517.829643407222, 8910.420822646507, 8960.731178064669, 9066.727998160066, 9077.500737459239, 9345.532723363669, 9397.671081512115, 9407.717768466957, 9426.387284826464, 9486.288528360263, 8667.089955554828, 8673.392862499564, 8690.78273043179, 8793.252573531321, 8881.689140880038, 8443.408961372645, 8558.144185581392, 8654.22112009922, 8682.869756953525, 8863.955680221557, 7544.47188696786, 7669.309762121436, 7669.654865531176, 7670.386369090571, 7674.232576742281, 5145.4340022598235, 5222.164453687392, 5251.422343542131, 5280.1815718563685, 5512.3385193777, 5642.0, 5687.77248910039, 5709.942900570941, 5759.596828221985, 5770.61505238748], "value": 4991.788583790995, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.2945297413815, 350.561797752809, 354.19420817639866, 355.71542765787365, 357.04134399138314, 5597.216389705444, 5650.378458369576, 5674.779922316701, 5703.942960570393, 5710.257666503359, 5225.0, 5338.145896656536, 5458.972705136479, 5540.944590554093, 5545.750441230144, 4345.760983145926, 4393.692441529094, 4411.7794110294435, 4417.249067658506, 4417.73493590384, 4400.845970391037, 4440.778785833905, 4442.244818380883, 4449.621782148505, 4450.0, 4278.4010238566625, 4322.298563397876, 4349.195398851207, 4375.781210939456, 4399.824007039714, 4331.242032644306, 4331.3719510670935, 4339.153864996327, 4341.131773645284, 4354.847580334705, 3859.6333348331864, 3870.78710670913, 3879.2047630235734, 3880.72834901557, 3905.5313362396696, 5263.842084737464, 5355.437679043722, 5452.345718513764, 5472.646761892384, 5497.917531237034, 4974.129527332702, 4982.451930287665, 4996.300517927493, 5006.524380183877, 5033.119204139298, 5757.942420575824, 5777.913331300034, 5783.5084017858535, 5783.5084017858535, 5800.216970708942, 3967.226390853785, 4173.749575025508, 4212.7472351659, 4221.324588065895, 4285.0, 6011.4289142531825, 6017.5185985121525, 6025.427584379477, 6031.336552979169, 6038.0, 4293.552674975296, 4316.719413238129, 4320.962968887445, 4325.0, 4329.15581461612, 4174.666026717855, 4338.414314067561, 4361.563843615613, 4363.869083927487, 4380.956190438055, 4907.1167189905445, 4920.188168952082, 4927.679700819435, 4940.456549779521, 4953.331300274489, 5849.1226316052935, 5868.94131058684, 5869.7945571904775, 5911.940880591225, 5929.466348028698, 1414.3988804757937, 1441.0592875973607, 1460.9634759131106, 1494.1184701026446, 1502.5342143935416, 998.502246630055, 1017.6641708236265, 1027.6403258859339, 1029.351508549609, 1041.2607048995146, 5555.444455554493, 5572.498475137257, 5595.944040559623, 5605.439456054443, 5621.887562248732, 5901.645901245939, 5904.232449781501, 5920.940790592125, 5950.4049595041015, 5955.553333500005, 5427.267318911975, 5536.0, 5541.889162216733, 5550.195221692832, 5594.566363631833, 2731.729745668234, 2776.9170023690776, 2790.9023184188454, 2806.4948309304114, 2818.7872520072056, 5294.973525132426, 5314.016906872241, 5343.839684809462, 5380.632521690522, 5401.351837779492, 4945.576667433004, 5253.816116435906, 5253.868653283698, 5268.367795864444, 5269.235960785664, 5212.635115541876, 5229.686218826807, 5248.790048398174, 5250.503606472137, 5263.210498435835, 5098.79604815818, 5169.379674439091, 5169.638125331191, 5179.559737422362, 5230.986070362862, 4818.975905120452, 5155.845324640267, 5177.870553236199, 5192.532672059382, 5192.688438693691, 5305.602079844104, 5311.362636483492, 5346.25152478661, 5353.384360798558, 5379.0, 3897.649211570972, 3901.4147877818555, 3917.6102577427177, 3964.563897971163, 3975.4633124528978, 4976.576990955809, 5264.605154613496, 5271.472852714851, 5272.657277276965, 5285.497877701612, 5654.8690261948595, 5771.480566749013, 5792.436042268648, 5837.314835175866, 5869.706514674334, 5812.95366833957, 5829.09649004405, 5838.708064596668, 5844.590878638624, 5906.527477801851, 5643.0, 5772.451617096369, 5786.913196302143, 5810.535157187331, 5820.565866138998, 5842.620229685057, 5866.5893387462465, 5872.500837428866, 5872.794452194153, 5895.263092113486, 5572.665640061609, 5756.366799651951, 5756.395578464201, 5771.97114014444, 5781.913271300849], "value": 4310.090324051124, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [831.280941985183, 836.4186890111373, 873.3188113271648, 882.616062013024, 909.2316992141638, 16383.344666213352, 17962.550936226602, 18013.459596212102, 18050.007249601284, 18099.095045247734, 16079.517614471555, 16283.430079947204, 16287.83712162878, 16295.674086518262, 16322.673546529062, 751.9248075192492, 884.9778755531099, 893.6157452295511, 897.4929165021741, 900.7973206028624, 894.2577660541734, 894.4767311122969, 895.3463971300954, 896.041235877608, 896.3904544909459, 939.0843927171022, 940.0928104379307, 942.5569982108398, 942.9387089839173, 944.2446043165475, 783.4985609210111, 917.2478567574592, 931.529577563333, 936.2089034765612, 938.5166639180827, 677.610374034927, 679.6397909108131, 679.6635665345636, 680.2007641021779, 680.2075581947025, 14586.34361453737, 14628.122312661275, 14697.33861976203, 14705.338259778335, 14717.70564588713, 15789.921050394789, 16031.519054428385, 16153.27310271029, 16320.75518867218, 16380.0, 12590.496380144783, 12673.366331683379, 12687.365631718378, 14412.207328596924, 14904.776428353583, 4756.595689366408, 4835.153848076572, 4837.854864354075, 4841.709497430165, 4950.727709975949, 84157.57921210355, 85219.57390213071, 85245.14754852497, 85419.57290213571, 85453.57273213657, 12377.690557735948, 12476.937615311865, 12522.81215781764, 12535.435905384098, 12597.685057873443, 21615.135394584355, 22117.66823497649, 22244.332670019925, 22252.0, 22300.888495557418, 9381.343305968654, 9403.0, 9413.105754953405, 9460.47967361788, 9524.571394287274, 5266.131088370376, 5266.7366631668265, 5350.27771250884, 5442.428545002717, 5632.070708333079, 3906.4726261954274, 4048.655864251513, 4066.573009833984, 4069.5116586009867, 4083.89790255246, 689.899964505154, 712.593821521738, 714.2464699741822, 724.6557885004686, 725.6807004917924, 736.260058641073, 751.5828715063083, 834.7746108550657, 846.9449485783405, 849.8597731374253, 10385.983741776534, 10784.083352914933, 11272.54909803615, 11491.195616306946, 12210.511579536718, 726.6439444672072, 758.4463341760546, 788.8264581792112, 841.3521588376916, 850.8085680721788, 4919.179244019726, 5170.353705786777, 5212.296339994128, 5216.895662086737, 5481.477780830831, 113330.0, 114653.4267328658, 115655.42172289085, 118836.0, 119599.40200298943, 103867.48066259772, 106440.0, 106906.8650625787, 107062.46468767607, 107231.0, 54211.45788542143, 70190.64904675545, 87185.56407217923, 88388.0, 88799.0, 10317.535710892878, 10388.896111038794, 10584.96652434734, 10661.0, 10727.0, 53315.53449025801, 53674.0, 54282.7285863584, 54330.0, 55431.722841385534, 71946.64026679833, 78833.05834708357, 82267.58866205631, 83838.58080709557, 84340.0, 13442.529511467052, 13705.588832334646, 13976.860231397555, 14413.495527656481, 14469.204193769443, 13641.795373069213, 16901.830981690026, 16929.74605380945, 17024.23390947435, 17123.486295411156, 13421.32893355348, 13609.523666672016, 15662.608434789221, 15801.20993950321, 15975.0, 13394.129381590166, 13514.0, 15147.54557363281, 15395.307211175748, 15517.30172142279, 14421.134731916118, 15573.532794016197, 15736.370545178523, 15851.365945362057, 16325.102119383548, 11885.524579016743, 12246.142770006014, 12357.814632780695, 12554.623361299176, 12686.619401417973, 12463.314517701614, 12625.747485050064, 12777.680557986125, 12815.935920320711, 12843.743125137631], "value": 8294.241987634075, "unit": "score"}], "name": "dromaeo_css", "value": 7627.400050336794, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 110.0, 117.0, 109.0, 108.0, 111.0, 109.0, 109.0, 109.0, 110.0], "value": 110.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 115.0, 141.0, 169.0, 116.0, 149.0, 143.0, 116.0, 167.0, 115.0], "value": 137.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 160.0, 160.0, 163.0, 161.0, 161.0, 161.0, 161.0, 161.0], "value": 160.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 75.0, 178.0, 76.0, 75.0, 74.0, 77.0, 161.0, 77.0], "value": 94.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 157.0, 83.0, 83.0, 83.0, 134.0, 83.0, 82.0, 147.0], "value": 101.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 117.0, 118.0, 116.0, 117.0, 117.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 146.0, 146.0, 147.0, 145.0, 145.0, 146.0, 146.0, 146.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 50.0, 50.0, 51.0, 49.0, 49.0, 49.0, 50.0, 49.0], "value": 49.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 60.0, 60.0, 68.0, 59.0, 59.0, 59.0, 60.0, 59.0], "value": 60.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 73.0, 72.0, 77.0, 73.0, 73.0, 72.0, 72.0, 73.0], "value": 73.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 123.0, 116.0, 118.0, 122.0, 129.0, 116.0, 117.0, 186.0, 117.0], "value": 126.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 153.0, 150.0, 149.0, 151.0, 158.0, 157.0, 156.0, 150.0, 149.0], "value": 152.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 63.0, 68.0, 61.0, 60.0, 65.0, 61.0, 59.0, 60.0], "value": 62.2, "unit": "score"}], "name": "kraken", "value": 1486.6, "alertThreshold": 2.0}]} 04:03:42 INFO - Return code: 0 04:03:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:03:42 INFO - # TBPL SUCCESS # 04:03:42 INFO - Running post-action listener: _resource_record_post_action 04:03:42 INFO - [mozharness: 2016-09-11 11:03:42.220807Z] Finished run-tests step (success) 04:03:42 INFO - Running post-run listener: _resource_record_post_run 04:03:42 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 4096; Write bytes: 329596928; Read time: 20; Write time: 211572 04:03:42 INFO - TinderboxPrint: CPU usage
13.0% 04:03:42 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 20 04:03:42 INFO - TinderboxPrint: I/O write bytes / time
329,596,928 / 211,572 04:03:42 INFO - TinderboxPrint: CPU idle
8,255.9 (87.0%) 04:03:42 INFO - TinderboxPrint: CPU user
1,189.1 (12.5%) 04:03:42 INFO - TinderboxPrint: Swap in / out
0 / 0 04:03:42 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 78393344; Read time: 0; Write time: 37320 04:03:42 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 0; Write bytes: 250707968; Read time: 0; Write time: 174188 04:03:42 INFO - Running post-run listener: _upload_blobber_files 04:03:42 INFO - Blob upload gear active. 04:03:42 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:03:42 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 04:03:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:03:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:03:43 INFO - (blobuploader) - INFO - Open directory for files ... 04:03:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:03:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:03:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:03:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:03:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:03:47 INFO - (blobuploader) - INFO - Done attempting. 04:03:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:03:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:03:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:03:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:03:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:03:48 INFO - (blobuploader) - INFO - Done attempting. 04:03:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:03:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:03:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:03:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:03:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:03:49 INFO - (blobuploader) - INFO - Done attempting. 04:03:49 INFO - (blobuploader) - INFO - Iteration through files over. 04:03:49 INFO - Return code: 0 04:03:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:03:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:03:49 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/02005c7ba8d5ba34f4a79d6f718feec3b9112d7f083169d7e2fd41abaf6ce8c6e912e8c17ab60210ea7be1cbc2aa6943a3c619cfb4849e3901a48d11d4d31cf8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cb7aae7ff90602055cfb6c4893d7aa31b4fb21be34dff09116e7ba82cade5d586247f13688e04432f38488c3f683eadba743baf05eff4ba41ee6ef874b8eb413", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d0b2dd26ef23ea35c6c5be560f36cf370054ef7faf12bd29d7c2a602b488e5862f0d00f71b404a9484e00c2916a832d56f539cdaf4a30f8f99649ae0580c4e8a"} 04:03:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:03:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:03:49 INFO - Contents: 04:03:49 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/02005c7ba8d5ba34f4a79d6f718feec3b9112d7f083169d7e2fd41abaf6ce8c6e912e8c17ab60210ea7be1cbc2aa6943a3c619cfb4849e3901a48d11d4d31cf8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cb7aae7ff90602055cfb6c4893d7aa31b4fb21be34dff09116e7ba82cade5d586247f13688e04432f38488c3f683eadba743baf05eff4ba41ee6ef874b8eb413", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d0b2dd26ef23ea35c6c5be560f36cf370054ef7faf12bd29d7c2a602b488e5862f0d00f71b404a9484e00c2916a832d56f539cdaf4a30f8f99649ae0580c4e8a"} 04:03:49 INFO - Running post-run listener: copy_logs_to_upload_dir 04:03:49 INFO - Copying logs to upload dir... 04:03:49 INFO - mkdir: /builds/slave/test/build/upload/logs 04:03:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1677.416269 ========= master_lag: 45.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 42 secs) (at 2016-09-11 04:04:32.371929) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-11 04:04:32.375106) ========= 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=3a3620fb28aea1604ab609ef000001d2-1473590150.41679-1659892745 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/02005c7ba8d5ba34f4a79d6f718feec3b9112d7f083169d7e2fd41abaf6ce8c6e912e8c17ab60210ea7be1cbc2aa6943a3c619cfb4849e3901a48d11d4d31cf8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cb7aae7ff90602055cfb6c4893d7aa31b4fb21be34dff09116e7ba82cade5d586247f13688e04432f38488c3f683eadba743baf05eff4ba41ee6ef874b8eb413", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d0b2dd26ef23ea35c6c5be560f36cf370054ef7faf12bd29d7c2a602b488e5862f0d00f71b404a9484e00c2916a832d56f539cdaf4a30f8f99649ae0580c4e8a"} build_url:https://queue.taskcluster.net/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011488 build_url: 'https://queue.taskcluster.net/v1/task/VfN27DBgTDClEKi7gifl_w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/02005c7ba8d5ba34f4a79d6f718feec3b9112d7f083169d7e2fd41abaf6ce8c6e912e8c17ab60210ea7be1cbc2aa6943a3c619cfb4849e3901a48d11d4d31cf8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cb7aae7ff90602055cfb6c4893d7aa31b4fb21be34dff09116e7ba82cade5d586247f13688e04432f38488c3f683eadba743baf05eff4ba41ee6ef874b8eb413", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d0b2dd26ef23ea35c6c5be560f36cf370054ef7faf12bd29d7c2a602b488e5862f0d00f71b404a9484e00c2916a832d56f539cdaf4a30f8f99649ae0580c4e8a"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-11 04:04:32.401353) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-11 04:04:32.401743) ========= 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=3a3620fb28aea1604ab609ef000001d2-1473590150.41679-1659892745 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004456 ========= master_lag: 0.15 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-11 04:04:32.559974) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-11 04:04:32.560331) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-11 04:04:32.560671) ========= ========= Total master_lag: 45.38 =========