builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-069 starttime: 1473224482.4 results: success (0) buildid: 20160906191115 builduid: 8705dd46464e4b83b31702b5d10c5173 revision: 3a0fd1bb116a3b79b6f33462a04144818b550ee9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-06 22:01:22.397411) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-06 22:01:22.398241) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-06 22:01:22.398558) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-06 22:01:22.568322) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 22:01:22.568620) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1473223835.34884-1500199462 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-06 22:01:22-- 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% 50.7M=0s 2016-09-06 22:01:22 (50.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.168429 ========= master_lag: 0.19 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 22:01:22.927091) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 22:01:22.927432) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1473223835.34884-1500199462 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.133326 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 22:01:23.081702) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 22:01:23.082266) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 3a0fd1bb116a3b79b6f33462a04144818b550ee9 --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 3a0fd1bb116a3b79b6f33462a04144818b550ee9 --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=05b19d94dfa9f3ce7b6e62cc000001f7-1473223835.34884-1500199462 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-06 22:01:23,154 truncating revision to first 12 chars 2016-09-06 22:01:23,154 Setting DEBUG logging. 2016-09-06 22:01:23,154 attempt 1/10 2016-09-06 22:01:23,154 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/3a0fd1bb116a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-06 22:01:23,466 unpacking tar archive at: mozilla-aurora-3a0fd1bb116a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.659283 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 22:01:23.756423) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-06 22:01:23.756904) ========= script_repo_revision: 3a0fd1bb116a3b79b6f33462a04144818b550ee9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-06 22:01:23.757529) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-06 22:01:23.757922) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-06 22:01:23.770201) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 6 secs) (at 2016-09-06 22:01:23.770870) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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=05b19d94dfa9f3ce7b6e62cc000001f7-1473223835.34884-1500199462 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:01:24 INFO - MultiFileLogger online at 20160906 22:01:24 in /builds/slave/test 22:01:24 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 22:01:24 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:01:24 INFO - {'append_to_log': False, 22:01:24 INFO - 'base_work_dir': '/builds/slave/test', 22:01:24 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 22:01:24 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:01:24 INFO - 'branch': 'Mozilla-Aurora', 22:01:24 INFO - 'buildbot_json_path': 'buildprops.json', 22:01:24 INFO - 'config_files': ('talos/linux_config.py',), 22:01:24 INFO - 'default_actions': ('clobber', 22:01:24 INFO - 'read-buildbot-config', 22:01:24 INFO - 'download-and-extract', 22:01:24 INFO - 'populate-webroot', 22:01:24 INFO - 'create-virtualenv', 22:01:24 INFO - 'install', 22:01:24 INFO - 'run-tests'), 22:01:24 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:01:24 INFO - 'download_minidump_stackwalk': True, 22:01:24 INFO - 'download_symbols': 'ondemand', 22:01:24 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:01:24 INFO - 'tooltool.py': '/tools/tooltool.py', 22:01:24 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:01:24 INFO - '/tools/misc-python/virtualenv.py')}, 22:01:24 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:01:24 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:01:24 INFO - 'installer_path': 'installer.exe', 22:01:24 INFO - 'log_level': 'info', 22:01:24 INFO - 'log_name': 'talos', 22:01:24 INFO - 'log_to_console': True, 22:01:24 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:01:24 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:01:24 INFO - 'opt_config_files': (), 22:01:24 INFO - 'pip_index': False, 22:01:24 INFO - 'sps_profile': False, 22:01:24 INFO - 'sps_profile_interval': 0, 22:01:24 INFO - 'suite': 'dromaeojs-e10s', 22:01:24 INFO - 'system_bits': '32', 22:01:24 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:01:24 INFO - 'title': 'talos-linux64-ix-069', 22:01:24 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:01:24 INFO - 'use_talos_json': True, 22:01:24 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:01:24 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:01:24 INFO - 'work_dir': 'build'} 22:01:24 INFO - [mozharness: 2016-09-07 05:01:24.058312Z] Running clobber step. 22:01:24 INFO - Running pre-action listener: _resource_record_pre_action 22:01:24 INFO - Running main action method: clobber 22:01:24 INFO - rmtree: /builds/slave/test/build 22:01:24 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:01:29 INFO - Running post-action listener: _resource_record_post_action 22:01:29 INFO - [mozharness: 2016-09-07 05:01:29.322781Z] Finished clobber step (success) 22:01:29 INFO - [mozharness: 2016-09-07 05:01:29.322880Z] Running read-buildbot-config step. 22:01:29 INFO - Running pre-action listener: _resource_record_pre_action 22:01:29 INFO - Running main action method: read_buildbot_config 22:01:29 INFO - Using buildbot properties: 22:01:29 INFO - { 22:01:29 INFO - "project": "", 22:01:29 INFO - "product": "firefox", 22:01:29 INFO - "script_repo_revision": "production", 22:01:29 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s", 22:01:29 INFO - "repository": "", 22:01:29 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs-e10s", 22:01:29 INFO - "buildid": "20160906191115", 22:01:29 INFO - "pgo_build": "True", 22:01:29 INFO - "basedir": "/builds/slave/test", 22:01:29 INFO - "buildnumber": 544, 22:01:29 INFO - "slavename": "talos-linux64-ix-069", 22:01:29 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 22:01:29 INFO - "platform": "ubuntu64_hw", 22:01:29 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 22:01:29 INFO - "branch": "mozilla-aurora", 22:01:29 INFO - "repo_path": "releases/mozilla-aurora", 22:01:29 INFO - "revision": "3a0fd1bb116a3b79b6f33462a04144818b550ee9", 22:01:29 INFO - "stage_platform": "linux64-pgo", 22:01:29 INFO - "builduid": "8705dd46464e4b83b31702b5d10c5173", 22:01:29 INFO - "slavebuilddir": "test" 22:01:29 INFO - } 22:01:29 INFO - Found installer url https://queue.taskcluster.net/v1/task/aySZQqZqQiqyUKJ8L1hjAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 22:01:29 INFO - Running post-action listener: _resource_record_post_action 22:01:29 INFO - [mozharness: 2016-09-07 05:01:29.325290Z] Finished read-buildbot-config step (success) 22:01:29 INFO - [mozharness: 2016-09-07 05:01:29.325379Z] Running download-and-extract step. 22:01:29 INFO - Running pre-action listener: _resource_record_pre_action 22:01:29 INFO - Running main action method: download_and_extract 22:01:29 INFO - mkdir: /builds/slave/test/build/tests 22:01:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:29 INFO - https://queue.taskcluster.net/v1/task/aySZQqZqQiqyUKJ8L1hjAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 22:01:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aySZQqZqQiqyUKJ8L1hjAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 22:01:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aySZQqZqQiqyUKJ8L1hjAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 22:01:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aySZQqZqQiqyUKJ8L1hjAw/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 22:01:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aySZQqZqQiqyUKJ8L1hjAw/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 22:01:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aySZQqZqQiqyUKJ8L1hjAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 22:01:29 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 22:01:29 INFO - retry: Failed, sleeping 30 seconds before retrying 22:01:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aySZQqZqQiqyUKJ8L1hjAw/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 22:01:59 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aySZQqZqQiqyUKJ8L1hjAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 22:01:59 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 22:01:59 INFO - retry: Failed, sleeping 60 seconds before retrying 22:02:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aySZQqZqQiqyUKJ8L1hjAw/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 22:02:59 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aySZQqZqQiqyUKJ8L1hjAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 22:02:59 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 22:02:59 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aySZQqZqQiqyUKJ8L1hjAw/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! 22:02:59 INFO - Caught exception: HTTP Error 404: Not Found 22:02:59 INFO - Caught exception: HTTP Error 404: Not Found 22:02:59 INFO - Caught exception: HTTP Error 404: Not Found 22:02:59 INFO - trying https://queue.taskcluster.net/v1/task/aySZQqZqQiqyUKJ8L1hjAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 22:02:59 INFO - Downloading https://queue.taskcluster.net/v1/task/aySZQqZqQiqyUKJ8L1hjAw/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 22:02:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aySZQqZqQiqyUKJ8L1hjAw/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 22:03:00 INFO - Downloaded 1361 bytes. 22:03:00 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 22:03:00 INFO - Using the following test package requirements: 22:03:00 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 22:03:00 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 22:03:00 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 22:03:00 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 22:03:00 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 22:03:00 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 22:03:00 INFO - u'jsshell-linux-x86_64.zip'], 22:03:00 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 22:03:00 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 22:03:00 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 22:03:00 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 22:03:00 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 22:03:00 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 22:03:00 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 22:03:00 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 22:03:00 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 22:03:00 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 22:03:00 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 22:03:00 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:03:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:03:00 INFO - https://queue.taskcluster.net/v1/task/aySZQqZqQiqyUKJ8L1hjAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:03:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aySZQqZqQiqyUKJ8L1hjAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 22:03:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aySZQqZqQiqyUKJ8L1hjAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 22:03:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aySZQqZqQiqyUKJ8L1hjAw/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 22:03:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aySZQqZqQiqyUKJ8L1hjAw/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 22:03:00 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aySZQqZqQiqyUKJ8L1hjAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 22:03:00 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 22:03:00 INFO - retry: Failed, sleeping 30 seconds before retrying 22:03:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aySZQqZqQiqyUKJ8L1hjAw/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 22:03:30 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aySZQqZqQiqyUKJ8L1hjAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 22:03:30 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 22:03:30 INFO - retry: Failed, sleeping 60 seconds before retrying 22:04:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aySZQqZqQiqyUKJ8L1hjAw/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 22:04:30 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aySZQqZqQiqyUKJ8L1hjAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 22:04:30 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 22:04:30 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aySZQqZqQiqyUKJ8L1hjAw/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! 22:04:30 INFO - Caught exception: HTTP Error 404: Not Found 22:04:30 INFO - Caught exception: HTTP Error 404: Not Found 22:04:30 INFO - Caught exception: HTTP Error 404: Not Found 22:04:30 INFO - trying https://queue.taskcluster.net/v1/task/aySZQqZqQiqyUKJ8L1hjAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 22:04:30 INFO - Downloading https://queue.taskcluster.net/v1/task/aySZQqZqQiqyUKJ8L1hjAw/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 22:04:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aySZQqZqQiqyUKJ8L1hjAw/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 22:04:30 INFO - Downloaded 21838311 bytes. 22:04:30 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'] 22:04:30 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 22:04:32 INFO - Return code: 0 22:04:32 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 22:04:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:04:32 INFO - https://queue.taskcluster.net/v1/task/aySZQqZqQiqyUKJ8L1hjAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:04:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aySZQqZqQiqyUKJ8L1hjAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 22:04:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aySZQqZqQiqyUKJ8L1hjAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 22:04:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aySZQqZqQiqyUKJ8L1hjAw/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 22:04:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aySZQqZqQiqyUKJ8L1hjAw/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 22:04:32 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aySZQqZqQiqyUKJ8L1hjAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 22:04:32 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 22:04:32 INFO - retry: Failed, sleeping 30 seconds before retrying 22:05:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aySZQqZqQiqyUKJ8L1hjAw/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 22:05:02 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aySZQqZqQiqyUKJ8L1hjAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 22:05:02 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 22:05:02 INFO - retry: Failed, sleeping 60 seconds before retrying 22:06:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aySZQqZqQiqyUKJ8L1hjAw/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 22:06:02 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aySZQqZqQiqyUKJ8L1hjAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 22:06:02 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 22:06:02 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aySZQqZqQiqyUKJ8L1hjAw/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! 22:06:02 INFO - Caught exception: HTTP Error 404: Not Found 22:06:02 INFO - Caught exception: HTTP Error 404: Not Found 22:06:02 INFO - Caught exception: HTTP Error 404: Not Found 22:06:02 INFO - trying https://queue.taskcluster.net/v1/task/aySZQqZqQiqyUKJ8L1hjAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 22:06:02 INFO - Downloading https://queue.taskcluster.net/v1/task/aySZQqZqQiqyUKJ8L1hjAw/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 22:06:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aySZQqZqQiqyUKJ8L1hjAw/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 22:06:03 INFO - Downloaded 21838311 bytes. 22:06:03 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'] 22:06:03 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 22:06:04 INFO - Return code: 0 22:06:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:04 INFO - https://queue.taskcluster.net/v1/task/aySZQqZqQiqyUKJ8L1hjAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:06:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aySZQqZqQiqyUKJ8L1hjAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 22:06:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aySZQqZqQiqyUKJ8L1hjAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 22:06:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aySZQqZqQiqyUKJ8L1hjAw/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 22:06:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aySZQqZqQiqyUKJ8L1hjAw/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 22:06:04 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aySZQqZqQiqyUKJ8L1hjAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 22:06:04 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 22:06:04 INFO - retry: Failed, sleeping 30 seconds before retrying 22:06:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aySZQqZqQiqyUKJ8L1hjAw/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 22:06:34 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aySZQqZqQiqyUKJ8L1hjAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 22:06:34 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 22:06:34 INFO - retry: Failed, sleeping 60 seconds before retrying 22:07:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aySZQqZqQiqyUKJ8L1hjAw/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 22:07:34 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aySZQqZqQiqyUKJ8L1hjAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 22:07:34 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 22:07:34 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aySZQqZqQiqyUKJ8L1hjAw/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! 22:07:34 INFO - Caught exception: HTTP Error 404: Not Found 22:07:34 INFO - Caught exception: HTTP Error 404: Not Found 22:07:34 INFO - Caught exception: HTTP Error 404: Not Found 22:07:34 INFO - trying https://queue.taskcluster.net/v1/task/aySZQqZqQiqyUKJ8L1hjAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 22:07:34 INFO - Downloading https://queue.taskcluster.net/v1/task/aySZQqZqQiqyUKJ8L1hjAw/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 22:07:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aySZQqZqQiqyUKJ8L1hjAw/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 22:07:35 INFO - Downloaded 14413740 bytes. 22:07:35 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'] 22:07:35 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 22:07:35 INFO - Return code: 0 22:07:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:07:35 INFO - https://queue.taskcluster.net/v1/task/aySZQqZqQiqyUKJ8L1hjAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:07:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aySZQqZqQiqyUKJ8L1hjAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 22:07:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aySZQqZqQiqyUKJ8L1hjAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 22:07:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aySZQqZqQiqyUKJ8L1hjAw/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 22:07:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aySZQqZqQiqyUKJ8L1hjAw/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 22:07:35 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aySZQqZqQiqyUKJ8L1hjAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 22:07:35 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 22:07:35 INFO - retry: Failed, sleeping 30 seconds before retrying 22:08:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aySZQqZqQiqyUKJ8L1hjAw/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 22:08:05 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aySZQqZqQiqyUKJ8L1hjAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 22:08:05 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 22:08:05 INFO - retry: Failed, sleeping 60 seconds before retrying 22:09:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aySZQqZqQiqyUKJ8L1hjAw/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 22:09:05 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aySZQqZqQiqyUKJ8L1hjAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 22:09:05 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 22:09:05 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aySZQqZqQiqyUKJ8L1hjAw/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! 22:09:05 INFO - Caught exception: HTTP Error 404: Not Found 22:09:05 INFO - Caught exception: HTTP Error 404: Not Found 22:09:05 INFO - Caught exception: HTTP Error 404: Not Found 22:09:05 INFO - trying https://queue.taskcluster.net/v1/task/aySZQqZqQiqyUKJ8L1hjAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 22:09:05 INFO - Downloading https://queue.taskcluster.net/v1/task/aySZQqZqQiqyUKJ8L1hjAw/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 22:09:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aySZQqZqQiqyUKJ8L1hjAw/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 22:09:06 INFO - Downloaded 63893845 bytes. 22:09:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aySZQqZqQiqyUKJ8L1hjAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 22:09:06 INFO - mkdir: /builds/slave/test/properties 22:09:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:09:06 INFO - Writing to file /builds/slave/test/properties/build_url 22:09:06 INFO - Contents: 22:09:06 INFO - build_url:https://queue.taskcluster.net/v1/task/aySZQqZqQiqyUKJ8L1hjAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 22:09:07 INFO - Running post-action listener: _resource_record_post_action 22:09:07 INFO - Running post-action listener: set_extra_try_arguments 22:09:07 INFO - [mozharness: 2016-09-07 05:09:07.557847Z] Finished download-and-extract step (success) 22:09:07 INFO - [mozharness: 2016-09-07 05:09:07.558080Z] Running populate-webroot step. 22:09:07 INFO - Running pre-action listener: _resource_record_pre_action 22:09:07 INFO - Running main action method: populate_webroot 22:09:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:09:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:09:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:09:07 INFO - '--tppagecycles', 22:09:07 INFO - '7'], 22:09:07 INFO - 'tests': ['tsvgm']}}, 22:09:07 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:09:07 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:09:07 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:09:07 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:09:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:09:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:09:07 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:09:07 INFO - 'talos_options': ['--disable-e10s'], 22:09:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:09:07 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:09:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:09:07 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:09:07 INFO - 'talos_options': ['--disable-e10s'], 22:09:07 INFO - 'tests': ['damp', 'tps']}, 22:09:07 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:09:07 INFO - 'tests': ['damp', 'tps']}, 22:09:07 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:09:07 INFO - 'tests': ['dromaeo_dom']}, 22:09:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:09:07 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:09:07 INFO - 'tests': ['basic_compositor_video']}, 22:09:07 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:09:07 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:09:07 INFO - 'tests': ['a11yr', 22:09:07 INFO - 'ts_paint', 22:09:07 INFO - 'tpaint', 22:09:07 INFO - 'sessionrestore', 22:09:07 INFO - 'sessionrestore_no_auto_restore', 22:09:07 INFO - 'tabpaint']}, 22:09:07 INFO - 'other-e10s': {'tests': ['a11yr', 22:09:07 INFO - 'ts_paint', 22:09:07 INFO - 'tpaint', 22:09:07 INFO - 'sessionrestore', 22:09:07 INFO - 'sessionrestore_no_auto_restore', 22:09:07 INFO - 'tabpaint']}, 22:09:07 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:09:07 INFO - 'ts_paint', 22:09:07 INFO - 'tpaint', 22:09:07 INFO - 'sessionrestore', 22:09:07 INFO - 'sessionrestore_no_auto_restore', 22:09:07 INFO - 'tabpaint']}, 22:09:07 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:09:07 INFO - 'ts_paint', 22:09:07 INFO - 'tpaint', 22:09:07 INFO - 'sessionrestore', 22:09:07 INFO - 'sessionrestore_no_auto_restore', 22:09:07 INFO - 'tabpaint']}, 22:09:07 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:09:07 INFO - 'tests': ['a11yr', 22:09:07 INFO - 'ts_paint', 22:09:07 INFO - 'tpaint', 22:09:07 INFO - 'sessionrestore', 22:09:07 INFO - 'sessionrestore_no_auto_restore', 22:09:07 INFO - 'tabpaint']}, 22:09:07 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:09:07 INFO - 'tests': ['a11yr', 22:09:07 INFO - 'ts_paint', 22:09:07 INFO - 'tpaint', 22:09:07 INFO - 'sessionrestore', 22:09:07 INFO - 'sessionrestore_no_auto_restore', 22:09:07 INFO - 'tabpaint']}, 22:09:07 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:09:07 INFO - 'tests': ['tsvgx', 22:09:07 INFO - 'tsvgr_opacity', 22:09:07 INFO - 'tart', 22:09:07 INFO - 'tscrollx', 22:09:07 INFO - 'cart']}, 22:09:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:09:07 INFO - 'tsvgr_opacity', 22:09:07 INFO - 'tart', 22:09:07 INFO - 'tscrollx', 22:09:07 INFO - 'cart']}, 22:09:07 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:09:07 INFO - 'talos_options': ['--disable-e10s'], 22:09:07 INFO - 'tests': ['tp5o']}, 22:09:07 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:09:07 INFO - 'tests': ['tp5o']}, 22:09:07 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:09:07 INFO - 'talos_options': ['--disable-e10s', 22:09:07 INFO - '--xperf_path', 22:09:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:09:07 INFO - 'tests': ['tp5n']}, 22:09:07 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:09:07 INFO - 'talos_options': ['--xperf_path', 22:09:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:09:07 INFO - 'tests': ['tp5n']}}, 22:09:07 INFO - 'talos.zip': {'path': '', 22:09:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:09:07 INFO - Running post-action listener: _resource_record_post_action 22:09:07 INFO - [mozharness: 2016-09-07 05:09:07.578807Z] Finished populate-webroot step (success) 22:09:07 INFO - [mozharness: 2016-09-07 05:09:07.578895Z] Running create-virtualenv step. 22:09:07 INFO - Running pre-action listener: _resource_record_pre_action 22:09:07 INFO - Running main action method: create_virtualenv 22:09:07 INFO - Creating virtualenv /builds/slave/test/build/venv 22:09:07 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 22:09:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:09:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:09:07 INFO - Using real prefix '/usr' 22:09:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:09:08 INFO - Installing distribute.............................................................................................................................................................................................done. 22:09:10 INFO - Installing pip.................done. 22:09:10 INFO - Return code: 0 22:09:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:09:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:09:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:09:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:10 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 0x100d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11708a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x123c400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12cb570>, '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 0x12cc470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12cc900>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1473223835.34884-1500199462', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:09:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 22:09:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:09:10 INFO - Using env: {'DISPLAY': ':0', 22:09:10 INFO - 'HOME': '/home/cltbld', 22:09:10 INFO - 'LANG': 'en_US.UTF-8', 22:09:10 INFO - 'LANGUAGE': 'en_US:en', 22:09:10 INFO - 'LOGNAME': 'cltbld', 22:09:10 INFO - 'MAIL': '/var/mail/cltbld', 22:09:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:10 INFO - 'MOZ_NO_REMOTE': '1', 22:09:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:09:10 INFO - 'NO_EM_RESTART': '1', 22:09:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:09:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:10 INFO - 'PWD': '/builds/slave/test', 22:09:10 INFO - 'SHELL': '/bin/bash', 22:09:10 INFO - 'SHLVL': '1', 22:09:10 INFO - 'TERM': 'linux', 22:09:10 INFO - 'TMOUT': '86400', 22:09:10 INFO - 'USER': 'cltbld', 22:09:10 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1473223835.34884-1500199462', 22:09:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:10 INFO - '_': '/tools/buildbot/bin/python'} 22:09:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:10 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 22:09:10 INFO - Cleaning up... 22:09:10 INFO - Return code: 0 22:09:10 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:09:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:09:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:09:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:10 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 0x100d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11708a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x123c400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12cb570>, '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 0x12cc470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12cc900>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1473223835.34884-1500199462', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:09:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 22:09:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 22:09:10 INFO - Using env: {'DISPLAY': ':0', 22:09:10 INFO - 'HOME': '/home/cltbld', 22:09:10 INFO - 'LANG': 'en_US.UTF-8', 22:09:10 INFO - 'LANGUAGE': 'en_US:en', 22:09:10 INFO - 'LOGNAME': 'cltbld', 22:09:10 INFO - 'MAIL': '/var/mail/cltbld', 22:09:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:10 INFO - 'MOZ_NO_REMOTE': '1', 22:09:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:09:10 INFO - 'NO_EM_RESTART': '1', 22:09:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:09:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:10 INFO - 'PWD': '/builds/slave/test', 22:09:10 INFO - 'SHELL': '/bin/bash', 22:09:10 INFO - 'SHLVL': '1', 22:09:10 INFO - 'TERM': 'linux', 22:09:10 INFO - 'TMOUT': '86400', 22:09:10 INFO - 'USER': 'cltbld', 22:09:10 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1473223835.34884-1500199462', 22:09:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:10 INFO - '_': '/tools/buildbot/bin/python'} 22:09:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:10 INFO - Downloading/unpacking psutil>=3.1.1 22:09:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:09:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:09:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:09:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:09:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:09:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:09:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:09:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:09:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:09:12 INFO - Installing collected packages: psutil 22:09:12 INFO - Running setup.py install for psutil 22:09:13 INFO - building 'psutil._psutil_linux' extension 22:09:13 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 22:09:13 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 22:09:13 INFO - building 'psutil._psutil_posix' extension 22:09:13 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 22:09:13 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 22:09:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:09:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:09:14 INFO - Successfully installed psutil 22:09:14 INFO - Cleaning up... 22:09:14 INFO - Return code: 0 22:09:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:09:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:09:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:09:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:14 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 0x100d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11708a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x123c400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12cb570>, '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 0x12cc470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12cc900>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1473223835.34884-1500199462', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:09:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 22:09:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 22:09:14 INFO - Using env: {'DISPLAY': ':0', 22:09:14 INFO - 'HOME': '/home/cltbld', 22:09:14 INFO - 'LANG': 'en_US.UTF-8', 22:09:14 INFO - 'LANGUAGE': 'en_US:en', 22:09:14 INFO - 'LOGNAME': 'cltbld', 22:09:14 INFO - 'MAIL': '/var/mail/cltbld', 22:09:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:14 INFO - 'MOZ_NO_REMOTE': '1', 22:09:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:09:14 INFO - 'NO_EM_RESTART': '1', 22:09:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:09:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:14 INFO - 'PWD': '/builds/slave/test', 22:09:14 INFO - 'SHELL': '/bin/bash', 22:09:14 INFO - 'SHLVL': '1', 22:09:14 INFO - 'TERM': 'linux', 22:09:14 INFO - 'TMOUT': '86400', 22:09:14 INFO - 'USER': 'cltbld', 22:09:14 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1473223835.34884-1500199462', 22:09:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:14 INFO - '_': '/tools/buildbot/bin/python'} 22:09:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:09:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:09:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:09:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:09:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:09:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:09:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:09:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:09:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:09:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:09:16 INFO - Installing collected packages: mozsystemmonitor 22:09:16 INFO - Running setup.py install for mozsystemmonitor 22:09:16 INFO - Successfully installed mozsystemmonitor 22:09:16 INFO - Cleaning up... 22:09:16 INFO - Return code: 0 22:09:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:09:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:09:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:09:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:16 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 0x100d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11708a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x123c400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12cb570>, '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 0x12cc470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12cc900>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1473223835.34884-1500199462', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:09:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 22:09:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 22:09:16 INFO - Using env: {'DISPLAY': ':0', 22:09:16 INFO - 'HOME': '/home/cltbld', 22:09:16 INFO - 'LANG': 'en_US.UTF-8', 22:09:16 INFO - 'LANGUAGE': 'en_US:en', 22:09:16 INFO - 'LOGNAME': 'cltbld', 22:09:16 INFO - 'MAIL': '/var/mail/cltbld', 22:09:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:16 INFO - 'MOZ_NO_REMOTE': '1', 22:09:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:09:16 INFO - 'NO_EM_RESTART': '1', 22:09:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:09:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:16 INFO - 'PWD': '/builds/slave/test', 22:09:16 INFO - 'SHELL': '/bin/bash', 22:09:16 INFO - 'SHLVL': '1', 22:09:16 INFO - 'TERM': 'linux', 22:09:16 INFO - 'TMOUT': '86400', 22:09:16 INFO - 'USER': 'cltbld', 22:09:16 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1473223835.34884-1500199462', 22:09:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:16 INFO - '_': '/tools/buildbot/bin/python'} 22:09:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:16 INFO - Downloading/unpacking jsonschema==2.5.1 22:09:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:09:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:09:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:09:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:09:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:09:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:09:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:09:18 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:09:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:09:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:09:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:09:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:09:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:09:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:09:18 INFO - Downloading functools32-3.2.3-2.tar.gz 22:09:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:09:18 INFO - warning: no files found matching '*.txt' 22:09:18 INFO - no previously-included directories found matching 'build' 22:09:18 INFO - no previously-included directories found matching 'dist' 22:09:18 INFO - no previously-included directories found matching '.git*' 22:09:18 INFO - Installing collected packages: jsonschema, functools32 22:09:18 INFO - Running setup.py install for functools32 22:09:18 INFO - warning: no files found matching '*.txt' 22:09:18 INFO - no previously-included directories found matching 'build' 22:09:18 INFO - no previously-included directories found matching 'dist' 22:09:18 INFO - no previously-included directories found matching '.git*' 22:09:18 INFO - Successfully installed jsonschema functools32 22:09:18 INFO - Cleaning up... 22:09:19 INFO - Return code: 0 22:09:19 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:09:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:09:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:09:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11708a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x123c400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12cb570>, '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 0x12cc470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12cc900>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1473223835.34884-1500199462', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:09:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 22:09:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 22:09:19 INFO - Using env: {'DISPLAY': ':0', 22:09:19 INFO - 'HOME': '/home/cltbld', 22:09:19 INFO - 'LANG': 'en_US.UTF-8', 22:09:19 INFO - 'LANGUAGE': 'en_US:en', 22:09:19 INFO - 'LOGNAME': 'cltbld', 22:09:19 INFO - 'MAIL': '/var/mail/cltbld', 22:09:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:19 INFO - 'MOZ_NO_REMOTE': '1', 22:09:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:09:19 INFO - 'NO_EM_RESTART': '1', 22:09:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:09:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:19 INFO - 'PWD': '/builds/slave/test', 22:09:19 INFO - 'SHELL': '/bin/bash', 22:09:19 INFO - 'SHLVL': '1', 22:09:19 INFO - 'TERM': 'linux', 22:09:19 INFO - 'TMOUT': '86400', 22:09:19 INFO - 'USER': 'cltbld', 22:09:19 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1473223835.34884-1500199462', 22:09:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:19 INFO - '_': '/tools/buildbot/bin/python'} 22:09:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:19 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:09:19 INFO - Cleaning up... 22:09:19 INFO - Return code: 0 22:09:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:09:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:09:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:09:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11708a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x123c400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12cb570>, '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 0x12cc470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12cc900>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1473223835.34884-1500199462', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:09:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:09:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 22:09:19 INFO - Using env: {'DISPLAY': ':0', 22:09:19 INFO - 'HOME': '/home/cltbld', 22:09:19 INFO - 'LANG': 'en_US.UTF-8', 22:09:19 INFO - 'LANGUAGE': 'en_US:en', 22:09:19 INFO - 'LOGNAME': 'cltbld', 22:09:19 INFO - 'MAIL': '/var/mail/cltbld', 22:09:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:19 INFO - 'MOZ_NO_REMOTE': '1', 22:09:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:09:19 INFO - 'NO_EM_RESTART': '1', 22:09:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:09:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:19 INFO - 'PWD': '/builds/slave/test', 22:09:19 INFO - 'SHELL': '/bin/bash', 22:09:19 INFO - 'SHLVL': '1', 22:09:19 INFO - 'TERM': 'linux', 22:09:19 INFO - 'TMOUT': '86400', 22:09:19 INFO - 'USER': 'cltbld', 22:09:19 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1473223835.34884-1500199462', 22:09:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:19 INFO - '_': '/tools/buildbot/bin/python'} 22:09:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:19 INFO - Downloading/unpacking blobuploader==1.2.4 22:09:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:09:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:09:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:09:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:09:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:09:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:09:21 INFO - Downloading blobuploader-1.2.4.tar.gz 22:09:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:09:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:09:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:09:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:09:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:09:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:09:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:09:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:09:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:09:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:09:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:09:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:09:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:09:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:09:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:09:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:09:22 INFO - Downloading docopt-0.6.1.tar.gz 22:09:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:09:22 INFO - Installing collected packages: blobuploader, requests, docopt 22:09:22 INFO - Running setup.py install for blobuploader 22:09:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:09:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:09:22 INFO - Running setup.py install for requests 22:09:22 INFO - Running setup.py install for docopt 22:09:22 INFO - Successfully installed blobuploader requests docopt 22:09:22 INFO - Cleaning up... 22:09:22 INFO - Return code: 0 22:09:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:09:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:09:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:09:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:22 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 0x100d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11708a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x123c400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12cb570>, '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 0x12cc470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12cc900>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1473223835.34884-1500199462', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:09:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:09:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:09:22 INFO - Using env: {'DISPLAY': ':0', 22:09:22 INFO - 'HOME': '/home/cltbld', 22:09:22 INFO - 'LANG': 'en_US.UTF-8', 22:09:22 INFO - 'LANGUAGE': 'en_US:en', 22:09:22 INFO - 'LOGNAME': 'cltbld', 22:09:22 INFO - 'MAIL': '/var/mail/cltbld', 22:09:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:22 INFO - 'MOZ_NO_REMOTE': '1', 22:09:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:09:22 INFO - 'NO_EM_RESTART': '1', 22:09:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:09:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:22 INFO - 'PWD': '/builds/slave/test', 22:09:22 INFO - 'SHELL': '/bin/bash', 22:09:22 INFO - 'SHLVL': '1', 22:09:22 INFO - 'TERM': 'linux', 22:09:22 INFO - 'TMOUT': '86400', 22:09:22 INFO - 'USER': 'cltbld', 22:09:22 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1473223835.34884-1500199462', 22:09:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:22 INFO - '_': '/tools/buildbot/bin/python'} 22:09:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:09:22 INFO - Running setup.py (path:/tmp/pip-YuI3vt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:09:22 INFO - Running setup.py (path:/tmp/pip-hYRsNG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:09:23 INFO - Running setup.py (path:/tmp/pip-32thaw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:09:23 INFO - Running setup.py (path:/tmp/pip-6S0cqN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:09:23 INFO - Running setup.py (path:/tmp/pip-J4Za4Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:09:23 INFO - Running setup.py (path:/tmp/pip-7tGon1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:09:23 INFO - Running setup.py (path:/tmp/pip-ItnfyU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:09:23 INFO - Running setup.py (path:/tmp/pip-wRG6UB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:09:23 INFO - Running setup.py (path:/tmp/pip-VRtmW2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:09:23 INFO - Running setup.py (path:/tmp/pip-azt6cV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:09:23 INFO - Running setup.py (path:/tmp/pip-4NbJ_m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:09:23 INFO - Running setup.py (path:/tmp/pip-JpAXBS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:09:23 INFO - Running setup.py (path:/tmp/pip-k8bvNw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:09:23 INFO - Running setup.py (path:/tmp/pip-0DGml4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:09:23 INFO - Running setup.py (path:/tmp/pip-B7PNaA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:09:24 INFO - Running setup.py (path:/tmp/pip-LOT2jU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:09:24 INFO - Running setup.py (path:/tmp/pip-AGnhZt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:09:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:09:24 INFO - Running setup.py install for manifestparser 22:09:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:09:24 INFO - Running setup.py install for mozcrash 22:09:24 INFO - Running setup.py install for mozdebug 22:09:24 INFO - Running setup.py install for mozdevice 22:09:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:09:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:09:24 INFO - Running setup.py install for mozfile 22:09:24 INFO - Running setup.py install for mozhttpd 22:09:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:09:24 INFO - Running setup.py install for mozinfo 22:09:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:09:24 INFO - Running setup.py install for mozInstall 22:09:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:09:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:09:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:09:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:09:24 INFO - Running setup.py install for mozleak 22:09:25 INFO - Running setup.py install for mozlog 22:09:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:09:25 INFO - Running setup.py install for moznetwork 22:09:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:09:25 INFO - Running setup.py install for mozprocess 22:09:25 INFO - Running setup.py install for mozprofile 22:09:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:09:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:09:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:09:25 INFO - Running setup.py install for mozrunner 22:09:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:09:25 INFO - Running setup.py install for mozscreenshot 22:09:25 INFO - Running setup.py install for moztest 22:09:25 INFO - Running setup.py install for mozversion 22:09:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:09:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:09:25 INFO - Cleaning up... 22:09:25 INFO - Return code: 0 22:09:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:09:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:09:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:09:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:25 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 0x100d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11708a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x123c400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12cb570>, '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 0x12cc470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12cc900>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1473223835.34884-1500199462', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:09:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:09:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:09:25 INFO - Using env: {'DISPLAY': ':0', 22:09:25 INFO - 'HOME': '/home/cltbld', 22:09:25 INFO - 'LANG': 'en_US.UTF-8', 22:09:25 INFO - 'LANGUAGE': 'en_US:en', 22:09:25 INFO - 'LOGNAME': 'cltbld', 22:09:25 INFO - 'MAIL': '/var/mail/cltbld', 22:09:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:25 INFO - 'MOZ_NO_REMOTE': '1', 22:09:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:09:25 INFO - 'NO_EM_RESTART': '1', 22:09:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:09:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:25 INFO - 'PWD': '/builds/slave/test', 22:09:25 INFO - 'SHELL': '/bin/bash', 22:09:25 INFO - 'SHLVL': '1', 22:09:25 INFO - 'TERM': 'linux', 22:09:25 INFO - 'TMOUT': '86400', 22:09:25 INFO - 'USER': 'cltbld', 22:09:25 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1473223835.34884-1500199462', 22:09:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:25 INFO - '_': '/tools/buildbot/bin/python'} 22:09:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:09:26 INFO - Running setup.py (path:/tmp/pip-InJSy9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:09:26 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 22:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:09:26 INFO - Running setup.py (path:/tmp/pip-07pdc6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:09:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:09:26 INFO - Running setup.py (path:/tmp/pip-KA2t7y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:09:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:09:26 INFO - Running setup.py (path:/tmp/pip-vfHLev-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:09:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:09:26 INFO - Running setup.py (path:/tmp/pip-VXwkuc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:09:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 22:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:09:26 INFO - Running setup.py (path:/tmp/pip-DThkkS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:09:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 22:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:09:26 INFO - Running setup.py (path:/tmp/pip-LNopcB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:09:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 22:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:09:26 INFO - Running setup.py (path:/tmp/pip-ykhN6k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:09:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 22:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:09:26 INFO - Running setup.py (path:/tmp/pip-66RTue-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:09:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 22:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:09:26 INFO - Running setup.py (path:/tmp/pip-rubyZK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:09:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:09:26 INFO - Running setup.py (path:/tmp/pip-Vgedjv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:09:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 22:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:09:26 INFO - Running setup.py (path:/tmp/pip-5wvTr5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:09:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 22:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:09:27 INFO - Running setup.py (path:/tmp/pip-HPEUbt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:09:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 22:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:09:27 INFO - Running setup.py (path:/tmp/pip-R6xake-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:09:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 22:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:09:27 INFO - Running setup.py (path:/tmp/pip-IIdQxR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:09:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 22:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:09:27 INFO - Running setup.py (path:/tmp/pip-kQVKA6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:09:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 22:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:09:27 INFO - Running setup.py (path:/tmp/pip-gwsDxG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:09:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 22:09:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:09:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:09:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:09:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:09:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:09:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:09:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:09:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:09:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:09:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:09:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:09:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:09:29 INFO - Downloading blessings-1.6.tar.gz 22:09:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:09:29 INFO - Installing collected packages: blessings 22:09:29 INFO - Running setup.py install for blessings 22:09:29 INFO - Successfully installed blessings 22:09:29 INFO - Cleaning up... 22:09:29 INFO - Return code: 0 22:09:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:09:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:09:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:09:29 INFO - Reading from file tmpfile_stdout 22:09:29 INFO - Current package versions: 22:09:29 INFO - argparse == 1.2.1 22:09:29 INFO - blessings == 1.6 22:09:29 INFO - blobuploader == 1.2.4 22:09:29 INFO - docopt == 0.6.1 22:09:29 INFO - functools32 == 3.2.3-2 22:09:29 INFO - jsonschema == 2.5.1 22:09:29 INFO - manifestparser == 1.1 22:09:29 INFO - mozInstall == 1.12 22:09:29 INFO - mozcrash == 0.17 22:09:29 INFO - mozdebug == 0.1 22:09:29 INFO - mozdevice == 0.48 22:09:29 INFO - mozfile == 1.2 22:09:29 INFO - mozhttpd == 0.7 22:09:29 INFO - mozinfo == 0.9 22:09:29 INFO - mozleak == 0.1 22:09:29 INFO - mozlog == 3.3 22:09:29 INFO - moznetwork == 0.27 22:09:29 INFO - mozprocess == 0.23 22:09:29 INFO - mozprofile == 0.28 22:09:29 INFO - mozrunner == 6.12 22:09:29 INFO - mozscreenshot == 0.1 22:09:29 INFO - mozsystemmonitor == 0.3 22:09:29 INFO - moztest == 0.7 22:09:29 INFO - mozversion == 1.4 22:09:29 INFO - psutil == 3.1.1 22:09:29 INFO - requests == 1.2.3 22:09:29 INFO - wsgiref == 0.1.2 22:09:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:09:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:09:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:09:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:29 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 0x100d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11708a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x123c400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12cb570>, '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 0x12cc470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12cc900>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1473223835.34884-1500199462', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:09:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 22:09:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:09:29 INFO - Using env: {'DISPLAY': ':0', 22:09:29 INFO - 'HOME': '/home/cltbld', 22:09:29 INFO - 'LANG': 'en_US.UTF-8', 22:09:29 INFO - 'LANGUAGE': 'en_US:en', 22:09:29 INFO - 'LOGNAME': 'cltbld', 22:09:29 INFO - 'MAIL': '/var/mail/cltbld', 22:09:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:29 INFO - 'MOZ_NO_REMOTE': '1', 22:09:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:09:29 INFO - 'NO_EM_RESTART': '1', 22:09:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:09:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:29 INFO - 'PWD': '/builds/slave/test', 22:09:29 INFO - 'SHELL': '/bin/bash', 22:09:29 INFO - 'SHLVL': '1', 22:09:29 INFO - 'TERM': 'linux', 22:09:29 INFO - 'TMOUT': '86400', 22:09:29 INFO - 'USER': 'cltbld', 22:09:29 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1473223835.34884-1500199462', 22:09:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:29 INFO - '_': '/tools/buildbot/bin/python'} 22:09:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:09:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 22:09:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 22:09:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:09:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 22:09:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 22:09:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 22:09:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 22:09:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 22:09:29 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:09:29 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:09:29 INFO - Cleaning up... 22:09:29 INFO - Return code: 0 22:09:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:09:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:09:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:09:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:29 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 0x100d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11708a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x123c400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12cb570>, '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 0x12cc470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12cc900>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1473223835.34884-1500199462', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:09:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 22:09:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:09:29 INFO - Using env: {'DISPLAY': ':0', 22:09:29 INFO - 'HOME': '/home/cltbld', 22:09:29 INFO - 'LANG': 'en_US.UTF-8', 22:09:29 INFO - 'LANGUAGE': 'en_US:en', 22:09:29 INFO - 'LOGNAME': 'cltbld', 22:09:29 INFO - 'MAIL': '/var/mail/cltbld', 22:09:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:29 INFO - 'MOZ_NO_REMOTE': '1', 22:09:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:09:29 INFO - 'NO_EM_RESTART': '1', 22:09:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:09:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:29 INFO - 'PWD': '/builds/slave/test', 22:09:29 INFO - 'SHELL': '/bin/bash', 22:09:29 INFO - 'SHLVL': '1', 22:09:29 INFO - 'TERM': 'linux', 22:09:29 INFO - 'TMOUT': '86400', 22:09:29 INFO - 'USER': 'cltbld', 22:09:29 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1473223835.34884-1500199462', 22:09:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:29 INFO - '_': '/tools/buildbot/bin/python'} 22:09:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:09:30 INFO - Cleaning up... 22:09:30 INFO - Return code: 0 22:09:30 INFO - Running post-action listener: _resource_record_post_action 22:09:30 INFO - Running post-action listener: _start_resource_monitoring 22:09:30 INFO - Starting resource monitoring. 22:09:30 INFO - [mozharness: 2016-09-07 05:09:30.229086Z] Finished create-virtualenv step (success) 22:09:30 INFO - [mozharness: 2016-09-07 05:09:30.230103Z] Running install step. 22:09:30 INFO - Running pre-action listener: _resource_record_pre_action 22:09:30 INFO - Running main action method: install 22:09:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:09:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:09:30 INFO - Reading from file tmpfile_stdout 22:09:30 INFO - Detecting whether we're running mozinstall >=1.0... 22:09:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:09:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:09:30 INFO - Reading from file tmpfile_stdout 22:09:30 INFO - Output received: 22:09:30 INFO - Usage: mozinstall [options] installer 22:09:30 INFO - Options: 22:09:30 INFO - -h, --help show this help message and exit 22:09:30 INFO - -d DEST, --destination=DEST 22:09:30 INFO - Directory to install application into. [default: 22:09:30 INFO - "/builds/slave/test"] 22:09:30 INFO - --app=APP Application being installed. [default: firefox] 22:09:30 INFO - mkdir: /builds/slave/test/build/application 22:09:30 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'] 22:09:30 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 22:09:47 INFO - Reading from file tmpfile_stdout 22:09:47 INFO - Output received: 22:09:47 INFO - /builds/slave/test/build/application/firefox/firefox 22:09:47 INFO - Running post-action listener: _resource_record_post_action 22:09:47 INFO - [mozharness: 2016-09-07 05:09:47.745117Z] Finished install step (success) 22:09:47 INFO - [mozharness: 2016-09-07 05:09:47.745392Z] Running run-tests step. 22:09:47 INFO - Running pre-action listener: _resource_record_pre_action 22:09:47 INFO - Running main action method: run_tests 22:09:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:09:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:09:47 INFO - Python 2.7.3 22:09:47 INFO - Return code: 0 22:09:47 INFO - grabbing minidump binary from tooltool 22:09:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:09:47 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 0x12cb570>, '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 0x12cc470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12cc900>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:09:47 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 22:09:47 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 22:09:47 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 22:09:47 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:09:47 INFO - Return code: 0 22:09:47 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:09:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:09:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:09:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:09:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:09:47 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-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aySZQqZqQiqyUKJ8L1hjAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-069', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 22:09:47 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-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/aySZQqZqQiqyUKJ8L1hjAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-069 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 22:09:47 INFO - Using env: {'DISPLAY': ':0', 22:09:47 INFO - 'HOME': '/home/cltbld', 22:09:47 INFO - 'LANG': 'en_US.UTF-8', 22:09:47 INFO - 'LANGUAGE': 'en_US:en', 22:09:47 INFO - 'LOGNAME': 'cltbld', 22:09:47 INFO - 'MAIL': '/var/mail/cltbld', 22:09:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:09:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:09:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:47 INFO - 'MOZ_NO_REMOTE': '1', 22:09:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:09:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:09:47 INFO - 'NO_EM_RESTART': '1', 22:09:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:09:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:47 INFO - 'PWD': '/builds/slave/test', 22:09:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:09:47 INFO - 'SHELL': '/bin/bash', 22:09:47 INFO - 'SHLVL': '1', 22:09:47 INFO - 'TERM': 'linux', 22:09:47 INFO - 'TMOUT': '86400', 22:09:47 INFO - 'USER': 'cltbld', 22:09:47 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1473223835.34884-1500199462', 22:09:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:47 INFO - '_': '/tools/buildbot/bin/python'} 22:09:47 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aySZQqZqQiqyUKJ8L1hjAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-069', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 22:09:48 INFO - mozversion application_buildid: 20160906191115 22:09:48 INFO - mozversion application_changeset: 3a0fd1bb116a3b79b6f33462a04144818b550ee9 22:09:48 INFO - mozversion application_display_name: FirefoxDeveloperEdition 22:09:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:09:48 INFO - mozversion application_name: Firefox 22:09:48 INFO - mozversion application_remotingname: firefox-dev 22:09:48 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 22:09:48 INFO - mozversion application_vendor: Mozilla 22:09:48 INFO - mozversion application_version: 50.0a2 22:09:48 INFO - mozversion platform_buildid: 20160906191115 22:09:48 INFO - mozversion platform_changeset: 3a0fd1bb116a3b79b6f33462a04144818b550ee9 22:09:48 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 22:09:48 INFO - mozversion platform_version: 50.0a2 22:09:48 INFO - using testdate: 1473224988 22:09:48 INFO - actual date: 1473224988 22:09:48 INFO - starting webserver on 'localhost:53766' 22:09:48 INFO - SUITE-START | Running 2 tests 22:09:48 INFO - TEST-START | dromaeo_css 22:09:48 INFO - operating with platform_type : linux_ 22:09:48 INFO - Initialising browser for dromaeo_css test... 22:09:48 INFO - TEST-INFO | started process 3313 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnacI03/profile http://localhost:53766/getInfo.html) 22:09:54 INFO - PROCESS | 3313 | __metrics Screen width/height:1600/1200 22:09:54 INFO - PROCESS | 3313 | colorDepth:24 22:09:54 INFO - PROCESS | 3313 | Browser inner width/height: 1024/697 22:09:54 INFO - PROCESS | 3313 | __metrics 22:09:55 ERROR - PROCESS | 3313 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 22:09:55 INFO - TEST-INFO | 3313: exit 0 22:09:55 INFO - Browser initialized. 22:09:55 INFO - Running cycle 1/1 for dromaeo_css test... 22:09:55 INFO - TEST-INFO | started process 3439 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnacI03/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:10:07 INFO - PROCESS | 3439 | RSS: Main: 152342528 22:10:07 INFO - PROCESS | 3439 | 22:10:57 INFO - PROCESS | 3439 | 1473225057276 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 22:13:06 INFO - PROCESS | 3439 | Cycle 1(1): loaded http://localhost:53766/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53766/tests/dromaeo/cssquery-ext.html) 22:13:07 INFO - PROCESS | 3439 | RSS: Main: 157683712 22:13:07 INFO - PROCESS | 3439 | 22:16:09 INFO - PROCESS | 3439 | Cycle 1(1): loaded http://localhost:53766/tests/dromaeo/cssquery-ext.html (next: http://localhost:53766/tests/dromaeo/cssquery-jquery.html) 22:16:09 INFO - PROCESS | 3439 | RSS: Main: 153620480 22:16:09 INFO - PROCESS | 3439 | 22:19:11 INFO - PROCESS | 3439 | Cycle 1(1): loaded http://localhost:53766/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53766/tests/dromaeo/cssquery-mootools.html) 22:19:12 INFO - PROCESS | 3439 | RSS: Main: 155414528 22:19:12 INFO - PROCESS | 3439 | 22:22:13 INFO - PROCESS | 3439 | Cycle 1(1): loaded http://localhost:53766/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53766/tests/dromaeo/cssquery-prototype.html) 22:22:14 INFO - PROCESS | 3439 | RSS: Main: 153550848 22:22:14 INFO - PROCESS | 3439 | 22:25:17 INFO - PROCESS | 3439 | Cycle 1(1): loaded http://localhost:53766/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53766/tests/dromaeo/cssquery-yui.html) 22:25:17 INFO - PROCESS | 3439 | RSS: Main: 155504640 22:25:17 INFO - PROCESS | 3439 | 22:28:20 INFO - PROCESS | 3439 | Cycle 1(1): loaded http://localhost:53766/tests/dromaeo/cssquery-yui.html (next: http://localhost:53766/tests/dromaeo/cssquery-dojo.html) 22:28:20 INFO - PROCESS | 3439 | RSS: Main: 155684864 22:28:20 INFO - PROCESS | 3439 | 22:28:20 INFO - PROCESS | 3439 | __start_tp_report 22:28:20 INFO - PROCESS | 3439 | _x_x_mozilla_page_load 22:28:20 INFO - PROCESS | 3439 | _x_x_mozilla_page_load_details 22:28:20 INFO - PROCESS | 3439 | |i|pagename|runs| 22:28:20 INFO - PROCESS | 3439 | |0;dojo.html;3327.8169700666467;3348.8493017814203;3364.108511244522;3371.106656735964;3395.0324157615087;5842.79550215742;5864.912026319608;5898.675572843492;5917.733701983416;5950.137230101633;5775.162601422801;5840.591158618888;5872.941270587293;5878.059510478324;5887.793927212549;5308.787648494055;5360.91958620621;5366.70483123428;5393.9190912136155;5413.160960051198;5211.114110601206;5231.215317702337;5257.86855328616;5296.046711591912;5299.60252981026;5335.573154147659;5340.67955922644;5346.358436987567;5382.165764306519;5388.380336261322;5364.892702145955;5371.919421208685;5381.112116500773;5386.084365657847;5402.486763757476;5104;5172.948270517284;5182.585393168575;5284.10170271051;5329.973350133263;5163.3545806774155;5597.384287728347;5658.490735833753;5744;5763.769449222026;6128.3565225651555;6161.9691901540655;6162.414570615784;6165.599236049642;6176.042713379388;6197.845053873689;6200.937990620081;6201.542642474594;6202.472789812871;6208.689565521706;5581.6930068846195;5715.342735585419;5745.856353591152;5773.480386765212;5924.644521328734;6338.760820544463;6384.808455746335;6386.840328991812;6387.361263873576;6409.647469389229;6156.538259630456;6185.165002724664;6188.938110618837;6189.721462534197;6198.783042593487;5954.970225148846;6124.38756124384;6157.6613286269685;6175.938240617626;6285.214348206474;6223.439890409885;6235.75056997715;6262.655553944577;6263.467605253604;6277.96861015692;6229.283632382243;6236.158118654014;6247.718852651642;6266.122742816009;6277.2153480814895;4590.150822097922;4632.073585282957;4679.017406344706;4696.225122854691;4753.096911586787;4156.646685031746;4180.665546756252;4186.7278626889765;4196.559361267084;4212.283911735012;5862.941370586324;5887.646741195542;5896.734646940898;5897.852553686106;5983.551233657493;5986.132010858489;6135.355158799479;6143.450468055707;6164.044573091178;6337.207849018872;6034.668093254913;6048.4858787003595;6060.939390606126;6063.8484037898525;6093.969530152321;5184.092783762826;5206.297149884717;5209.244659524347;5219.556337711337;5225.163973764255;6235.189425374763;6245.250569931637;6303.086052522359;6309;6472.061551075162;6184.257889053342;6200.627962322275;6211.378862113933;6219.284782250099;6228.532860035425;6478.060681201294;6483.870322593616;6487.091807147098;6489.448396886318;6494.675266236574;5883.470487656132;5969.403059693996;6006.729697163725;6046.5767396281835;6115.453310713017;6261.78083767066;6275.937240627718;6282.057691346335;6282.371762823681;6307.558470907176;6121.296050954019;6127.805078009746;6131.8467038322615;6137.772445511005;6157.353477884937;5926.259217597801;6021.397860213943;6046.818595442144;6066.848328791815;6142.201513803356;6072.240969878765;6079.361667025076;6081.422264884829;6092.725827337692;6104.633721976696;6090.23872015998;6094.573379863545;6167.84580385494;6169.84575385619;6184.474319682878;5788.305403351625;5864.003119469786;5988.4909782668965;5989.40105989415;6117.388261173847 22:28:20 INFO - PROCESS | 3439 | |1;ext.html;597.7160848596917;630.5145038320493;649.5550547874703;650.3074225949364;698.6122701900443;41296.38055429171;44621.776891115536;45040.32439513402;45337.31994020093;45430.54569454304;46777;47332;47396.52603473973;47464.525354746445;47511.52488475114;8879.422837515573;9015.729528114167;9035.412698174598;9052.230560402373;9077.95461022694;8992.145746154105;9026.826512553393;9033.864492032624;9082.273418126533;9089.68186113486;9103.726888193365;9134;9138.543072846363;9158.954205228998;9163.450192988408;9062.365634405594;9108.18026377626;9186.494742789144;9190.540472976325;9193.724188274362;9081.682141125095;9102.317326200495;9119.908800912039;9151.313651476099;9180.770480738034;42574.78712606417;42808.3578746319;43029.56970430318;43052.138957220996;43188.784056079516;3788.90527736806;3843.846246150151;3932.8623498177426;4244.236037513247;4521.706089104197;33962.1509462263;33990.32019359599;34211.657883421096;34236.315273694636;35918.281634367166;3849.7305188636806;3905.1018265798743;3912.882613521599;3936.586658400884;3939.074317535377;357714;361694;363508;364047.17976410215;364228;35661.8216908919;36595.26809463849;56089;56461;56860.43139568551;38852.80573597114;38898.22203555913;39106.413403798964;39232;39350.21299573992;15114.168720720467;15242.085474871321;15281.38874445032;15293.311800968982;15312.387504499919;12113.576024839089;12197.39013049344;12197.817032744517;12200.206986545845;12308.81536776949;9048.50233237165;9060.95469522648;9067;9146.2225710814;9209.447433153899;3502.614712381636;3577.928441431157;3583.1221350768706;3614.8373323200517;3634.182308980511;988.5847943863595;1009.3540134314045;1104.9226554141294;1109.7946879827257;1153.798085335063;34656.96392960855;35216;35247.29505409929;35542.822285888404;35547.93356199318;1107.5901916291025;1127.3686735428023;1129.412705393191;1133.0652211925194;1144.719543711796;18237.27050917975;18318.267269309345;18346.724799128024;18374.908125459286;18546.44360669155;619421;625788;628073;628846;630203;66214;66716.33283667198;66762.33237667561;67024.6648766753;67152.66423667851;1361.3805718398303;1369.4043091255137;1379.558541266785;1384.9099808512415;1392.5752645443035;32769.180770480925;33788.32423351568;34016.65983340135;34219.14452138716;34300.31399371949;56709.149362760414;56859.14711279417;57311.426885732275;57365;57432.42567574438;363218.18390908936;364654;367912;368905;369463;31628.367432651678;31655.683443166196;32042.51936220912;32154.678453216104;32241.838790805898;53242;53405;53471.732641336544;53872.83351884292;54320.18519722128;43639.56360436355;48324.7583762093;49354.50645493499;50050;50680;46047;46675.53324466712;46701;46784.53215467802;47378.76310618425;39102.413463798635;44636.55363446324;44726.329105062796;45198.54801451943;47166.52833471621;39779.60220397759;39876.60123398729;40594.59405405908;40730.79634601808;41169.38245926373;30872.382552349278;30962;31357.52963705592;31365.024942316148;31451.213719657193 22:28:20 INFO - PROCESS | 3439 | |2;jquery.html;406.8820042187764;412.5358971157448;418.9204051230266;420.6319470463346;427.17555118621067;20024.89987550064;20862.269820556285;21590.352289431303;21623.891880540614;21801.781982180175;20455;20589.485262868435;20828.375148745523;20978.265760698378;21093.472663183427;5719.542436605062;5849.590528662995;5919;5942.049272116462;5945;5787.421257874221;5795.043817770063;5851.736671849756;5880.735366908478;5888.676122813244;5664.603477756558;5777.133429985514;5802.506786923117;5823.825285241428;5836.766529338822;5678.318601767773;5758.42415758425;5761.711914404284;5773.682447465388;5816.185733997244;5221.530062294411;5317.813876514342;5332.33345831771;5356.9732151339385;5360.91958620621;24408.14571490007;24479;24642.876785616136;24654.260372188863;25260.242192734248;2443.8900249488815;2756.3444984612647;2806.5228911085164;3204.567383403234;3263.282077942849;15411.306491207923;15450.922745386313;15466;15681.921590392089;15731.52805415828;2041;2041.4488088216099;2082.750069991611;2108.8945552722457;2133.1040962795664;80459;81386.37227255481;83259.58370208171;85183;85814;11281.830772538418;11456.942715286537;11509.309441433541;11606.187566870238;11650.126240531994;16249.106299153424;16401.1799410029;16405.91797041031;16437.506874793777;16464.176791160393;7550.735724249625;7621.69513219465;8111.675532978733;8258.8348233035;8259.050209225896;7592;7634.465587408939;7637.274458926393;7766.37868970481;7770.339521140653;4485.529019452975;4531.071130418322;4609.262517997105;4741.454732705783;5023.79904803811;1918.1655979648897;2078.4596005038798;2138.0806253311225;2161.124744478457;2164.1343462614764;900.8873890763655;962.259060523393;975.8341082016075;1020.2093377632245;1059.5391004912888;20829.270975515785;21069.367918962456;21102.2614208502;21202;21400.143994240367;972.8297547929084;993.438707130469;1039.0336986602533;1064.2390690656064;1067.4662668665667;11091.778164436666;11300.547978080785;11581.826272605918;11666.708332291591;11924.224925379822;468680;479184;531913;540221;541949;64206.6789666058;93029;110926;111810;112378.43810780894;977.6969139566758;1018.8777346718441;1053.5311786255043;1110.2838669058413;1112.7273817914663;17949.37177198844;18336.449906502825;18626;18715.719264210777;18989.05054747285;43567.34648980331;44611.77694111509;44948.73288813891;45247.547524524336;45473;57602;58073;63368.36631633624;63555.36444635679;65384;8828.602712878064;9091.863622045807;9241.353105282566;9263.490508022123;9344.626214951326;9242.353035287735;13210.141340813201;13836.515721950087;13974.79037814454;13981.091229070078;12265.938670306949;12703.110782245154;12778.977681785249;12806.103572749818;13107.213567186;12537.122401431812;12648.367581621067;12660.493580257054;12743.426545805647;12850.485980560672;12593.748125037264;12665;12723.681907952376;12758.463309124043;13056.21662700241;9048.773780655536;9170.862437063315;9251.583678734338;9324.860127098234;9428.764280893032;11989.460474278503;12072.517099316281;12163.939180304396;12455.626331210078;12611.243325400505 22:28:20 INFO - PROCESS | 3439 | |3;mootools.html;1583.0343490470812;1620.2789758557442;1788.4008857032893;1839.1631807527576;1873.2319748902953;8493.957530212356;9211.309151813615;9222.677206297782;9980.800383992315;10002;9357.298202634796;9659.80680386392;9669.081437263449;9710.320277580573;9713.757156071102;2459.0409740201335;2468.2348471973746;2475.1584461283114;2488.042103790036;2503.9374015649573;2441.5361081394476;2545.0583284184877;2547.350425641468;2559.897604095834;2561.2828408045775;2536.4039450729065;2548.4138648110948;2558.0535201975295;2566.691996960358;2575.6909170899517;2479.6776419065404;2498.6252062190633;2506.223070848043;2515.3963048868322;2524.798016158721;2464.913728019511;2479.82641215115;2481.85108893467;2482.379405148713;2484.1926373928363;9658;9702.805943881083;9799.363041402286;9872.407655540619;9923.702288931343;5638.746256418471;5714.571407144481;5721.284839395075;5735.0823868181305;5773.88452230953;3432.262063656326;3531.187826799825;3583.4624806279007;3583.6416358364218;3584.1577229351083;1202.7594481103727;1238.8141778733172;1257.9119461637688;1259.779538580754;1272.1730874931277;13931.930340348335;13948.721025579533;13972;13987.650308742363;14014;5015.3730783652045;5049.166887463527;5104.566111880457;5157.381114266312;5189.273501709764;11623.360715160747;11646.126540509496;11704.122190835722;11708.70728231784;11712.648620541397;5872.060470324727;5886.440788125121;5898.498627616701;5921.940780592225;5922.2597175353085;4532.592066714012;5274.604404669665;5331.226972089103;5422.457754224546;5444.346678398618;4387.056782791715;4441.289393697058;4473.932891006638;4474.932876006863;4504.414426124583;1396.4553824008644;1398.9440422383195;1400.908940918837;1413.3993052952453;1433.1830856411746;1045.9581616735397;1066.269605320358;1074.2050882347007;1075.5321435175724;1099.0822663076422;6396.5202609804455;6659.766908158288;6721.395074443226;6785.93214067853;6865.862682746317;459.81147729430774;460.1234647995507;462.6830621024609;466.76195140478603;469.4413647759156;4513.661475389294;4523.683342166017;4558.133954548625;4595.701279416827;4619.145458090264;11693.824592631117;11957.461914213882;12081.335526546125;12105.69735756613;12118.03055755555;12091.637250882488;12143.696407589881;12162.209456385306;12164.33096179701;12178.086643501772;8596.355273354524;8954.776130596787;8972.102789720975;9188.219001384958;9207.631694732136;6567.671616419256;6654;6777.661116944231;6889.069975553437;7015.438764898898;11143.609973650884;11311.377874216998;11326.603568875049;11496.712582185512;11497.195196336175;11492.82760758606;11521.423928803359;11578.073754099483;11581.594644187413;11604.013658839092;8473.745787626382;8809.911900880908;8878.11218878107;8884.955575222082;8901.421407608485;9357.111074448188;9451.243900488082;9487.525623718924;9540.427574345562;9559.139677429064;8221.506709597443;8416.2004609562;8457.873131902903;8486.19381158789;8570.185832345916;7955.363570914429;8008.079070906687;8057.43597948138;8090.514569125871;8311.459755115899;7454.552726836408;7467.253274672489;7510.549367037996;7531.8117047074265;7534.886976695464;5391.083515802401;5499.780008799763;5569.387367389665;5584.441555844383;5748.741306641295;5669.971650141723;5775.306963164448;5784.768609255582;5837.0368889132815;5890.587658864009 22:28:20 INFO - PROCESS | 3439 | |4;prototype.html;344.1001780344399;348.4858290726792;349.14111286235857;355.5537800060923;357.4905759293007;5809.854753631162;5851.648901065933;5871.119332100182;5873.676947767871;5879.470847623713;5784.033428634263;5798.478136967672;5801.941980580193;5815.534757219423;5830.7667693292215;3905.2970465316303;4408.603225709686;4513.50351461339;4538.931916021263;4558.430196225472;4548.135854187711;4560.2019646561885;4563.931541026887;4581.5647513486165;4600.217962946307;4527.615152712024;4536.364908912738;4546.8863278417975;4549.31760235964;4559.521250268724;4233.153369326132;4432.711873728197;4493.955060449419;4526.75102869342;4541.863744087683;3959.227950549644;3963.504561929759;3964.5044369453817;3968.285708572456;3983.342748446503;5661.60368774186;5672.68800215988;5674.574406919498;5688.573356998241;5701.116326969326;4933.876653083666;5022.27177059328;5145.3568303962;5151.4333423323405;5221.477852214786;6035.818925432244;6081.878362432771;6091.238595175603;6097.237845269341;6105.786297479612;4304.526502084768;4304.978475107604;4346.630536404409;4351.630111440531;4397.780110994438;6123.357047510036;6133.84665383369;6195.659238741822;6286.245650521921;6297.559170858041;4481.663875209372;4529.5243999380245;4535.2765948939195;4559.840405585788;4571;4593.196190666687;4608.170529304757;4615.676902616851;4616.792244349012;4638.628909687217;4958.826441074545;5119.769610367543;5281.970015846873;5285.577153827685;5291.99452104099;6016.548758843104;6041.486473649701;6066.817995460143;6080.361562036011;6090.390960903874;1540.8536189062245;1568.827428982811;1571.3478906253777;1572.5518227305392;1576.1646327446472;989.302541708094;1043.6816770884802;1048.3238311289176;1049.3598904668147;1064.1965316186229;5241.659292145999;5246.216286462544;5251.684898906078;5267.657602255841;5300.522952934254;5417.187421886749;5480.068388373986;5502.394736578973;5524.4199359067525;5551.444855514416;4972.3894955148835;5189.42916279209;5251.658642188246;5253.658512196695;5284.894302113936;2898.739113479755;2920.8831646734316;2935.1822250639198;2957.2015555799812;2970.7474864636747;5542;5659.971700141473;5666.206731057655;5686.947914635805;5688.971555142198;5975.551833612496;6400.679966001681;6435.517336199804;6493.642849643315;6498.740050397932;6645.933540664531;6655.600663960178;6656.733730650816;6688.297728738413;6702.597844129368;6141.938580614136;6259.280182778856;6267.592606480564;6268.655223962726;6436.259830119596;6193.783217587363;6214.130021796862;6247.68761561911;6291.528135389773;6305.306416294296;6204.472619827366;6382.297947225894;6512.088307637029;6517.152770139852;6535.607863528204;4420.889477763081;4438.578335058164;4445.222086134849;4456.376107345039;4473.306637471197;6204.162438070803;6241.251049874044;6282.591631543935;6350.872982540416;6353.872922541431;6076.665783381956;6097.634141951497;6117.96941015292;6123.693815309306;6139.2325959255095;5888.352281249145;6125.47933425646;6161.907571386343;6191.349908259568;6207.379262073757;5972.25346831646;6004.459598636144;6012.518998480198;6021.9397806023135;6029.668368239789;6100.23747031621;6133.355997620364;6135.693215339126;6141.662208578571;6170.568060235741;5757.683327417032;5972.880542389041;5973.1637570741;6004.618937644454;6039.214902062704 22:28:20 INFO - PROCESS | 3439 | |5;yui.html;847.8134810341722;863.5725315968592;873.6199753107397;904.9728508144758;951.5052172870104;16074.437394691187;17676.20457079433;17807;17873.7318940216;17954.10229488525;16436.178191090457;16587.66824663509;16609;17039.318427262893;17391.652166956654;861.7931696392883;869.9043105258448;870.4820631724114;872.9960545372841;891.6121487153076;878.3017501086651;905.316486053029;910.8269428808505;913.3332667152982;915.5239275576696;956.8469044952809;962.9518524073803;964.6961207219739;965.9130678238957;969.0793745941348;951.4291425144927;954.9952250238739;955.0401846144649;955.8709574207533;956.6030097509525;702.3854127638316;703.4126504368858;703.5391818358931;705.2911823617283;707.7805880177161;16326.346946122141;16357.100359480224;16417.42539011129;16456.42402515918;16472.42346517878;14968.401263949429;15155.393784248618;15191.468298609496;15322.310496027705;15357.616059598487;13508;13601.523946661919;13633;13633.795493067611;13974.161550307013;4085.836566537365;4168.270552653274;4181.811818468176;4190.476190476191;4218.8523401680795;64525.354746452875;64723.67638161779;79203.6039819803;91300;92269;12234.571789987307;12267.815982760265;12280.938595306967;12500.24998500093;12682;23357;23529.7647023531;23552.64671029936;23798;24072.87963560206;8398.748037558884;8924.55377231136;8973.596188171548;9060.773480662905;9098.181163695299;5093.363329583802;5192.247124166975;5262.289590905179;5264.736763161826;5303.973480132575;3637.399829028234;3823.235352929425;3823.617638236154;3831.6934645228316;3836.3861782115287;641.5733537197805;650.4991156809325;656.3961155736733;667.5927684112687;675.3651567526509;642.5823214910346;661.8940969444963;663.571996062537;725.2529894208973;760.9315161635479;9253.72238832836;9643.373180743229;9665.275104367201;10163;10465.110465610509;660.3132741948416;667.9799606011827;725.8983742276085;737.2075019354215;739.3789217057697;4586.197415452282;4700.741459219776;4759.547842954914;4918.803247870045;4925.162722337212;105069;136537.3173134128;139019.30490347484;139057.30471347782;141130;94056;95342.52328738451;117696;119483;120595.39702301608;91652.54173729089;92539.5373023144;92773.53613231891;94150.52924735332;95509;10560.366378017343;11017.559297628006;11391.658250252505;11440.370779607201;11649.767004660029;52616;52906;53010;53324.73337633287;53755;70913.64543177458;81161.59419202866;81777.59111204406;82867.17132828594;84499.15500845159;14171.929140354232;14435.92782036083;14795.852041479879;14862.479813206486;14898.851011489747;15798.842011579736;16188.791148545846;16607.3357065716;17124.571885702957;17928.372506962194;14683.853161468676;15345.693086138437;16034.198290085682;16062.277197525906;16685.249163787415;14095.506657266946;14444.205568693822;14789.260536973323;15738.842611573738;15770;12615.747685046432;13405;14685.41258349654;15475.613109672347;15813.525594232191;11821;11889.346085965355;12914.612561623166;12957.546485872948;13309.866901330863;11417.486213120595;11430.542778289107;11690.357030363411;11900.702482438008;12103 22:28:20 INFO - PROCESS | 3439 | __end_tp_report 22:28:20 INFO - PROCESS | 3439 | __start_cc_report 22:28:20 INFO - PROCESS | 3439 | _x_x_mozilla_cycle_collect,331 22:28:20 INFO - PROCESS | 3439 | __end_cc_report 22:28:20 INFO - PROCESS | 3439 | __startTimestamp1473226100547__endTimestamp 22:28:20 INFO - PROCESS | 3439 | 22:28:20 INFO - PROCESS | 3439 | ------- Summary: start ------- 22:28:20 INFO - PROCESS | 3439 | Number of tests: 6 22:28:20 INFO - PROCESS | 3439 | 22:28:20 INFO - PROCESS | 3439 | [#0] dojo.html Cycles:165 Average:5770.48 Median:6046.58 stddev:667.35 (11.0%) stddev-sans-first:641.30 22:28:20 INFO - PROCESS | 3439 | Values: 3327.8 3348.8 3364.1 3371.1 3395.0 5842.8 5864.9 5898.7 5917.7 5950.1 5775.2 5840.6 5872.9 5878.1 5887.8 5308.8 5360.9 5366.7 5393.9 5413.2 5211.1 5231.2 5257.9 5296.0 5299.6 5335.6 5340.7 5346.4 5382.2 5388.4 5364.9 5371.9 5381.1 5386.1 5402.5 5104.0 5172.9 5182.6 5284.1 5330.0 5163.4 5597.4 5658.5 5744.0 5763.8 6128.4 6162.0 6162.4 6165.6 6176.0 6197.8 6200.9 6201.5 6202.5 6208.7 5581.7 5715.3 5745.9 5773.5 5924.6 6338.8 6384.8 6386.8 6387.4 6409.6 6156.5 6185.2 6188.9 6189.7 6198.8 5955.0 6124.4 6157.7 6175.9 6285.2 6223.4 6235.8 6262.7 6263.5 6278.0 6229.3 6236.2 6247.7 6266.1 6277.2 4590.2 4632.1 4679.0 4696.2 4753.1 4156.6 4180.7 4186.7 4196.6 4212.3 5862.9 5887.6 5896.7 5897.9 5983.6 5986.1 6135.4 6143.5 6164.0 6337.2 6034.7 6048.5 6060.9 6063.8 6094.0 5184.1 5206.3 5209.2 5219.6 5225.2 6235.2 6245.3 6303.1 6309.0 6472.1 6184.3 6200.6 6211.4 6219.3 6228.5 6478.1 6483.9 6487.1 6489.4 6494.7 5883.5 5969.4 6006.7 6046.6 6115.5 6261.8 6275.9 6282.1 6282.4 6307.6 6121.3 6127.8 6131.8 6137.8 6157.4 5926.3 6021.4 6046.8 6066.8 6142.2 6072.2 6079.4 6081.4 6092.7 6104.6 6090.2 6094.6 6167.8 6169.8 6184.5 5788.3 5864.0 5988.5 5989.4 6117.4 22:28:20 INFO - PROCESS | 3439 | 22:28:20 INFO - PROCESS | 3439 | [#1] ext.html Cycles:180 Average:61602.02 Median:22770.31 stddev:124759.94 (547.9%) stddev-sans-first:125025.85 22:28:20 INFO - PROCESS | 3439 | Values: 597.7 630.5 649.6 650.3 698.6 41296.4 44621.8 45040.3 45337.3 45430.5 46777.0 47332.0 47396.5 47464.5 47511.5 8879.4 9015.7 9035.4 9052.2 9078.0 8992.1 9026.8 9033.9 9082.3 9089.7 9103.7 9134.0 9138.5 9159.0 9163.5 9062.4 9108.2 9186.5 9190.5 9193.7 9081.7 9102.3 9119.9 9151.3 9180.8 42574.8 42808.4 43029.6 43052.1 43188.8 3788.9 3843.8 3932.9 4244.2 4521.7 33962.2 33990.3 34211.7 34236.3 35918.3 3849.7 3905.1 3912.9 3936.6 3939.1 357714.0 361694.0 363508.0 364047.2 364228.0 35661.8 36595.3 56089.0 56461.0 56860.4 38852.8 38898.2 39106.4 39232.0 39350.2 15114.2 15242.1 15281.4 15293.3 15312.4 12113.6 12197.4 12197.8 12200.2 12308.8 9048.5 9061.0 9067.0 9146.2 9209.4 3502.6 3577.9 3583.1 3614.8 3634.2 988.6 1009.4 1104.9 1109.8 1153.8 34657.0 35216.0 35247.3 35542.8 35547.9 1107.6 1127.4 1129.4 1133.1 1144.7 18237.3 18318.3 18346.7 18374.9 18546.4 619421.0 625788.0 628073.0 628846.0 630203.0 66214.0 66716.3 66762.3 67024.7 67152.7 1361.4 1369.4 1379.6 1384.9 1392.6 32769.2 33788.3 34016.7 34219.1 34300.3 56709.1 56859.1 57311.4 57365.0 57432.4 363218.2 364654.0 367912.0 368905.0 369463.0 31628.4 31655.7 32042.5 32154.7 32241.8 53242.0 53405.0 53471.7 53872.8 54320.2 43639.6 48324.8 49354.5 50050.0 50680.0 46047.0 46675.5 46701.0 46784.5 47378.8 39102.4 44636.6 44726.3 45198.5 47166.5 39779.6 39876.6 40594.6 40730.8 41169.4 30872.4 30962.0 31357.5 31365.0 31451.2 22:28:20 INFO - PROCESS | 3439 | 22:28:20 INFO - PROCESS | 3439 | [#2] jquery.html Cycles:180 Average:30634.19 Median:24957.25 stddev:84712.19 (339.4%) stddev-sans-first:84919.43 22:28:20 INFO - PROCESS | 3439 | Values: 406.9 412.5 418.9 420.6 427.2 20024.9 20862.3 21590.4 21623.9 21801.8 20455.0 20589.5 20828.4 20978.3 21093.5 5719.5 5849.6 5919.0 5942.0 5945.0 5787.4 5795.0 5851.7 5880.7 5888.7 5664.6 5777.1 5802.5 5823.8 5836.8 5678.3 5758.4 5761.7 5773.7 5816.2 5221.5 5317.8 5332.3 5357.0 5360.9 24408.1 24479.0 24642.9 24654.3 25260.2 2443.9 2756.3 2806.5 3204.6 3263.3 15411.3 15450.9 15466.0 15681.9 15731.5 2041.0 2041.4 2082.8 2108.9 2133.1 80459.0 81386.4 83259.6 85183.0 85814.0 11281.8 11456.9 11509.3 11606.2 11650.1 16249.1 16401.2 16405.9 16437.5 16464.2 7550.7 7621.7 8111.7 8258.8 8259.1 7592.0 7634.5 7637.3 7766.4 7770.3 4485.5 4531.1 4609.3 4741.5 5023.8 1918.2 2078.5 2138.1 2161.1 2164.1 900.9 962.3 975.8 1020.2 1059.5 20829.3 21069.4 21102.3 21202.0 21400.1 972.8 993.4 1039.0 1064.2 1067.5 11091.8 11300.5 11581.8 11666.7 11924.2 468680.0 479184.0 531913.0 540221.0 541949.0 64206.7 93029.0 110926.0 111810.0 112378.4 977.7 1018.9 1053.5 1110.3 1112.7 17949.4 18336.4 18626.0 18715.7 18989.1 43567.3 44611.8 44948.7 45247.5 45473.0 57602.0 58073.0 63368.4 63555.4 65384.0 8828.6 9091.9 9241.4 9263.5 9344.6 9242.4 13210.1 13836.5 13974.8 13981.1 12265.9 12703.1 12779.0 12806.1 13107.2 12537.1 12648.4 12660.5 12743.4 12850.5 12593.7 12665.0 12723.7 12758.5 13056.2 9048.8 9170.9 9251.6 9324.9 9428.8 11989.5 12072.5 12163.9 12455.6 12611.2 22:28:20 INFO - PROCESS | 3439 | 22:28:20 INFO - PROCESS | 3439 | [#3] mootools.html Cycles:180 Average:6404.00 Median:2527.76 stddev:3718.74 (147.1%) stddev-sans-first:3711.52 22:28:20 INFO - PROCESS | 3439 | Values: 1583.0 1620.3 1788.4 1839.2 1873.2 8494.0 9211.3 9222.7 9980.8 10002.0 9357.3 9659.8 9669.1 9710.3 9713.8 2459.0 2468.2 2475.2 2488.0 2503.9 2441.5 2545.1 2547.4 2559.9 2561.3 2536.4 2548.4 2558.1 2566.7 2575.7 2479.7 2498.6 2506.2 2515.4 2524.8 2464.9 2479.8 2481.9 2482.4 2484.2 9658.0 9702.8 9799.4 9872.4 9923.7 5638.7 5714.6 5721.3 5735.1 5773.9 3432.3 3531.2 3583.5 3583.6 3584.2 1202.8 1238.8 1257.9 1259.8 1272.2 13931.9 13948.7 13972.0 13987.7 14014.0 5015.4 5049.2 5104.6 5157.4 5189.3 11623.4 11646.1 11704.1 11708.7 11712.6 5872.1 5886.4 5898.5 5921.9 5922.3 4532.6 5274.6 5331.2 5422.5 5444.3 4387.1 4441.3 4473.9 4474.9 4504.4 1396.5 1398.9 1400.9 1413.4 1433.2 1046.0 1066.3 1074.2 1075.5 1099.1 6396.5 6659.8 6721.4 6785.9 6865.9 459.8 460.1 462.7 466.8 469.4 4513.7 4523.7 4558.1 4595.7 4619.1 11693.8 11957.5 12081.3 12105.7 12118.0 12091.6 12143.7 12162.2 12164.3 12178.1 8596.4 8954.8 8972.1 9188.2 9207.6 6567.7 6654.0 6777.7 6889.1 7015.4 11143.6 11311.4 11326.6 11496.7 11497.2 11492.8 11521.4 11578.1 11581.6 11604.0 8473.7 8809.9 8878.1 8885.0 8901.4 9357.1 9451.2 9487.5 9540.4 9559.1 8221.5 8416.2 8457.9 8486.2 8570.2 7955.4 8008.1 8057.4 8090.5 8311.5 7454.6 7467.3 7510.5 7531.8 7534.9 5391.1 5499.8 5569.4 5584.4 5748.7 5670.0 5775.3 5784.8 5837.0 5890.6 22:28:20 INFO - PROCESS | 3439 | 22:28:20 INFO - PROCESS | 3439 | [#4] prototype.html Cycles:180 Average:5052.96 Median:5660.79 stddev:1498.18 (26.5%) stddev-sans-first:1460.10 22:28:20 INFO - PROCESS | 3439 | Values: 344.1 348.5 349.1 355.6 357.5 5809.9 5851.6 5871.1 5873.7 5879.5 5784.0 5798.5 5801.9 5815.5 5830.8 3905.3 4408.6 4513.5 4538.9 4558.4 4548.1 4560.2 4563.9 4581.6 4600.2 4527.6 4536.4 4546.9 4549.3 4559.5 4233.2 4432.7 4494.0 4526.8 4541.9 3959.2 3963.5 3964.5 3968.3 3983.3 5661.6 5672.7 5674.6 5688.6 5701.1 4933.9 5022.3 5145.4 5151.4 5221.5 6035.8 6081.9 6091.2 6097.2 6105.8 4304.5 4305.0 4346.6 4351.6 4397.8 6123.4 6133.8 6195.7 6286.2 6297.6 4481.7 4529.5 4535.3 4559.8 4571.0 4593.2 4608.2 4615.7 4616.8 4638.6 4958.8 5119.8 5282.0 5285.6 5292.0 6016.5 6041.5 6066.8 6080.4 6090.4 1540.9 1568.8 1571.3 1572.6 1576.2 989.3 1043.7 1048.3 1049.4 1064.2 5241.7 5246.2 5251.7 5267.7 5300.5 5417.2 5480.1 5502.4 5524.4 5551.4 4972.4 5189.4 5251.7 5253.7 5284.9 2898.7 2920.9 2935.2 2957.2 2970.7 5542.0 5660.0 5666.2 5686.9 5689.0 5975.6 6400.7 6435.5 6493.6 6498.7 6645.9 6655.6 6656.7 6688.3 6702.6 6141.9 6259.3 6267.6 6268.7 6436.3 6193.8 6214.1 6247.7 6291.5 6305.3 6204.5 6382.3 6512.1 6517.2 6535.6 4420.9 4438.6 4445.2 4456.4 4473.3 6204.2 6241.3 6282.6 6350.9 6353.9 6076.7 6097.6 6118.0 6123.7 6139.2 5888.4 6125.5 6161.9 6191.3 6207.4 5972.3 6004.5 6012.5 6021.9 6029.7 6100.2 6133.4 6135.7 6141.7 6170.6 5757.7 5972.9 5973.2 6004.6 6039.2 22:28:20 INFO - PROCESS | 3439 | 22:28:20 INFO - PROCESS | 3439 | [#5] yui.html Cycles:180 Average:22731.61 Median:4127.05 stddev:33138.77 (803.0%) stddev-sans-first:33191.00 22:28:20 INFO - PROCESS | 3439 | Values: 847.8 863.6 873.6 905.0 951.5 16074.4 17676.2 17807.0 17873.7 17954.1 16436.2 16587.7 16609.0 17039.3 17391.7 861.8 869.9 870.5 873.0 891.6 878.3 905.3 910.8 913.3 915.5 956.8 963.0 964.7 965.9 969.1 951.4 955.0 955.0 955.9 956.6 702.4 703.4 703.5 705.3 707.8 16326.3 16357.1 16417.4 16456.4 16472.4 14968.4 15155.4 15191.5 15322.3 15357.6 13508.0 13601.5 13633.0 13633.8 13974.2 4085.8 4168.3 4181.8 4190.5 4218.9 64525.4 64723.7 79203.6 91300.0 92269.0 12234.6 12267.8 12280.9 12500.2 12682.0 23357.0 23529.8 23552.6 23798.0 24072.9 8398.7 8924.6 8973.6 9060.8 9098.2 5093.4 5192.2 5262.3 5264.7 5304.0 3637.4 3823.2 3823.6 3831.7 3836.4 641.6 650.5 656.4 667.6 675.4 642.6 661.9 663.6 725.3 760.9 9253.7 9643.4 9665.3 10163.0 10465.1 660.3 668.0 725.9 737.2 739.4 4586.2 4700.7 4759.5 4918.8 4925.2 105069.0 136537.3 139019.3 139057.3 141130.0 94056.0 95342.5 117696.0 119483.0 120595.4 91652.5 92539.5 92773.5 94150.5 95509.0 10560.4 11017.6 11391.7 11440.4 11649.8 52616.0 52906.0 53010.0 53324.7 53755.0 70913.6 81161.6 81777.6 82867.2 84499.2 14171.9 14435.9 14795.9 14862.5 14898.9 15798.8 16188.8 16607.3 17124.6 17928.4 14683.9 15345.7 16034.2 16062.3 16685.2 14095.5 14444.2 14789.3 15738.8 15770.0 12615.7 13405.0 14685.4 15475.6 15813.5 11821.0 11889.3 12914.6 12957.5 13309.9 11417.5 11430.5 11690.4 11900.7 12103.0 22:28:20 INFO - PROCESS | 3439 | -------- Summary: end -------- 22:28:20 INFO - PROCESS | 3439 | 22:28:21 INFO - TEST-INFO | 3439: exit 0 22:28:21 INFO - TEST-OK | dromaeo_css | took 1112778ms 22:28:21 INFO - TEST-START | kraken 22:28:21 INFO - operating with platform_type : linux_ 22:28:21 INFO - Initialising browser for kraken test... 22:28:21 INFO - TEST-INFO | started process 3702 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp50iG8e/profile http://localhost:53766/getInfo.html) 22:28:25 INFO - PROCESS | 3702 | __metrics Screen width/height:1600/1200 22:28:25 INFO - PROCESS | 3702 | colorDepth:24 22:28:25 INFO - PROCESS | 3702 | Browser inner width/height: 1024/697 22:28:25 INFO - PROCESS | 3702 | __metrics 22:28:26 ERROR - PROCESS | 3702 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 22:28:27 INFO - TEST-INFO | 3702: exit 0 22:28:27 INFO - Browser initialized. 22:28:27 INFO - Running cycle 1/1 for kraken test... 22:28:27 INFO - TEST-INFO | started process 3824 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp50iG8e/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:28:39 INFO - PROCESS | 3824 | RSS: Main: 152264704 22:28:39 INFO - PROCESS | 3824 | 22:28:41 INFO - PROCESS | 3824 | Cycle 1(1): loaded http://localhost:53766/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53766/tests/kraken/driver.html?testName=audio-beat-detection) 22:28:42 INFO - PROCESS | 3824 | RSS: Main: 157597696 22:28:42 INFO - PROCESS | 3824 | 22:28:45 INFO - PROCESS | 3824 | Cycle 1(1): loaded http://localhost:53766/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53766/tests/kraken/driver.html?testName=audio-dft) 22:28:45 INFO - PROCESS | 3824 | RSS: Main: 155885568 22:28:45 INFO - PROCESS | 3824 | 22:28:48 INFO - PROCESS | 3824 | Cycle 1(1): loaded http://localhost:53766/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53766/tests/kraken/driver.html?testName=audio-fft) 22:28:49 INFO - PROCESS | 3824 | RSS: Main: 156520448 22:28:49 INFO - PROCESS | 3824 | 22:28:51 INFO - PROCESS | 3824 | Cycle 1(1): loaded http://localhost:53766/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53766/tests/kraken/driver.html?testName=audio-oscillator) 22:28:51 INFO - PROCESS | 3824 | RSS: Main: 156475392 22:28:51 INFO - PROCESS | 3824 | 22:28:54 INFO - PROCESS | 3824 | Cycle 1(1): loaded http://localhost:53766/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53766/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:28:54 INFO - PROCESS | 3824 | RSS: Main: 156446720 22:28:54 INFO - PROCESS | 3824 | 22:28:57 INFO - PROCESS | 3824 | Cycle 1(1): loaded http://localhost:53766/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53766/tests/kraken/driver.html?testName=imaging-darkroom) 22:28:58 INFO - PROCESS | 3824 | RSS: Main: 155889664 22:28:58 INFO - PROCESS | 3824 | 22:29:01 INFO - PROCESS | 3824 | Cycle 1(1): loaded http://localhost:53766/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53766/tests/kraken/driver.html?testName=imaging-desaturate) 22:29:02 INFO - PROCESS | 3824 | RSS: Main: 154562560 22:29:02 INFO - PROCESS | 3824 | 22:29:05 INFO - PROCESS | 3824 | Cycle 1(1): loaded http://localhost:53766/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53766/tests/kraken/driver.html?testName=json-parse-financial) 22:29:05 INFO - PROCESS | 3824 | RSS: Main: 156348416 22:29:05 INFO - PROCESS | 3824 | 22:29:07 INFO - PROCESS | 3824 | Cycle 1(1): loaded http://localhost:53766/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53766/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:29:08 INFO - PROCESS | 3824 | RSS: Main: 156487680 22:29:08 INFO - PROCESS | 3824 | 22:29:09 INFO - PROCESS | 3824 | Cycle 1(1): loaded http://localhost:53766/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53766/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:29:10 INFO - PROCESS | 3824 | RSS: Main: 156250112 22:29:10 INFO - PROCESS | 3824 | 22:29:13 INFO - PROCESS | 3824 | Cycle 1(1): loaded http://localhost:53766/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53766/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:29:14 INFO - PROCESS | 3824 | RSS: Main: 157057024 22:29:14 INFO - PROCESS | 3824 | 22:29:16 INFO - PROCESS | 3824 | Cycle 1(1): loaded http://localhost:53766/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53766/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:29:17 INFO - PROCESS | 3824 | RSS: Main: 156368896 22:29:17 INFO - PROCESS | 3824 | 22:29:20 INFO - PROCESS | 3824 | Cycle 1(1): loaded http://localhost:53766/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53766/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:29:20 INFO - PROCESS | 3824 | RSS: Main: 157249536 22:29:20 INFO - PROCESS | 3824 | 22:29:22 INFO - PROCESS | 3824 | Cycle 1(1): loaded http://localhost:53766/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53766/tests/kraken/driver.html?testName=ai-astar) 22:29:22 INFO - PROCESS | 3824 | RSS: Main: 158097408 22:29:22 INFO - PROCESS | 3824 | 22:29:22 INFO - PROCESS | 3824 | __start_tp_report 22:29:22 INFO - PROCESS | 3824 | _x_x_mozilla_page_load 22:29:22 INFO - PROCESS | 3824 | _x_x_mozilla_page_load_details 22:29:22 INFO - PROCESS | 3824 | |i|pagename|runs| 22:29:22 INFO - PROCESS | 3824 | |0;ai-astar;100;113;104;109;111;111;110;110;110;110 22:29:22 INFO - PROCESS | 3824 | |1;audio-beat-detection;152;116;156;169;120;152;141;120;165;115 22:29:22 INFO - PROCESS | 3824 | |2;audio-dft;162;163;163;161;163;162;162;161;162;160 22:29:22 INFO - PROCESS | 3824 | |3;audio-fft;78;74;83;186;77;76;74;74;163;75 22:29:22 INFO - PROCESS | 3824 | |4;audio-oscillator;83;83;139;84;85;84;138;84;84;144 22:29:22 INFO - PROCESS | 3824 | |5;imaging-gaussian-blur;117;117;118;117;118;118;118;118;118;118 22:29:22 INFO - PROCESS | 3824 | |6;imaging-darkroom;147;148;148;147;147;147;146;147;147;149 22:29:22 INFO - PROCESS | 3824 | |7;imaging-desaturate;96;96;97;96;96;96;96;97;95;96 22:29:22 INFO - PROCESS | 3824 | |8;json-parse-financial;56;53;52;53;55;53;51;51;51;54 22:29:22 INFO - PROCESS | 3824 | |9;json-stringify-tinderbox;61;59;59;59;60;59;60;59;59;59 22:29:22 INFO - PROCESS | 3824 | |10;stanford-crypto-aes;76;73;74;71;73;72;73;73;73;72 22:29:22 INFO - PROCESS | 3824 | |11;stanford-crypto-ccm;127;129;121;119;146;132;122;127;149;131 22:29:22 INFO - PROCESS | 3824 | |12;stanford-crypto-pbkdf2;156;148;160;154;148;161;156;149;154;150 22:29:22 INFO - PROCESS | 3824 | |13;stanford-crypto-sha256-iterative;62;62;62;63;62;62;62;62;63;62 22:29:22 INFO - PROCESS | 3824 | __end_tp_report 22:29:22 INFO - PROCESS | 3824 | __start_cc_report 22:29:22 INFO - PROCESS | 3824 | _x_x_mozilla_cycle_collect,842 22:29:22 INFO - PROCESS | 3824 | __end_cc_report 22:29:22 INFO - PROCESS | 3824 | __startTimestamp1473226162622__endTimestamp 22:29:22 INFO - PROCESS | 3824 | 22:29:22 INFO - PROCESS | 3824 | ------- Summary: start ------- 22:29:22 INFO - PROCESS | 3824 | Number of tests: 14 22:29:22 INFO - PROCESS | 3824 | 22:29:22 INFO - PROCESS | 3824 | [#0] ai-astar Cycles:10 Average:108.80 Median:110.00 stddev:3.85 (3.5%) stddev-sans-first:2.44 22:29:22 INFO - PROCESS | 3824 | Values: 100.0 113.0 104.0 109.0 111.0 111.0 110.0 110.0 110.0 110.0 22:29:22 INFO - PROCESS | 3824 | 22:29:22 INFO - PROCESS | 3824 | [#1] audio-beat-detection Cycles:10 Average:140.60 Median:152.00 stddev:21.10 (13.9%) stddev-sans-first:21.98 22:29:22 INFO - PROCESS | 3824 | Values: 152.0 116.0 156.0 169.0 120.0 152.0 141.0 120.0 165.0 115.0 22:29:22 INFO - PROCESS | 3824 | 22:29:22 INFO - PROCESS | 3824 | [#2] audio-dft Cycles:10 Average:161.90 Median:162.00 stddev:0.99 (0.6%) stddev-sans-first:1.05 22:29:22 INFO - PROCESS | 3824 | Values: 162.0 163.0 163.0 161.0 163.0 162.0 162.0 161.0 162.0 160.0 22:29:22 INFO - PROCESS | 3824 | 22:29:22 INFO - PROCESS | 3824 | [#3] audio-fft Cycles:10 Average:96.00 Median:75.50 stddev:41.81 (55.4%) stddev-sans-first:43.84 22:29:22 INFO - PROCESS | 3824 | Values: 78.0 74.0 83.0 186.0 77.0 76.0 74.0 74.0 163.0 75.0 22:29:22 INFO - PROCESS | 3824 | 22:29:22 INFO - PROCESS | 3824 | [#4] audio-oscillator Cycles:10 Average:100.80 Median:84.00 stddev:27.33 (32.5%) stddev-sans-first:28.22 22:29:22 INFO - PROCESS | 3824 | Values: 83.0 83.0 139.0 84.0 85.0 84.0 138.0 84.0 84.0 144.0 22:29:22 INFO - PROCESS | 3824 | 22:29:22 INFO - PROCESS | 3824 | [#5] imaging-gaussian-blur Cycles:10 Average:117.70 Median:118.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 22:29:22 INFO - PROCESS | 3824 | Values: 117.0 117.0 118.0 117.0 118.0 118.0 118.0 118.0 118.0 118.0 22:29:22 INFO - PROCESS | 3824 | 22:29:22 INFO - PROCESS | 3824 | [#6] imaging-darkroom Cycles:10 Average:147.30 Median:147.00 stddev:0.82 (0.6%) stddev-sans-first:0.87 22:29:22 INFO - PROCESS | 3824 | Values: 147.0 148.0 148.0 147.0 147.0 147.0 146.0 147.0 147.0 149.0 22:29:22 INFO - PROCESS | 3824 | 22:29:22 INFO - PROCESS | 3824 | [#7] imaging-desaturate Cycles:10 Average:96.10 Median:96.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 22:29:22 INFO - PROCESS | 3824 | Values: 96.0 96.0 97.0 96.0 96.0 96.0 96.0 97.0 95.0 96.0 22:29:22 INFO - PROCESS | 3824 | 22:29:22 INFO - PROCESS | 3824 | [#8] json-parse-financial Cycles:10 Average:52.90 Median:53.00 stddev:1.73 (3.3%) stddev-sans-first:1.42 22:29:22 INFO - PROCESS | 3824 | Values: 56.0 53.0 52.0 53.0 55.0 53.0 51.0 51.0 51.0 54.0 22:29:22 INFO - PROCESS | 3824 | 22:29:22 INFO - PROCESS | 3824 | [#9] json-stringify-tinderbox Cycles:10 Average:59.40 Median:59.00 stddev:0.70 (1.2%) stddev-sans-first:0.44 22:29:22 INFO - PROCESS | 3824 | Values: 61.0 59.0 59.0 59.0 60.0 59.0 60.0 59.0 59.0 59.0 22:29:22 INFO - PROCESS | 3824 | 22:29:22 INFO - PROCESS | 3824 | [#10] stanford-crypto-aes Cycles:10 Average:73.00 Median:73.00 stddev:1.33 (1.8%) stddev-sans-first:0.87 22:29:22 INFO - PROCESS | 3824 | Values: 76.0 73.0 74.0 71.0 73.0 72.0 73.0 73.0 73.0 72.0 22:29:22 INFO - PROCESS | 3824 | 22:29:22 INFO - PROCESS | 3824 | [#11] stanford-crypto-ccm Cycles:10 Average:130.30 Median:130.00 stddev:10.03 (7.7%) stddev-sans-first:10.57 22:29:22 INFO - PROCESS | 3824 | Values: 127.0 129.0 121.0 119.0 146.0 132.0 122.0 127.0 149.0 131.0 22:29:22 INFO - PROCESS | 3824 | 22:29:22 INFO - PROCESS | 3824 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.60 Median:155.00 stddev:4.77 (3.1%) stddev-sans-first:4.97 22:29:22 INFO - PROCESS | 3824 | Values: 156.0 148.0 160.0 154.0 148.0 161.0 156.0 149.0 154.0 150.0 22:29:22 INFO - PROCESS | 3824 | 22:29:22 INFO - PROCESS | 3824 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.20 Median:62.00 stddev:0.42 (0.7%) stddev-sans-first:0.44 22:29:22 INFO - PROCESS | 3824 | Values: 62.0 62.0 62.0 63.0 62.0 62.0 62.0 62.0 63.0 62.0 22:29:22 INFO - PROCESS | 3824 | -------- Summary: end -------- 22:29:22 INFO - PROCESS | 3824 | 22:29:23 INFO - TEST-INFO | 3824: exit 0 22:29:23 INFO - TEST-OK | kraken | took 62311ms 22:29:23 INFO - SUITE-END | took 1175s 22:29:23 INFO - Completed test suite (00:19:35) 22:29:23 INFO - javascript benchmark 22:29:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7710.32697525734, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3327.8169700666467, 3348.8493017814203, 3364.108511244522, 3371.106656735964, 3395.0324157615087, 5842.79550215742, 5864.912026319608, 5898.675572843492, 5917.733701983416, 5950.137230101633, 5775.162601422801, 5840.591158618888, 5872.941270587293, 5878.059510478324, 5887.793927212549, 5308.787648494055, 5360.91958620621, 5366.70483123428, 5393.9190912136155, 5413.160960051198, 5211.114110601206, 5231.215317702337, 5257.86855328616, 5296.046711591912, 5299.60252981026, 5335.573154147659, 5340.67955922644, 5346.358436987567, 5382.165764306519, 5388.380336261322, 5364.892702145955, 5371.919421208685, 5381.112116500773, 5386.084365657847, 5402.486763757476, 5104.0, 5172.948270517284, 5182.585393168575, 5284.10170271051, 5329.973350133263, 5163.3545806774155, 5597.384287728347, 5658.490735833753, 5744.0, 5763.769449222026, 6128.3565225651555, 6161.9691901540655, 6162.414570615784, 6165.599236049642, 6176.042713379388, 6197.845053873689, 6200.937990620081, 6201.542642474594, 6202.472789812871, 6208.689565521706, 5581.6930068846195, 5715.342735585419, 5745.856353591152, 5773.480386765212, 5924.644521328734, 6338.760820544463, 6384.808455746335, 6386.840328991812, 6387.361263873576, 6409.647469389229, 6156.538259630456, 6185.165002724664, 6188.938110618837, 6189.721462534197, 6198.783042593487, 5954.970225148846, 6124.38756124384, 6157.6613286269685, 6175.938240617626, 6285.214348206474, 6223.439890409885, 6235.75056997715, 6262.655553944577, 6263.467605253604, 6277.96861015692, 6229.283632382243, 6236.158118654014, 6247.718852651642, 6266.122742816009, 6277.2153480814895, 4590.150822097922, 4632.073585282957, 4679.017406344706, 4696.225122854691, 4753.096911586787, 4156.646685031746, 4180.665546756252, 4186.7278626889765, 4196.559361267084, 4212.283911735012, 5862.941370586324, 5887.646741195542, 5896.734646940898, 5897.852553686106, 5983.551233657493, 5986.132010858489, 6135.355158799479, 6143.450468055707, 6164.044573091178, 6337.207849018872, 6034.668093254913, 6048.4858787003595, 6060.939390606126, 6063.8484037898525, 6093.969530152321, 5184.092783762826, 5206.297149884717, 5209.244659524347, 5219.556337711337, 5225.163973764255, 6235.189425374763, 6245.250569931637, 6303.086052522359, 6309.0, 6472.061551075162, 6184.257889053342, 6200.627962322275, 6211.378862113933, 6219.284782250099, 6228.532860035425, 6478.060681201294, 6483.870322593616, 6487.091807147098, 6489.448396886318, 6494.675266236574, 5883.470487656132, 5969.403059693996, 6006.729697163725, 6046.5767396281835, 6115.453310713017, 6261.78083767066, 6275.937240627718, 6282.057691346335, 6282.371762823681, 6307.558470907176, 6121.296050954019, 6127.805078009746, 6131.8467038322615, 6137.772445511005, 6157.353477884937, 5926.259217597801, 6021.397860213943, 6046.818595442144, 6066.848328791815, 6142.201513803356, 6072.240969878765, 6079.361667025076, 6081.422264884829, 6092.725827337692, 6104.633721976696, 6090.23872015998, 6094.573379863545, 6167.84580385494, 6169.84575385619, 6184.474319682878, 5788.305403351625, 5864.003119469786, 5988.4909782668965, 5989.40105989415, 6117.388261173847], "value": 5724.692970040365, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [597.7160848596917, 630.5145038320493, 649.5550547874703, 650.3074225949364, 698.6122701900443, 41296.38055429171, 44621.776891115536, 45040.32439513402, 45337.31994020093, 45430.54569454304, 46777.0, 47332.0, 47396.52603473973, 47464.525354746445, 47511.52488475114, 8879.422837515573, 9015.729528114167, 9035.412698174598, 9052.230560402373, 9077.95461022694, 8992.145746154105, 9026.826512553393, 9033.864492032624, 9082.273418126533, 9089.68186113486, 9103.726888193365, 9134.0, 9138.543072846363, 9158.954205228998, 9163.450192988408, 9062.365634405594, 9108.18026377626, 9186.494742789144, 9190.540472976325, 9193.724188274362, 9081.682141125095, 9102.317326200495, 9119.908800912039, 9151.313651476099, 9180.770480738034, 42574.78712606417, 42808.3578746319, 43029.56970430318, 43052.138957220996, 43188.784056079516, 3788.90527736806, 3843.846246150151, 3932.8623498177426, 4244.236037513247, 4521.706089104197, 33962.1509462263, 33990.32019359599, 34211.657883421096, 34236.315273694636, 35918.281634367166, 3849.7305188636806, 3905.1018265798743, 3912.882613521599, 3936.586658400884, 3939.074317535377, 357714.0, 361694.0, 363508.0, 364047.17976410215, 364228.0, 35661.8216908919, 36595.26809463849, 56089.0, 56461.0, 56860.43139568551, 38852.80573597114, 38898.22203555913, 39106.413403798964, 39232.0, 39350.21299573992, 15114.168720720467, 15242.085474871321, 15281.38874445032, 15293.311800968982, 15312.387504499919, 12113.576024839089, 12197.39013049344, 12197.817032744517, 12200.206986545845, 12308.81536776949, 9048.50233237165, 9060.95469522648, 9067.0, 9146.2225710814, 9209.447433153899, 3502.614712381636, 3577.928441431157, 3583.1221350768706, 3614.8373323200517, 3634.182308980511, 988.5847943863595, 1009.3540134314045, 1104.9226554141294, 1109.7946879827257, 1153.798085335063, 34656.96392960855, 35216.0, 35247.29505409929, 35542.822285888404, 35547.93356199318, 1107.5901916291025, 1127.3686735428023, 1129.412705393191, 1133.0652211925194, 1144.719543711796, 18237.27050917975, 18318.267269309345, 18346.724799128024, 18374.908125459286, 18546.44360669155, 619421.0, 625788.0, 628073.0, 628846.0, 630203.0, 66214.0, 66716.33283667198, 66762.33237667561, 67024.6648766753, 67152.66423667851, 1361.3805718398303, 1369.4043091255137, 1379.558541266785, 1384.9099808512415, 1392.5752645443035, 32769.180770480925, 33788.32423351568, 34016.65983340135, 34219.14452138716, 34300.31399371949, 56709.149362760414, 56859.14711279417, 57311.426885732275, 57365.0, 57432.42567574438, 363218.18390908936, 364654.0, 367912.0, 368905.0, 369463.0, 31628.367432651678, 31655.683443166196, 32042.51936220912, 32154.678453216104, 32241.838790805898, 53242.0, 53405.0, 53471.732641336544, 53872.83351884292, 54320.18519722128, 43639.56360436355, 48324.7583762093, 49354.50645493499, 50050.0, 50680.0, 46047.0, 46675.53324466712, 46701.0, 46784.53215467802, 47378.76310618425, 39102.413463798635, 44636.55363446324, 44726.329105062796, 45198.54801451943, 47166.52833471621, 39779.60220397759, 39876.60123398729, 40594.59405405908, 40730.79634601808, 41169.38245926373, 30872.382552349278, 30962.0, 31357.52963705592, 31365.024942316148, 31451.213719657193], "value": 19897.734485116693, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [406.8820042187764, 412.5358971157448, 418.9204051230266, 420.6319470463346, 427.17555118621067, 20024.89987550064, 20862.269820556285, 21590.352289431303, 21623.891880540614, 21801.781982180175, 20455.0, 20589.485262868435, 20828.375148745523, 20978.265760698378, 21093.472663183427, 5719.542436605062, 5849.590528662995, 5919.0, 5942.049272116462, 5945.0, 5787.421257874221, 5795.043817770063, 5851.736671849756, 5880.735366908478, 5888.676122813244, 5664.603477756558, 5777.133429985514, 5802.506786923117, 5823.825285241428, 5836.766529338822, 5678.318601767773, 5758.42415758425, 5761.711914404284, 5773.682447465388, 5816.185733997244, 5221.530062294411, 5317.813876514342, 5332.33345831771, 5356.9732151339385, 5360.91958620621, 24408.14571490007, 24479.0, 24642.876785616136, 24654.260372188863, 25260.242192734248, 2443.8900249488815, 2756.3444984612647, 2806.5228911085164, 3204.567383403234, 3263.282077942849, 15411.306491207923, 15450.922745386313, 15466.0, 15681.921590392089, 15731.52805415828, 2041.0, 2041.4488088216099, 2082.750069991611, 2108.8945552722457, 2133.1040962795664, 80459.0, 81386.37227255481, 83259.58370208171, 85183.0, 85814.0, 11281.830772538418, 11456.942715286537, 11509.309441433541, 11606.187566870238, 11650.126240531994, 16249.106299153424, 16401.1799410029, 16405.91797041031, 16437.506874793777, 16464.176791160393, 7550.735724249625, 7621.69513219465, 8111.675532978733, 8258.8348233035, 8259.050209225896, 7592.0, 7634.465587408939, 7637.274458926393, 7766.37868970481, 7770.339521140653, 4485.529019452975, 4531.071130418322, 4609.262517997105, 4741.454732705783, 5023.79904803811, 1918.1655979648897, 2078.4596005038798, 2138.0806253311225, 2161.124744478457, 2164.1343462614764, 900.8873890763655, 962.259060523393, 975.8341082016075, 1020.2093377632245, 1059.5391004912888, 20829.270975515785, 21069.367918962456, 21102.2614208502, 21202.0, 21400.143994240367, 972.8297547929084, 993.438707130469, 1039.0336986602533, 1064.2390690656064, 1067.4662668665667, 11091.778164436666, 11300.547978080785, 11581.826272605918, 11666.708332291591, 11924.224925379822, 468680.0, 479184.0, 531913.0, 540221.0, 541949.0, 64206.6789666058, 93029.0, 110926.0, 111810.0, 112378.43810780894, 977.6969139566758, 1018.8777346718441, 1053.5311786255043, 1110.2838669058413, 1112.7273817914663, 17949.37177198844, 18336.449906502825, 18626.0, 18715.719264210777, 18989.05054747285, 43567.34648980331, 44611.77694111509, 44948.73288813891, 45247.547524524336, 45473.0, 57602.0, 58073.0, 63368.36631633624, 63555.36444635679, 65384.0, 8828.602712878064, 9091.863622045807, 9241.353105282566, 9263.490508022123, 9344.626214951326, 9242.353035287735, 13210.141340813201, 13836.515721950087, 13974.79037814454, 13981.091229070078, 12265.938670306949, 12703.110782245154, 12778.977681785249, 12806.103572749818, 13107.213567186, 12537.122401431812, 12648.367581621067, 12660.493580257054, 12743.426545805647, 12850.485980560672, 12593.748125037264, 12665.0, 12723.681907952376, 12758.463309124043, 13056.21662700241, 9048.773780655536, 9170.862437063315, 9251.583678734338, 9324.860127098234, 9428.764280893032, 11989.460474278503, 12072.517099316281, 12163.939180304396, 12455.626331210078, 12611.243325400505], "value": 9778.903965864238, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1583.0343490470812, 1620.2789758557442, 1788.4008857032893, 1839.1631807527576, 1873.2319748902953, 8493.957530212356, 9211.309151813615, 9222.677206297782, 9980.800383992315, 10002.0, 9357.298202634796, 9659.80680386392, 9669.081437263449, 9710.320277580573, 9713.757156071102, 2459.0409740201335, 2468.2348471973746, 2475.1584461283114, 2488.042103790036, 2503.9374015649573, 2441.5361081394476, 2545.0583284184877, 2547.350425641468, 2559.897604095834, 2561.2828408045775, 2536.4039450729065, 2548.4138648110948, 2558.0535201975295, 2566.691996960358, 2575.6909170899517, 2479.6776419065404, 2498.6252062190633, 2506.223070848043, 2515.3963048868322, 2524.798016158721, 2464.913728019511, 2479.82641215115, 2481.85108893467, 2482.379405148713, 2484.1926373928363, 9658.0, 9702.805943881083, 9799.363041402286, 9872.407655540619, 9923.702288931343, 5638.746256418471, 5714.571407144481, 5721.284839395075, 5735.0823868181305, 5773.88452230953, 3432.262063656326, 3531.187826799825, 3583.4624806279007, 3583.6416358364218, 3584.1577229351083, 1202.7594481103727, 1238.8141778733172, 1257.9119461637688, 1259.779538580754, 1272.1730874931277, 13931.930340348335, 13948.721025579533, 13972.0, 13987.650308742363, 14014.0, 5015.3730783652045, 5049.166887463527, 5104.566111880457, 5157.381114266312, 5189.273501709764, 11623.360715160747, 11646.126540509496, 11704.122190835722, 11708.70728231784, 11712.648620541397, 5872.060470324727, 5886.440788125121, 5898.498627616701, 5921.940780592225, 5922.2597175353085, 4532.592066714012, 5274.604404669665, 5331.226972089103, 5422.457754224546, 5444.346678398618, 4387.056782791715, 4441.289393697058, 4473.932891006638, 4474.932876006863, 4504.414426124583, 1396.4553824008644, 1398.9440422383195, 1400.908940918837, 1413.3993052952453, 1433.1830856411746, 1045.9581616735397, 1066.269605320358, 1074.2050882347007, 1075.5321435175724, 1099.0822663076422, 6396.5202609804455, 6659.766908158288, 6721.395074443226, 6785.93214067853, 6865.862682746317, 459.81147729430774, 460.1234647995507, 462.6830621024609, 466.76195140478603, 469.4413647759156, 4513.661475389294, 4523.683342166017, 4558.133954548625, 4595.701279416827, 4619.145458090264, 11693.824592631117, 11957.461914213882, 12081.335526546125, 12105.69735756613, 12118.03055755555, 12091.637250882488, 12143.696407589881, 12162.209456385306, 12164.33096179701, 12178.086643501772, 8596.355273354524, 8954.776130596787, 8972.102789720975, 9188.219001384958, 9207.631694732136, 6567.671616419256, 6654.0, 6777.661116944231, 6889.069975553437, 7015.438764898898, 11143.609973650884, 11311.377874216998, 11326.603568875049, 11496.712582185512, 11497.195196336175, 11492.82760758606, 11521.423928803359, 11578.073754099483, 11581.594644187413, 11604.013658839092, 8473.745787626382, 8809.911900880908, 8878.11218878107, 8884.955575222082, 8901.421407608485, 9357.111074448188, 9451.243900488082, 9487.525623718924, 9540.427574345562, 9559.139677429064, 8221.506709597443, 8416.2004609562, 8457.873131902903, 8486.19381158789, 8570.185832345916, 7955.363570914429, 8008.079070906687, 8057.43597948138, 8090.514569125871, 8311.459755115899, 7454.552726836408, 7467.253274672489, 7510.549367037996, 7531.8117047074265, 7534.886976695464, 5391.083515802401, 5499.780008799763, 5569.387367389665, 5584.441555844383, 5748.741306641295, 5669.971650141723, 5775.306963164448, 5784.768609255582, 5837.0368889132815, 5890.587658864009], "value": 5004.949461122263, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.1001780344399, 348.4858290726792, 349.14111286235857, 355.5537800060923, 357.4905759293007, 5809.854753631162, 5851.648901065933, 5871.119332100182, 5873.676947767871, 5879.470847623713, 5784.033428634263, 5798.478136967672, 5801.941980580193, 5815.534757219423, 5830.7667693292215, 3905.2970465316303, 4408.603225709686, 4513.50351461339, 4538.931916021263, 4558.430196225472, 4548.135854187711, 4560.2019646561885, 4563.931541026887, 4581.5647513486165, 4600.217962946307, 4527.615152712024, 4536.364908912738, 4546.8863278417975, 4549.31760235964, 4559.521250268724, 4233.153369326132, 4432.711873728197, 4493.955060449419, 4526.75102869342, 4541.863744087683, 3959.227950549644, 3963.504561929759, 3964.5044369453817, 3968.285708572456, 3983.342748446503, 5661.60368774186, 5672.68800215988, 5674.574406919498, 5688.573356998241, 5701.116326969326, 4933.876653083666, 5022.27177059328, 5145.3568303962, 5151.4333423323405, 5221.477852214786, 6035.818925432244, 6081.878362432771, 6091.238595175603, 6097.237845269341, 6105.786297479612, 4304.526502084768, 4304.978475107604, 4346.630536404409, 4351.630111440531, 4397.780110994438, 6123.357047510036, 6133.84665383369, 6195.659238741822, 6286.245650521921, 6297.559170858041, 4481.663875209372, 4529.5243999380245, 4535.2765948939195, 4559.840405585788, 4571.0, 4593.196190666687, 4608.170529304757, 4615.676902616851, 4616.792244349012, 4638.628909687217, 4958.826441074545, 5119.769610367543, 5281.970015846873, 5285.577153827685, 5291.99452104099, 6016.548758843104, 6041.486473649701, 6066.817995460143, 6080.361562036011, 6090.390960903874, 1540.8536189062245, 1568.827428982811, 1571.3478906253777, 1572.5518227305392, 1576.1646327446472, 989.302541708094, 1043.6816770884802, 1048.3238311289176, 1049.3598904668147, 1064.1965316186229, 5241.659292145999, 5246.216286462544, 5251.684898906078, 5267.657602255841, 5300.522952934254, 5417.187421886749, 5480.068388373986, 5502.394736578973, 5524.4199359067525, 5551.444855514416, 4972.3894955148835, 5189.42916279209, 5251.658642188246, 5253.658512196695, 5284.894302113936, 2898.739113479755, 2920.8831646734316, 2935.1822250639198, 2957.2015555799812, 2970.7474864636747, 5542.0, 5659.971700141473, 5666.206731057655, 5686.947914635805, 5688.971555142198, 5975.551833612496, 6400.679966001681, 6435.517336199804, 6493.642849643315, 6498.740050397932, 6645.933540664531, 6655.600663960178, 6656.733730650816, 6688.297728738413, 6702.597844129368, 6141.938580614136, 6259.280182778856, 6267.592606480564, 6268.655223962726, 6436.259830119596, 6193.783217587363, 6214.130021796862, 6247.68761561911, 6291.528135389773, 6305.306416294296, 6204.472619827366, 6382.297947225894, 6512.088307637029, 6517.152770139852, 6535.607863528204, 4420.889477763081, 4438.578335058164, 4445.222086134849, 4456.376107345039, 4473.306637471197, 6204.162438070803, 6241.251049874044, 6282.591631543935, 6350.872982540416, 6353.872922541431, 6076.665783381956, 6097.634141951497, 6117.96941015292, 6123.693815309306, 6139.2325959255095, 5888.352281249145, 6125.47933425646, 6161.907571386343, 6191.349908259568, 6207.379262073757, 5972.25346831646, 6004.459598636144, 6012.518998480198, 6021.9397806023135, 6029.668368239789, 6100.23747031621, 6133.355997620364, 6135.693215339126, 6141.662208578571, 6170.568060235741, 5757.683327417032, 5972.880542389041, 5973.1637570741, 6004.618937644454, 6039.214902062704], "value": 4575.283852653384, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [847.8134810341722, 863.5725315968592, 873.6199753107397, 904.9728508144758, 951.5052172870104, 16074.437394691187, 17676.20457079433, 17807.0, 17873.7318940216, 17954.10229488525, 16436.178191090457, 16587.66824663509, 16609.0, 17039.318427262893, 17391.652166956654, 861.7931696392883, 869.9043105258448, 870.4820631724114, 872.9960545372841, 891.6121487153076, 878.3017501086651, 905.316486053029, 910.8269428808505, 913.3332667152982, 915.5239275576696, 956.8469044952809, 962.9518524073803, 964.6961207219739, 965.9130678238957, 969.0793745941348, 951.4291425144927, 954.9952250238739, 955.0401846144649, 955.8709574207533, 956.6030097509525, 702.3854127638316, 703.4126504368858, 703.5391818358931, 705.2911823617283, 707.7805880177161, 16326.346946122141, 16357.100359480224, 16417.42539011129, 16456.42402515918, 16472.42346517878, 14968.401263949429, 15155.393784248618, 15191.468298609496, 15322.310496027705, 15357.616059598487, 13508.0, 13601.523946661919, 13633.0, 13633.795493067611, 13974.161550307013, 4085.836566537365, 4168.270552653274, 4181.811818468176, 4190.476190476191, 4218.8523401680795, 64525.354746452875, 64723.67638161779, 79203.6039819803, 91300.0, 92269.0, 12234.571789987307, 12267.815982760265, 12280.938595306967, 12500.24998500093, 12682.0, 23357.0, 23529.7647023531, 23552.64671029936, 23798.0, 24072.87963560206, 8398.748037558884, 8924.55377231136, 8973.596188171548, 9060.773480662905, 9098.181163695299, 5093.363329583802, 5192.247124166975, 5262.289590905179, 5264.736763161826, 5303.973480132575, 3637.399829028234, 3823.235352929425, 3823.617638236154, 3831.6934645228316, 3836.3861782115287, 641.5733537197805, 650.4991156809325, 656.3961155736733, 667.5927684112687, 675.3651567526509, 642.5823214910346, 661.8940969444963, 663.571996062537, 725.2529894208973, 760.9315161635479, 9253.72238832836, 9643.373180743229, 9665.275104367201, 10163.0, 10465.110465610509, 660.3132741948416, 667.9799606011827, 725.8983742276085, 737.2075019354215, 739.3789217057697, 4586.197415452282, 4700.741459219776, 4759.547842954914, 4918.803247870045, 4925.162722337212, 105069.0, 136537.3173134128, 139019.30490347484, 139057.30471347782, 141130.0, 94056.0, 95342.52328738451, 117696.0, 119483.0, 120595.39702301608, 91652.54173729089, 92539.5373023144, 92773.53613231891, 94150.52924735332, 95509.0, 10560.366378017343, 11017.559297628006, 11391.658250252505, 11440.370779607201, 11649.767004660029, 52616.0, 52906.0, 53010.0, 53324.73337633287, 53755.0, 70913.64543177458, 81161.59419202866, 81777.59111204406, 82867.17132828594, 84499.15500845159, 14171.929140354232, 14435.92782036083, 14795.852041479879, 14862.479813206486, 14898.851011489747, 15798.842011579736, 16188.791148545846, 16607.3357065716, 17124.571885702957, 17928.372506962194, 14683.853161468676, 15345.693086138437, 16034.198290085682, 16062.277197525906, 16685.249163787415, 14095.506657266946, 14444.205568693822, 14789.260536973323, 15738.842611573738, 15770.0, 12615.747685046432, 13405.0, 14685.41258349654, 15475.613109672347, 15813.525594232191, 11821.0, 11889.346085965355, 12914.612561623166, 12957.546485872948, 13309.866901330863, 11417.486213120595, 11430.542778289107, 11690.357030363411, 11900.702482438008, 12103.0], "value": 8236.343791115018, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1500.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 113.0, 104.0, 109.0, 111.0, 111.0, 110.0, 110.0, 110.0, 110.0], "value": 108.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 116.0, 156.0, 169.0, 120.0, 152.0, 141.0, 120.0, 165.0, 115.0], "value": 140.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 163.0, 163.0, 161.0, 163.0, 162.0, 162.0, 161.0, 162.0, 160.0], "value": 161.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 74.0, 83.0, 186.0, 77.0, 76.0, 74.0, 74.0, 163.0, 75.0], "value": 96.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 139.0, 84.0, 85.0, 84.0, 138.0, 84.0, 84.0, 144.0], "value": 100.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 118.0, 117.0, 118.0, 118.0, 118.0, 118.0, 118.0, 118.0], "value": 117.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 148.0, 147.0, 147.0, 147.0, 146.0, 147.0, 147.0, 149.0], "value": 147.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 97.0, 96.0, 96.0, 96.0, 96.0, 97.0, 95.0, 96.0], "value": 96.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 53.0, 52.0, 53.0, 55.0, 53.0, 51.0, 51.0, 51.0, 54.0], "value": 52.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 59.0, 59.0, 59.0, 60.0, 59.0, 60.0, 59.0, 59.0, 59.0], "value": 59.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 73.0, 74.0, 71.0, 73.0, 72.0, 73.0, 73.0, 73.0, 72.0], "value": 73.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 129.0, 121.0, 119.0, 146.0, 132.0, 122.0, 127.0, 149.0, 131.0], "value": 130.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 148.0, 160.0, 154.0, 148.0, 161.0, 156.0, 149.0, 154.0, 150.0], "value": 153.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 62.0, 63.0, 62.0, 62.0, 62.0, 62.0, 63.0, 62.0], "value": 62.2, "unit": "score"}]}]} 22:29:23 INFO - Return code: 0 22:29:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:29:23 ERROR - # TBPL SUCCESS # 22:29:23 INFO - Running post-action listener: _resource_record_post_action 22:29:23 INFO - [mozharness: 2016-09-07 05:29:23.926537Z] Finished run-tests step (success) 22:29:23 INFO - Running post-run listener: _resource_record_post_run 22:29:23 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 7942144; Write bytes: 456278016; Read time: 2428; Write time: 604860 22:29:23 INFO - TinderboxPrint: CPU usage
13.3% 22:29:23 INFO - TinderboxPrint: I/O read bytes / time
7,942,144 / 2,428 22:29:23 INFO - TinderboxPrint: I/O write bytes / time
456,278,016 / 604,860 22:29:23 INFO - TinderboxPrint: CPU idle
8,254.6 (86.7%) 22:29:23 INFO - TinderboxPrint: CPU user
1,200.9 (12.6%) 22:29:23 INFO - TinderboxPrint: Swap in / out
0 / 0 22:29:23 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 116125696; Read time: 0; Write time: 99224 22:29:24 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 5783552; Write bytes: 294014976; Read time: 1648; Write time: 422504 22:29:24 INFO - Running post-run listener: _upload_blobber_files 22:29:24 INFO - Blob upload gear active. 22:29:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:29:24 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 22:29:24 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'] 22:29:24 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 22:29:25 INFO - (blobuploader) - INFO - Open directory for files ... 22:29:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:29:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:29:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:29:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:29:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:29:25 INFO - (blobuploader) - INFO - Done attempting. 22:29:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:29:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:29:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:29:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:29:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:29:29 INFO - (blobuploader) - INFO - Done attempting. 22:29:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:29:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:29:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:29:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:29:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:29:30 INFO - (blobuploader) - INFO - Done attempting. 22:29:30 INFO - (blobuploader) - INFO - Iteration through files over. 22:29:30 INFO - Return code: 0 22:29:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:29:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:29:30 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c04bd9cdd8796afaaf9017481f43114fa0e43dacc38e4471f0b52b12f6ef99940d988f3edca2ffed0702365336238083f43d4eeeeeffc1adfa8385fe8f217b48", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b240b028ccbf12646dab5976981a101317aacbcbb11c39132e07857bcd89408ffb1f4b8710374d5adc649393961c78a330c45302676e1d3121da99c0cfff2870", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5ebb3d55454c6aa9453cc26e95fe1b70b4c84fc7b2247e0fcf053dce2935006f8442119f5d50697dadb23b604a8e9b669036aff0d616a5800a51f58011cb50fc"} 22:29:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:29:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:29:30 INFO - Contents: 22:29:30 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c04bd9cdd8796afaaf9017481f43114fa0e43dacc38e4471f0b52b12f6ef99940d988f3edca2ffed0702365336238083f43d4eeeeeffc1adfa8385fe8f217b48", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b240b028ccbf12646dab5976981a101317aacbcbb11c39132e07857bcd89408ffb1f4b8710374d5adc649393961c78a330c45302676e1d3121da99c0cfff2870", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5ebb3d55454c6aa9453cc26e95fe1b70b4c84fc7b2247e0fcf053dce2935006f8442119f5d50697dadb23b604a8e9b669036aff0d616a5800a51f58011cb50fc"} 22:29:30 INFO - Running post-run listener: copy_logs_to_upload_dir 22:29:30 INFO - Copying logs to upload dir... 22:29:30 INFO - mkdir: /builds/slave/test/build/upload/logs 22:29:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1686.653397 ========= master_lag: -0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 6 secs) (at 2016-09-06 22:29:30.411443) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-06 22:29:30.415445) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1473223835.34884-1500199462 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c04bd9cdd8796afaaf9017481f43114fa0e43dacc38e4471f0b52b12f6ef99940d988f3edca2ffed0702365336238083f43d4eeeeeffc1adfa8385fe8f217b48", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b240b028ccbf12646dab5976981a101317aacbcbb11c39132e07857bcd89408ffb1f4b8710374d5adc649393961c78a330c45302676e1d3121da99c0cfff2870", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5ebb3d55454c6aa9453cc26e95fe1b70b4c84fc7b2247e0fcf053dce2935006f8442119f5d50697dadb23b604a8e9b669036aff0d616a5800a51f58011cb50fc"} build_url:https://queue.taskcluster.net/v1/task/aySZQqZqQiqyUKJ8L1hjAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011325 build_url: 'https://queue.taskcluster.net/v1/task/aySZQqZqQiqyUKJ8L1hjAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c04bd9cdd8796afaaf9017481f43114fa0e43dacc38e4471f0b52b12f6ef99940d988f3edca2ffed0702365336238083f43d4eeeeeffc1adfa8385fe8f217b48", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b240b028ccbf12646dab5976981a101317aacbcbb11c39132e07857bcd89408ffb1f4b8710374d5adc649393961c78a330c45302676e1d3121da99c0cfff2870", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5ebb3d55454c6aa9453cc26e95fe1b70b4c84fc7b2247e0fcf053dce2935006f8442119f5d50697dadb23b604a8e9b669036aff0d616a5800a51f58011cb50fc"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-06 22:29:30.440630) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 22:29:30.440989) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1473223835.34884-1500199462 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004372 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 22:29:30.527869) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-06 22:29:30.528294) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-06 22:29:30.528742) ========= ========= Total master_lag: 0.31 =========