builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-054 starttime: 1478388000.88 results: success (0) buildid: 20161103181821 builduid: d984d1bb7c314f73889b19df79c575c9 revision: 24b8f08f77565f859898b45f62d2132ccc64c6d8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-05 16:20:00.876896) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-05 16:20:00.877374) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-05 16:20:00.877675) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-05 16:20:00.892301) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-05 16:20:00.892623) ========= 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=cbe409c6de289db6ac707de3000001d4-1478388000.885512-1859117507 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-11-05 16:20:01-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 42.9M=0s 2016-11-05 16:20:01 (42.9 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.091733 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-05 16:20:00.998034) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-05 16:20:00.998359) ========= 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=cbe409c6de289db6ac707de3000001d4-1478388000.885512-1859117507 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018213 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-05 16:20:01.048034) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-05 16:20:01.048423) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 24b8f08f77565f859898b45f62d2132ccc64c6d8 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 24b8f08f77565f859898b45f62d2132ccc64c6d8 --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=cbe409c6de289db6ac707de3000001d4-1478388000.885512-1859117507 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-11-05 16:20:01,773 truncating revision to first 12 chars 2016-11-05 16:20:01,773 Setting DEBUG logging. 2016-11-05 16:20:01,773 attempt 1/10 2016-11-05 16:20:01,773 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/24b8f08f7756?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-05 16:20:02,609 unpacking tar archive at: mozilla-beta-24b8f08f7756/testing/mozharness/ program finished with exit code 0 elapsedTime=1.170016 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-05 16:20:02.231807) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-05 16:20:02.232169) ========= script_repo_revision: 24b8f08f77565f859898b45f62d2132ccc64c6d8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-05 16:20:02.232556) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-05 16:20:02.232822) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-05 16:20:02.241835) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 54 secs) (at 2016-11-05 16:20:02.242148) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=cbe409c6de289db6ac707de3000001d4-1478388000.885512-1859117507 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:20:03 INFO - MultiFileLogger online at 20161105 16:20:03 in /builds/slave/test 16:20:03 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 16:20:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:20:03 INFO - {'append_to_log': False, 16:20:03 INFO - 'base_work_dir': '/builds/slave/test', 16:20:03 INFO - 'blob_upload_branch': 'Mozilla-Beta', 16:20:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:20:03 INFO - 'branch': 'Mozilla-Beta', 16:20:03 INFO - 'buildbot_json_path': 'buildprops.json', 16:20:03 INFO - 'config_files': ('talos/linux_config.py',), 16:20:03 INFO - 'default_actions': ('clobber', 16:20:03 INFO - 'read-buildbot-config', 16:20:03 INFO - 'download-and-extract', 16:20:03 INFO - 'populate-webroot', 16:20:03 INFO - 'create-virtualenv', 16:20:03 INFO - 'install', 16:20:03 INFO - 'run-tests'), 16:20:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:20:03 INFO - 'download_minidump_stackwalk': True, 16:20:03 INFO - 'download_symbols': 'ondemand', 16:20:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:20:03 INFO - 'tooltool.py': '/tools/tooltool.py', 16:20:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:20:03 INFO - '/tools/misc-python/virtualenv.py')}, 16:20:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:20:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:20:03 INFO - 'installer_path': 'installer.exe', 16:20:03 INFO - 'log_level': 'info', 16:20:03 INFO - 'log_name': 'talos', 16:20:03 INFO - 'log_to_console': True, 16:20:03 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:20:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:20:03 INFO - 'opt_config_files': (), 16:20:03 INFO - 'pip_index': False, 16:20:03 INFO - 'sps_profile': False, 16:20:03 INFO - 'sps_profile_interval': 0, 16:20:03 INFO - 'suite': 'dromaeojs', 16:20:03 INFO - 'system_bits': '32', 16:20:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:20:03 INFO - 'title': 'talos-linux64-ix-054', 16:20:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:20:03 INFO - 'use_talos_json': True, 16:20:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:20:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:20:03 INFO - 'work_dir': 'build'} 16:20:03 INFO - [mozharness: 2016-11-05 23:20:03.069810Z] Running clobber step. 16:20:03 INFO - Running pre-action listener: _resource_record_pre_action 16:20:03 INFO - Running main action method: clobber 16:20:03 INFO - rmtree: /builds/slave/test/build 16:20:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:20:03 INFO - Running post-action listener: _resource_record_post_action 16:20:03 INFO - [mozharness: 2016-11-05 23:20:03.529045Z] Finished clobber step (success) 16:20:03 INFO - [mozharness: 2016-11-05 23:20:03.529146Z] Running read-buildbot-config step. 16:20:03 INFO - Running pre-action listener: _resource_record_pre_action 16:20:03 INFO - Running main action method: read_buildbot_config 16:20:03 INFO - Using buildbot properties: 16:20:03 INFO - { 16:20:03 INFO - "project": "", 16:20:03 INFO - "product": "firefox", 16:20:03 INFO - "script_repo_revision": "production", 16:20:03 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 16:20:03 INFO - "repository": "", 16:20:03 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 16:20:03 INFO - "buildid": "20161103181821", 16:20:03 INFO - "pgo_build": "True", 16:20:03 INFO - "basedir": "/builds/slave/test", 16:20:03 INFO - "buildnumber": 2, 16:20:03 INFO - "slavename": "talos-linux64-ix-054", 16:20:03 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 16:20:03 INFO - "platform": "ubuntu64_hw", 16:20:03 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 16:20:03 INFO - "branch": "mozilla-beta", 16:20:03 INFO - "repo_path": "releases/mozilla-beta", 16:20:03 INFO - "revision": "24b8f08f77565f859898b45f62d2132ccc64c6d8", 16:20:03 INFO - "stage_platform": "linux64-pgo", 16:20:03 INFO - "builduid": "d984d1bb7c314f73889b19df79c575c9", 16:20:03 INFO - "slavebuilddir": "test" 16:20:03 INFO - } 16:20:03 INFO - Found installer url https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 16:20:03 INFO - Running post-action listener: _resource_record_post_action 16:20:03 INFO - [mozharness: 2016-11-05 23:20:03.531576Z] Finished read-buildbot-config step (success) 16:20:03 INFO - [mozharness: 2016-11-05 23:20:03.531665Z] Running download-and-extract step. 16:20:03 INFO - Running pre-action listener: _resource_record_pre_action 16:20:03 INFO - Running main action method: download_and_extract 16:20:03 INFO - mkdir: /builds/slave/test/build/tests 16:20:03 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')]} 16:20:03 INFO - https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 16:20:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 16:20:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 16:20:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 16:20:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 16:20:03 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 16:20:03 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 16:20:03 INFO - retry: Failed, sleeping 30 seconds before retrying 16:20:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #2 16:20:33 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 16:20:33 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 16:20:33 INFO - retry: Failed, sleeping 60 seconds before retrying 16:21:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #3 16:21:33 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 16:21:33 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 16:21:33 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json! 16:21:33 INFO - Caught exception: HTTP Error 404: Not Found 16:21:33 INFO - Caught exception: HTTP Error 404: Not Found 16:21:33 INFO - Caught exception: HTTP Error 404: Not Found 16:21:33 INFO - trying https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 16:21:33 INFO - Downloading https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 16:21:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 16:21:34 INFO - Downloaded 1327 bytes. 16:21:34 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 16:21:34 INFO - Using the following test package requirements: 16:21:34 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 16:21:34 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:21:34 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 16:21:34 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:21:34 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 16:21:34 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:21:34 INFO - u'jsshell-linux-x86_64.zip'], 16:21:34 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:21:34 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 16:21:34 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 16:21:34 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:21:34 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 16:21:34 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:21:34 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 16:21:34 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:21:34 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 16:21:34 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:21:34 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 16:21:34 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:21:34 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')]} 16:21:34 INFO - https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:21:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:21:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:21:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:21:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:21:34 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:21:34 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 16:21:34 INFO - retry: Failed, sleeping 30 seconds before retrying 16:22:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #2 16:22:04 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:22:04 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 16:22:04 INFO - retry: Failed, sleeping 60 seconds before retrying 16:23:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #3 16:23:04 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:23:04 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 16:23:04 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip! 16:23:04 INFO - Caught exception: HTTP Error 404: Not Found 16:23:04 INFO - Caught exception: HTTP Error 404: Not Found 16:23:04 INFO - Caught exception: HTTP Error 404: Not Found 16:23:04 INFO - trying https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:23:04 INFO - Downloading https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:23:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:23:05 INFO - Downloaded 21787606 bytes. 16:23:05 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:23:05 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 16:23:06 INFO - Return code: 0 16:23:06 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 16:23:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:23:06 INFO - https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:23:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:23:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:23:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:23:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:23:06 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:23:06 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 16:23:06 INFO - retry: Failed, sleeping 30 seconds before retrying 16:23:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #2 16:23:36 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:23:36 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 16:23:36 INFO - retry: Failed, sleeping 60 seconds before retrying 16:24:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #3 16:24:36 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:24:36 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 16:24:36 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip! 16:24:36 INFO - Caught exception: HTTP Error 404: Not Found 16:24:36 INFO - Caught exception: HTTP Error 404: Not Found 16:24:36 INFO - Caught exception: HTTP Error 404: Not Found 16:24:36 INFO - trying https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:24:36 INFO - Downloading https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:24:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:24:37 INFO - Downloaded 21787606 bytes. 16:24:37 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:24:37 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 16:24:38 INFO - Return code: 0 16:24:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:24:38 INFO - https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 16:24:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 16:24:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 16:24:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 16:24:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 16:24:38 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 16:24:38 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 16:24:38 INFO - retry: Failed, sleeping 30 seconds before retrying 16:25:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 16:25:08 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 16:25:08 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 16:25:08 INFO - retry: Failed, sleeping 60 seconds before retrying 16:26:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 16:26:08 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 16:26:08 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 16:26:08 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip! 16:26:08 INFO - Caught exception: HTTP Error 404: Not Found 16:26:08 INFO - Caught exception: HTTP Error 404: Not Found 16:26:08 INFO - Caught exception: HTTP Error 404: Not Found 16:26:08 INFO - trying https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 16:26:08 INFO - Downloading https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 16:26:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 16:26:09 INFO - Downloaded 14413770 bytes. 16:26:09 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:26:09 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 16:26:09 INFO - Return code: 0 16:26:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:26:09 INFO - https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 16:26:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:26:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:26:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:26:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 16:26:09 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:26:09 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 16:26:09 INFO - retry: Failed, sleeping 30 seconds before retrying 16:26:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #2 16:26:39 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:26:39 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 16:26:39 INFO - retry: Failed, sleeping 60 seconds before retrying 16:27:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #3 16:27:39 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:27:39 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 16:27:39 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2! 16:27:39 INFO - Caught exception: HTTP Error 404: Not Found 16:27:39 INFO - Caught exception: HTTP Error 404: Not Found 16:27:39 INFO - Caught exception: HTTP Error 404: Not Found 16:27:39 INFO - trying https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:27:39 INFO - Downloading https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:27:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 16:27:41 INFO - Downloaded 56799929 bytes. 16:27:41 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:27:41 INFO - mkdir: /builds/slave/test/properties 16:27:41 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:27:41 INFO - Writing to file /builds/slave/test/properties/build_url 16:27:41 INFO - Contents: 16:27:41 INFO - build_url:https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:27:41 INFO - Running post-action listener: _resource_record_post_action 16:27:41 INFO - Running post-action listener: set_extra_try_arguments 16:27:41 INFO - [mozharness: 2016-11-05 23:27:41.752115Z] Finished download-and-extract step (success) 16:27:41 INFO - [mozharness: 2016-11-05 23:27:41.752349Z] Running populate-webroot step. 16:27:41 INFO - Running pre-action listener: _resource_record_pre_action 16:27:41 INFO - Running main action method: populate_webroot 16:27:41 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:27:41 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:27:41 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:27:41 INFO - '--tppagecycles', 16:27:41 INFO - '7'], 16:27:41 INFO - 'tests': ['tsvgm']}}, 16:27:41 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:27:41 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:27:41 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:27:41 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:27:41 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:27:41 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:27:41 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:27:41 INFO - 'talos_options': ['--disable-e10s'], 16:27:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:27:41 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:27:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:27:41 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:27:41 INFO - 'talos_options': ['--disable-e10s'], 16:27:41 INFO - 'tests': ['damp', 'tps']}, 16:27:41 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:27:41 INFO - 'tests': ['damp', 'tps']}, 16:27:41 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:27:41 INFO - 'tests': ['dromaeo_dom']}, 16:27:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:27:41 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:27:41 INFO - 'tests': ['basic_compositor_video']}, 16:27:41 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:27:41 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:27:41 INFO - 'tests': ['a11yr', 16:27:41 INFO - 'ts_paint', 16:27:41 INFO - 'tpaint', 16:27:41 INFO - 'sessionrestore', 16:27:41 INFO - 'sessionrestore_no_auto_restore', 16:27:41 INFO - 'tabpaint']}, 16:27:41 INFO - 'other-e10s': {'tests': ['a11yr', 16:27:41 INFO - 'ts_paint', 16:27:41 INFO - 'tpaint', 16:27:41 INFO - 'sessionrestore', 16:27:41 INFO - 'sessionrestore_no_auto_restore', 16:27:41 INFO - 'tabpaint']}, 16:27:41 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:27:41 INFO - 'ts_paint', 16:27:41 INFO - 'tpaint', 16:27:41 INFO - 'sessionrestore', 16:27:41 INFO - 'sessionrestore_no_auto_restore', 16:27:41 INFO - 'tabpaint']}, 16:27:41 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:27:41 INFO - 'ts_paint', 16:27:41 INFO - 'tpaint', 16:27:41 INFO - 'sessionrestore', 16:27:41 INFO - 'sessionrestore_no_auto_restore', 16:27:41 INFO - 'tabpaint']}, 16:27:41 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:27:41 INFO - 'tests': ['a11yr', 16:27:41 INFO - 'ts_paint', 16:27:41 INFO - 'tpaint', 16:27:41 INFO - 'sessionrestore', 16:27:41 INFO - 'sessionrestore_no_auto_restore', 16:27:41 INFO - 'tabpaint']}, 16:27:41 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:27:41 INFO - 'tests': ['a11yr', 16:27:41 INFO - 'ts_paint', 16:27:41 INFO - 'tpaint', 16:27:41 INFO - 'sessionrestore', 16:27:41 INFO - 'sessionrestore_no_auto_restore', 16:27:41 INFO - 'tabpaint']}, 16:27:41 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:27:41 INFO - 'tests': ['tsvgx', 16:27:41 INFO - 'tsvgr_opacity', 16:27:41 INFO - 'tart', 16:27:41 INFO - 'tscrollx', 16:27:41 INFO - 'cart']}, 16:27:41 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:27:41 INFO - 'tsvgr_opacity', 16:27:41 INFO - 'tart', 16:27:41 INFO - 'tscrollx', 16:27:41 INFO - 'cart']}, 16:27:41 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:27:41 INFO - 'talos_options': ['--disable-e10s'], 16:27:41 INFO - 'tests': ['tp5o']}, 16:27:41 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:27:41 INFO - 'tests': ['tp5o']}, 16:27:41 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:27:41 INFO - 'talos_options': ['--disable-e10s', 16:27:41 INFO - '--xperf_path', 16:27:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:27:41 INFO - 'tests': ['tp5n']}, 16:27:41 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:27:41 INFO - 'talos_options': ['--xperf_path', 16:27:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:27:41 INFO - 'tests': ['tp5n']}}, 16:27:41 INFO - 'talos.zip': {'path': '', 16:27:41 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:27:41 INFO - Running post-action listener: _resource_record_post_action 16:27:41 INFO - [mozharness: 2016-11-05 23:27:41.768908Z] Finished populate-webroot step (success) 16:27:41 INFO - [mozharness: 2016-11-05 23:27:41.768998Z] Running create-virtualenv step. 16:27:41 INFO - Running pre-action listener: _resource_record_pre_action 16:27:41 INFO - Running main action method: create_virtualenv 16:27:41 INFO - Creating virtualenv /builds/slave/test/build/venv 16:27:41 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 16:27:41 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:27:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:27:41 INFO - Using real prefix '/usr' 16:27:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:27:42 INFO - Installing distribute.............................................................................................................................................................................................done. 16:27:44 INFO - Installing pip.................done. 16:27:44 INFO - Return code: 0 16:27:44 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:27:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:27:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:27:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:44 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 0xe4c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfaf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110b3b0>, '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 0x110c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110c740>, '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': 'cbe409c6de289db6ac707de3000001d4-1478388000.885512-1859117507', '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 16:27:44 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 16:27:44 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 16:27:44 INFO - Using env: {'DISPLAY': ':0', 16:27:44 INFO - 'HOME': '/home/cltbld', 16:27:44 INFO - 'LANG': 'en_US.UTF-8', 16:27:44 INFO - 'LANGUAGE': 'en_US:en', 16:27:44 INFO - 'LOGNAME': 'cltbld', 16:27:44 INFO - 'MAIL': '/var/mail/cltbld', 16:27:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:44 INFO - 'MOZ_NO_REMOTE': '1', 16:27:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:27:44 INFO - 'NO_EM_RESTART': '1', 16:27:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:27:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:44 INFO - 'PWD': '/builds/slave/test', 16:27:44 INFO - 'SHELL': '/bin/bash', 16:27:44 INFO - 'SHLVL': '1', 16:27:44 INFO - 'TERM': 'linux', 16:27:44 INFO - 'TMOUT': '86400', 16:27:44 INFO - 'USER': 'cltbld', 16:27:44 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1478388000.885512-1859117507', 16:27:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:44 INFO - '_': '/tools/buildbot/bin/python'} 16:27:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:44 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 16:27:44 INFO - Cleaning up... 16:27:44 INFO - Return code: 0 16:27:44 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:27:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:27:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:27:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:44 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 0xe4c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfaf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110b3b0>, '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 0x110c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110c740>, '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': 'cbe409c6de289db6ac707de3000001d4-1478388000.885512-1859117507', '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 16:27:44 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 16:27:44 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 16:27:44 INFO - Using env: {'DISPLAY': ':0', 16:27:44 INFO - 'HOME': '/home/cltbld', 16:27:44 INFO - 'LANG': 'en_US.UTF-8', 16:27:44 INFO - 'LANGUAGE': 'en_US:en', 16:27:44 INFO - 'LOGNAME': 'cltbld', 16:27:44 INFO - 'MAIL': '/var/mail/cltbld', 16:27:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:44 INFO - 'MOZ_NO_REMOTE': '1', 16:27:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:27:44 INFO - 'NO_EM_RESTART': '1', 16:27:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:27:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:44 INFO - 'PWD': '/builds/slave/test', 16:27:44 INFO - 'SHELL': '/bin/bash', 16:27:44 INFO - 'SHLVL': '1', 16:27:44 INFO - 'TERM': 'linux', 16:27:44 INFO - 'TMOUT': '86400', 16:27:44 INFO - 'USER': 'cltbld', 16:27:44 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1478388000.885512-1859117507', 16:27:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:44 INFO - '_': '/tools/buildbot/bin/python'} 16:27:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:44 INFO - Downloading/unpacking psutil>=3.1.1 16:27:44 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 16:27:44 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 16:27:44 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 16:27:44 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 16:27:44 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 16:27:44 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 16:27:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:27:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:27:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:27:46 INFO - Installing collected packages: psutil 16:27:46 INFO - Running setup.py install for psutil 16:27:46 INFO - building 'psutil._psutil_linux' extension 16:27:46 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 16:27:47 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 16:27:47 INFO - building 'psutil._psutil_posix' extension 16:27:47 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 16:27:47 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 16:27:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:27:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:27:47 INFO - Successfully installed psutil 16:27:47 INFO - Cleaning up... 16:27:47 INFO - Return code: 0 16:27:47 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:27: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')]} 16:27:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27: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')]} 16:27:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe4c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfaf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110b3b0>, '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 0x110c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110c740>, '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': 'cbe409c6de289db6ac707de3000001d4-1478388000.885512-1859117507', '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 16:27:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:27:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:27:47 INFO - Using env: {'DISPLAY': ':0', 16:27:47 INFO - 'HOME': '/home/cltbld', 16:27:47 INFO - 'LANG': 'en_US.UTF-8', 16:27:47 INFO - 'LANGUAGE': 'en_US:en', 16:27:47 INFO - 'LOGNAME': 'cltbld', 16:27:47 INFO - 'MAIL': '/var/mail/cltbld', 16:27:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:47 INFO - 'MOZ_NO_REMOTE': '1', 16:27:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:27:47 INFO - 'NO_EM_RESTART': '1', 16:27:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:27:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:47 INFO - 'PWD': '/builds/slave/test', 16:27:47 INFO - 'SHELL': '/bin/bash', 16:27:47 INFO - 'SHLVL': '1', 16:27:47 INFO - 'TERM': 'linux', 16:27:47 INFO - 'TMOUT': '86400', 16:27:47 INFO - 'USER': 'cltbld', 16:27:47 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1478388000.885512-1859117507', 16:27:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:47 INFO - '_': '/tools/buildbot/bin/python'} 16:27:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:47 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:27:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:27:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:27:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:27:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:27:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:27:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:27:49 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:27:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:27:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:27:49 INFO - Installing collected packages: mozsystemmonitor 16:27:49 INFO - Running setup.py install for mozsystemmonitor 16:27:49 INFO - Successfully installed mozsystemmonitor 16:27:49 INFO - Cleaning up... 16:27:49 INFO - Return code: 0 16:27:49 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:27:49 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')]} 16:27:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:49 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')]} 16:27:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:49 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 0xe4c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfaf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110b3b0>, '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 0x110c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110c740>, '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': 'cbe409c6de289db6ac707de3000001d4-1478388000.885512-1859117507', '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 16:27:49 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 16:27:49 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 16:27:49 INFO - Using env: {'DISPLAY': ':0', 16:27:49 INFO - 'HOME': '/home/cltbld', 16:27:49 INFO - 'LANG': 'en_US.UTF-8', 16:27:49 INFO - 'LANGUAGE': 'en_US:en', 16:27:49 INFO - 'LOGNAME': 'cltbld', 16:27:49 INFO - 'MAIL': '/var/mail/cltbld', 16:27:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:49 INFO - 'MOZ_NO_REMOTE': '1', 16:27:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:27:49 INFO - 'NO_EM_RESTART': '1', 16:27:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:27:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:49 INFO - 'PWD': '/builds/slave/test', 16:27:49 INFO - 'SHELL': '/bin/bash', 16:27:49 INFO - 'SHLVL': '1', 16:27:49 INFO - 'TERM': 'linux', 16:27:49 INFO - 'TMOUT': '86400', 16:27:49 INFO - 'USER': 'cltbld', 16:27:49 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1478388000.885512-1859117507', 16:27:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:49 INFO - '_': '/tools/buildbot/bin/python'} 16:27:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:49 INFO - Downloading/unpacking jsonschema==2.5.1 16:27:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:27:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:27:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:27:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:27:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:27:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:27:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:27:51 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:27:51 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 16:27:51 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 16:27:51 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 16:27:51 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 16:27:51 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 16:27:51 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 16:27:52 INFO - Downloading functools32-3.2.3-2.tar.gz 16:27:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:27:52 INFO - warning: no files found matching '*.txt' 16:27:52 INFO - no previously-included directories found matching 'build' 16:27:52 INFO - no previously-included directories found matching 'dist' 16:27:52 INFO - no previously-included directories found matching '.git*' 16:27:52 INFO - Installing collected packages: jsonschema, functools32 16:27:52 INFO - Running setup.py install for functools32 16:27:52 INFO - warning: no files found matching '*.txt' 16:27:52 INFO - no previously-included directories found matching 'build' 16:27:52 INFO - no previously-included directories found matching 'dist' 16:27:52 INFO - no previously-included directories found matching '.git*' 16:27:52 INFO - Successfully installed jsonschema functools32 16:27:52 INFO - Cleaning up... 16:27:52 INFO - Return code: 0 16:27:52 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:27:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:27:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:27:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xe4c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfaf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110b3b0>, '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 0x110c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110c740>, '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': 'cbe409c6de289db6ac707de3000001d4-1478388000.885512-1859117507', '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 16:27:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:27:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 16:27:52 INFO - Using env: {'DISPLAY': ':0', 16:27:52 INFO - 'HOME': '/home/cltbld', 16:27:52 INFO - 'LANG': 'en_US.UTF-8', 16:27:52 INFO - 'LANGUAGE': 'en_US:en', 16:27:52 INFO - 'LOGNAME': 'cltbld', 16:27:52 INFO - 'MAIL': '/var/mail/cltbld', 16:27:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:52 INFO - 'MOZ_NO_REMOTE': '1', 16:27:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:27:52 INFO - 'NO_EM_RESTART': '1', 16:27:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:27:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:52 INFO - 'PWD': '/builds/slave/test', 16:27:52 INFO - 'SHELL': '/bin/bash', 16:27:52 INFO - 'SHLVL': '1', 16:27:52 INFO - 'TERM': 'linux', 16:27:52 INFO - 'TMOUT': '86400', 16:27:52 INFO - 'USER': 'cltbld', 16:27:52 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1478388000.885512-1859117507', 16:27:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:52 INFO - '_': '/tools/buildbot/bin/python'} 16:27:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:52 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:27:52 INFO - Cleaning up... 16:27:52 INFO - Return code: 0 16:27:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:27:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:27:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:27:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xe4c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfaf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110b3b0>, '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 0x110c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110c740>, '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': 'cbe409c6de289db6ac707de3000001d4-1478388000.885512-1859117507', '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 16:27:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:27:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:27:52 INFO - Using env: {'DISPLAY': ':0', 16:27:52 INFO - 'HOME': '/home/cltbld', 16:27:52 INFO - 'LANG': 'en_US.UTF-8', 16:27:52 INFO - 'LANGUAGE': 'en_US:en', 16:27:52 INFO - 'LOGNAME': 'cltbld', 16:27:52 INFO - 'MAIL': '/var/mail/cltbld', 16:27:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:52 INFO - 'MOZ_NO_REMOTE': '1', 16:27:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:27:52 INFO - 'NO_EM_RESTART': '1', 16:27:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:27:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:52 INFO - 'PWD': '/builds/slave/test', 16:27:52 INFO - 'SHELL': '/bin/bash', 16:27:52 INFO - 'SHLVL': '1', 16:27:52 INFO - 'TERM': 'linux', 16:27:52 INFO - 'TMOUT': '86400', 16:27:52 INFO - 'USER': 'cltbld', 16:27:52 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1478388000.885512-1859117507', 16:27:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:52 INFO - '_': '/tools/buildbot/bin/python'} 16:27:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:52 INFO - Downloading/unpacking blobuploader==1.2.4 16:27:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:27:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:27:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:27:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:27:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:27:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:27:54 INFO - Downloading blobuploader-1.2.4.tar.gz 16:27:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:27:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:27:54 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 16:27:54 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 16:27:54 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 16:27:54 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 16:27:54 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 16:27:54 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 16:27:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:27:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:27:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:27:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:27:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:27:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:27:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:27:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:27:55 INFO - Downloading docopt-0.6.1.tar.gz 16:27:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:27:55 INFO - Installing collected packages: blobuploader, requests, docopt 16:27:55 INFO - Running setup.py install for blobuploader 16:27:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:27:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:27:55 INFO - Running setup.py install for requests 16:27:55 INFO - Running setup.py install for docopt 16:27:55 INFO - Successfully installed blobuploader requests docopt 16:27:55 INFO - Cleaning up... 16:27:55 INFO - Return code: 0 16:27:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:27:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:27:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:27:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:55 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 0xe4c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfaf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110b3b0>, '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 0x110c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110c740>, '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': 'cbe409c6de289db6ac707de3000001d4-1478388000.885512-1859117507', '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 16:27:55 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 16:27:56 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 16:27:56 INFO - Using env: {'DISPLAY': ':0', 16:27:56 INFO - 'HOME': '/home/cltbld', 16:27:56 INFO - 'LANG': 'en_US.UTF-8', 16:27:56 INFO - 'LANGUAGE': 'en_US:en', 16:27:56 INFO - 'LOGNAME': 'cltbld', 16:27:56 INFO - 'MAIL': '/var/mail/cltbld', 16:27:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:56 INFO - 'MOZ_NO_REMOTE': '1', 16:27:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:27:56 INFO - 'NO_EM_RESTART': '1', 16:27:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:27:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:56 INFO - 'PWD': '/builds/slave/test', 16:27:56 INFO - 'SHELL': '/bin/bash', 16:27:56 INFO - 'SHLVL': '1', 16:27:56 INFO - 'TERM': 'linux', 16:27:56 INFO - 'TMOUT': '86400', 16:27:56 INFO - 'USER': 'cltbld', 16:27:56 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1478388000.885512-1859117507', 16:27:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:56 INFO - '_': '/tools/buildbot/bin/python'} 16:27:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:27:56 INFO - Running setup.py (path:/tmp/pip-VptLnL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:27:56 INFO - Running setup.py (path:/tmp/pip-Dg1dPt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:27:56 INFO - Running setup.py (path:/tmp/pip-g4ufmN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:27:56 INFO - Running setup.py (path:/tmp/pip-HRK52r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:27:56 INFO - Running setup.py (path:/tmp/pip-hC3ltu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:27:56 INFO - Running setup.py (path:/tmp/pip-R3yHz1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:27:56 INFO - Running setup.py (path:/tmp/pip-BvzttP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:27:56 INFO - Running setup.py (path:/tmp/pip-NJkdRE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:27:56 INFO - Running setup.py (path:/tmp/pip-U7_Tsk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:27:56 INFO - Running setup.py (path:/tmp/pip-Wp746W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:27:56 INFO - Running setup.py (path:/tmp/pip-6WhvYd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:27:57 INFO - Running setup.py (path:/tmp/pip-nl1HrJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:27:57 INFO - Running setup.py (path:/tmp/pip-cDBjNG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:27:57 INFO - Running setup.py (path:/tmp/pip-piqTe_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:27:57 INFO - Running setup.py (path:/tmp/pip-lqwpuE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:27:57 INFO - Running setup.py (path:/tmp/pip-M21CJ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:27:57 INFO - Running setup.py (path:/tmp/pip-ExMSL2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:27:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:27:57 INFO - Running setup.py install for manifestparser 16:27:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:27:57 INFO - Running setup.py install for mozcrash 16:27:57 INFO - Running setup.py install for mozdebug 16:27:57 INFO - Running setup.py install for mozdevice 16:27:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:27:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:27:57 INFO - Running setup.py install for mozfile 16:27:57 INFO - Running setup.py install for mozhttpd 16:27:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:27:58 INFO - Running setup.py install for mozinfo 16:27:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:27:58 INFO - Running setup.py install for mozInstall 16:27:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:27:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:27:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:27:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:27:58 INFO - Running setup.py install for mozleak 16:27:58 INFO - Running setup.py install for mozlog 16:27:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:27:58 INFO - Running setup.py install for moznetwork 16:27:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:27:58 INFO - Running setup.py install for mozprocess 16:27:58 INFO - Running setup.py install for mozprofile 16:27:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:27:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:27:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:27:58 INFO - Running setup.py install for mozrunner 16:27:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:27:58 INFO - Running setup.py install for mozscreenshot 16:27:59 INFO - Running setup.py install for moztest 16:27:59 INFO - Running setup.py install for mozversion 16:27:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:27:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:27:59 INFO - Cleaning up... 16:27:59 INFO - Return code: 0 16:27:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:27:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:27:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:27:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:59 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 0xe4c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfaf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110b3b0>, '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 0x110c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110c740>, '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': 'cbe409c6de289db6ac707de3000001d4-1478388000.885512-1859117507', '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 16:27:59 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 16:27:59 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 16:27:59 INFO - Using env: {'DISPLAY': ':0', 16:27:59 INFO - 'HOME': '/home/cltbld', 16:27:59 INFO - 'LANG': 'en_US.UTF-8', 16:27:59 INFO - 'LANGUAGE': 'en_US:en', 16:27:59 INFO - 'LOGNAME': 'cltbld', 16:27:59 INFO - 'MAIL': '/var/mail/cltbld', 16:27:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:59 INFO - 'MOZ_NO_REMOTE': '1', 16:27:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:27:59 INFO - 'NO_EM_RESTART': '1', 16:27:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:27:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:59 INFO - 'PWD': '/builds/slave/test', 16:27:59 INFO - 'SHELL': '/bin/bash', 16:27:59 INFO - 'SHLVL': '1', 16:27:59 INFO - 'TERM': 'linux', 16:27:59 INFO - 'TMOUT': '86400', 16:27:59 INFO - 'USER': 'cltbld', 16:27:59 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1478388000.885512-1859117507', 16:27:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:59 INFO - '_': '/tools/buildbot/bin/python'} 16:27:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:27:59 INFO - Running setup.py (path:/tmp/pip-StgRYo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:27:59 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)) 16:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:27:59 INFO - Running setup.py (path:/tmp/pip-UQ0yqk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:27:59 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)) 16:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:27:59 INFO - Running setup.py (path:/tmp/pip-FjP2J5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:27:59 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)) 16:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:27:59 INFO - Running setup.py (path:/tmp/pip-FQcMvF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:27:59 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)) 16:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:27:59 INFO - Running setup.py (path:/tmp/pip-j9rIhI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:27:59 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)) 16:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:27:59 INFO - Running setup.py (path:/tmp/pip-r3MT0G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:27:59 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)) 16:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:27:59 INFO - Running setup.py (path:/tmp/pip-RJd_Zn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:27:59 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)) 16:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:27:59 INFO - Running setup.py (path:/tmp/pip-NHWC39-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:28:00 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)) 16:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:28:00 INFO - Running setup.py (path:/tmp/pip-6zcR_q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:28:00 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)) 16:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:28:00 INFO - Running setup.py (path:/tmp/pip-z8TiLG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:28:00 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)) 16:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:28:00 INFO - Running setup.py (path:/tmp/pip-ccdnsw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:28:00 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)) 16:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:28:00 INFO - Running setup.py (path:/tmp/pip-B0xUui-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:28:00 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)) 16:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:28:00 INFO - Running setup.py (path:/tmp/pip-ncS8Wc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:28:00 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)) 16:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:28:00 INFO - Running setup.py (path:/tmp/pip-EbQWIM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:28:00 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)) 16:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:28:00 INFO - Running setup.py (path:/tmp/pip-mhfyNg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:28:00 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)) 16:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:28:00 INFO - Running setup.py (path:/tmp/pip-jPjRkq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:28:00 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)) 16:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:28:00 INFO - Running setup.py (path:/tmp/pip-MK2Ixx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:28:00 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)) 16:28:00 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)) 16:28:00 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)) 16:28:00 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)) 16:28:00 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)) 16:28:00 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)) 16:28:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:28:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:28:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:28:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:28:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:28:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:28:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:28:02 INFO - Downloading blessings-1.6.tar.gz 16:28:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:28:02 INFO - Installing collected packages: blessings 16:28:02 INFO - Running setup.py install for blessings 16:28:02 INFO - Successfully installed blessings 16:28:02 INFO - Cleaning up... 16:28:02 INFO - Return code: 0 16:28:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:28:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:28:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:28:03 INFO - Reading from file tmpfile_stdout 16:28:03 INFO - Current package versions: 16:28:03 INFO - argparse == 1.2.1 16:28:03 INFO - blessings == 1.6 16:28:03 INFO - blobuploader == 1.2.4 16:28:03 INFO - docopt == 0.6.1 16:28:03 INFO - functools32 == 3.2.3-2 16:28:03 INFO - jsonschema == 2.5.1 16:28:03 INFO - manifestparser == 1.1 16:28:03 INFO - mozInstall == 1.12 16:28:03 INFO - mozcrash == 0.17 16:28:03 INFO - mozdebug == 0.1 16:28:03 INFO - mozdevice == 0.48 16:28:03 INFO - mozfile == 1.2 16:28:03 INFO - mozhttpd == 0.7 16:28:03 INFO - mozinfo == 0.9 16:28:03 INFO - mozleak == 0.1 16:28:03 INFO - mozlog == 3.3 16:28:03 INFO - moznetwork == 0.27 16:28:03 INFO - mozprocess == 0.23 16:28:03 INFO - mozprofile == 0.28 16:28:03 INFO - mozrunner == 6.12 16:28:03 INFO - mozscreenshot == 0.1 16:28:03 INFO - mozsystemmonitor == 0.3 16:28:03 INFO - moztest == 0.7 16:28:03 INFO - mozversion == 1.4 16:28:03 INFO - psutil == 3.1.1 16:28:03 INFO - requests == 1.2.3 16:28:03 INFO - wsgiref == 0.1.2 16:28:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:28:03 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')]} 16:28:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:28:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:03 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')]} 16:28:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:28:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:03 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 0xe4c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfaf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110b3b0>, '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 0x110c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110c740>, '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': 'cbe409c6de289db6ac707de3000001d4-1478388000.885512-1859117507', '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 16:28:03 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 16:28:03 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 16:28:03 INFO - Using env: {'DISPLAY': ':0', 16:28:03 INFO - 'HOME': '/home/cltbld', 16:28:03 INFO - 'LANG': 'en_US.UTF-8', 16:28:03 INFO - 'LANGUAGE': 'en_US:en', 16:28:03 INFO - 'LOGNAME': 'cltbld', 16:28:03 INFO - 'MAIL': '/var/mail/cltbld', 16:28:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:03 INFO - 'MOZ_NO_REMOTE': '1', 16:28:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:28:03 INFO - 'NO_EM_RESTART': '1', 16:28:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:28:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:03 INFO - 'PWD': '/builds/slave/test', 16:28:03 INFO - 'SHELL': '/bin/bash', 16:28:03 INFO - 'SHLVL': '1', 16:28:03 INFO - 'TERM': 'linux', 16:28:03 INFO - 'TMOUT': '86400', 16:28:03 INFO - 'USER': 'cltbld', 16:28:03 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1478388000.885512-1859117507', 16:28:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:03 INFO - '_': '/tools/buildbot/bin/python'} 16:28:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:28:03 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)) 16:28:03 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)) 16:28:03 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)) 16:28:03 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)) 16:28:03 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)) 16:28:03 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)) 16:28:03 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)) 16:28:03 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)) 16:28:03 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)) 16:28:03 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)) 16:28:03 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)) 16:28:03 INFO - Cleaning up... 16:28:03 INFO - Return code: 0 16:28:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:28:03 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')]} 16:28:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:28:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:03 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')]} 16:28:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:28:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:03 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 0xe4c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfaf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110b3b0>, '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 0x110c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110c740>, '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': 'cbe409c6de289db6ac707de3000001d4-1478388000.885512-1859117507', '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 16:28:03 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 16:28:03 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 16:28:03 INFO - Using env: {'DISPLAY': ':0', 16:28:03 INFO - 'HOME': '/home/cltbld', 16:28:03 INFO - 'LANG': 'en_US.UTF-8', 16:28:03 INFO - 'LANGUAGE': 'en_US:en', 16:28:03 INFO - 'LOGNAME': 'cltbld', 16:28:03 INFO - 'MAIL': '/var/mail/cltbld', 16:28:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:03 INFO - 'MOZ_NO_REMOTE': '1', 16:28:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:28:03 INFO - 'NO_EM_RESTART': '1', 16:28:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:28:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:03 INFO - 'PWD': '/builds/slave/test', 16:28:03 INFO - 'SHELL': '/bin/bash', 16:28:03 INFO - 'SHLVL': '1', 16:28:03 INFO - 'TERM': 'linux', 16:28:03 INFO - 'TMOUT': '86400', 16:28:03 INFO - 'USER': 'cltbld', 16:28:03 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1478388000.885512-1859117507', 16:28:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:03 INFO - '_': '/tools/buildbot/bin/python'} 16:28:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:28:03 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:28:03 INFO - Cleaning up... 16:28:03 INFO - Return code: 0 16:28:03 INFO - Running post-action listener: _resource_record_post_action 16:28:03 INFO - Running post-action listener: _start_resource_monitoring 16:28:03 INFO - Starting resource monitoring. 16:28:03 INFO - [mozharness: 2016-11-05 23:28:03.504778Z] Finished create-virtualenv step (success) 16:28:03 INFO - [mozharness: 2016-11-05 23:28:03.505251Z] Running install step. 16:28:03 INFO - Running pre-action listener: _resource_record_pre_action 16:28:03 INFO - Running main action method: install 16:28:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:28:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:28:03 INFO - Reading from file tmpfile_stdout 16:28:03 INFO - Detecting whether we're running mozinstall >=1.0... 16:28:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:28:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:28:03 INFO - Reading from file tmpfile_stdout 16:28:03 INFO - Output received: 16:28:03 INFO - Usage: mozinstall [options] installer 16:28:03 INFO - Options: 16:28:03 INFO - -h, --help show this help message and exit 16:28:03 INFO - -d DEST, --destination=DEST 16:28:03 INFO - Directory to install application into. [default: 16:28:03 INFO - "/builds/slave/test"] 16:28:03 INFO - --app=APP Application being installed. [default: firefox] 16:28:03 INFO - mkdir: /builds/slave/test/build/application 16:28:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 16:28:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 16:28:17 INFO - Reading from file tmpfile_stdout 16:28:17 INFO - Output received: 16:28:17 INFO - /builds/slave/test/build/application/firefox/firefox 16:28:17 INFO - Running post-action listener: _resource_record_post_action 16:28:17 INFO - [mozharness: 2016-11-05 23:28:17.294878Z] Finished install step (success) 16:28:17 INFO - [mozharness: 2016-11-05 23:28:17.295152Z] Running run-tests step. 16:28:17 INFO - Running pre-action listener: _resource_record_pre_action 16:28:17 INFO - Running main action method: run_tests 16:28:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:28:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:28:17 INFO - Python 2.7.3 16:28:17 INFO - Return code: 0 16:28:17 INFO - grabbing minidump binary from tooltool 16:28:17 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')]} 16:28:17 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 0x110b3b0>, '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 0x110c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110c740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:28:17 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 16:28:17 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 16:28:17 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 16:28:17 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:28:17 INFO - Return code: 0 16:28:17 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:28:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:28:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:28:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:28:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:28:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-054', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 16:28:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-054 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 16:28:17 INFO - Using env: {'DISPLAY': ':0', 16:28:17 INFO - 'HOME': '/home/cltbld', 16:28:17 INFO - 'LANG': 'en_US.UTF-8', 16:28:17 INFO - 'LANGUAGE': 'en_US:en', 16:28:17 INFO - 'LOGNAME': 'cltbld', 16:28:17 INFO - 'MAIL': '/var/mail/cltbld', 16:28:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:28:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:28:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:17 INFO - 'MOZ_NO_REMOTE': '1', 16:28:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:28:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:28:17 INFO - 'NO_EM_RESTART': '1', 16:28:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:28:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:17 INFO - 'PWD': '/builds/slave/test', 16:28:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:28:17 INFO - 'SHELL': '/bin/bash', 16:28:17 INFO - 'SHLVL': '1', 16:28:17 INFO - 'TERM': 'linux', 16:28:17 INFO - 'TMOUT': '86400', 16:28:17 INFO - 'USER': 'cltbld', 16:28:17 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1478388000.885512-1859117507', 16:28:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:17 INFO - '_': '/tools/buildbot/bin/python'} 16:28:17 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-054', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 16:28:17 INFO - mozversion application_buildid: 20161103181821 16:28:17 INFO - mozversion application_changeset: 24b8f08f77565f859898b45f62d2132ccc64c6d8 16:28:17 INFO - mozversion application_display_name: Firefox 16:28:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:28:17 INFO - mozversion application_name: Firefox 16:28:17 INFO - mozversion application_remotingname: firefox 16:28:17 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 16:28:17 INFO - mozversion application_vendor: Mozilla 16:28:17 INFO - mozversion application_version: 50.0 16:28:17 INFO - mozversion platform_buildid: 20161103181821 16:28:17 INFO - mozversion platform_changeset: 24b8f08f77565f859898b45f62d2132ccc64c6d8 16:28:17 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 16:28:17 INFO - mozversion platform_version: 50.0 16:28:17 INFO - using testdate: 1478388497 16:28:17 INFO - actual date: 1478388497 16:28:17 INFO - starting webserver on 'localhost:53765' 16:28:17 INFO - SUITE-START | Running 2 tests 16:28:17 INFO - TEST-START | dromaeo_css 16:28:17 INFO - operating with platform_type : linux_ 16:28:17 INFO - Initialising browser for dromaeo_css test... 16:28:17 INFO - TEST-INFO | started process 27967 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmph5NNeS/profile http://localhost:53765/getInfo.html) 16:28:21 INFO - PROCESS | 27967 | __metrics Screen width/height:1600/1200 16:28:21 INFO - PROCESS | 27967 | colorDepth:24 16:28:21 INFO - PROCESS | 27967 | Browser inner width/height: 1024/697 16:28:21 INFO - PROCESS | 27967 | __metrics 16:28:22 INFO - PROCESS | 27967 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:28:22 INFO - PROCESS | 27967 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:28:23 INFO - TEST-INFO | 27967: exit 0 16:28:23 INFO - Browser initialized. 16:28:23 INFO - Running cycle 1/1 for dromaeo_css test... 16:28:23 INFO - TEST-INFO | started process 28057 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmph5NNeS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:28:35 INFO - PROCESS | 28057 | RSS: Main: 144617472 16:28:35 INFO - PROCESS | 28057 | 16:29:26 INFO - PROCESS | 28057 | 1478388566328 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] 16:31:35 INFO - PROCESS | 28057 | Cycle 1(1): loaded http://localhost:53765/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53765/tests/dromaeo/cssquery-ext.html) 16:31:36 INFO - PROCESS | 28057 | RSS: Main: 265129984 16:31:36 INFO - PROCESS | 28057 | 16:34:38 INFO - PROCESS | 28057 | Cycle 1(1): loaded http://localhost:53765/tests/dromaeo/cssquery-ext.html (next: http://localhost:53765/tests/dromaeo/cssquery-jquery.html) 16:34:39 INFO - PROCESS | 28057 | RSS: Main: 228786176 16:34:39 INFO - PROCESS | 28057 | 16:37:41 INFO - PROCESS | 28057 | Cycle 1(1): loaded http://localhost:53765/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53765/tests/dromaeo/cssquery-mootools.html) 16:37:42 INFO - PROCESS | 28057 | RSS: Main: 177778688 16:37:42 INFO - PROCESS | 28057 | 16:40:43 INFO - PROCESS | 28057 | Cycle 1(1): loaded http://localhost:53765/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53765/tests/dromaeo/cssquery-prototype.html) 16:40:44 INFO - PROCESS | 28057 | RSS: Main: 217391104 16:40:44 INFO - PROCESS | 28057 | 16:43:47 INFO - PROCESS | 28057 | Cycle 1(1): loaded http://localhost:53765/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53765/tests/dromaeo/cssquery-yui.html) 16:43:47 INFO - PROCESS | 28057 | RSS: Main: 177627136 16:43:47 INFO - PROCESS | 28057 | 16:46:49 INFO - PROCESS | 28057 | Cycle 1(1): loaded http://localhost:53765/tests/dromaeo/cssquery-yui.html (next: http://localhost:53765/tests/dromaeo/cssquery-dojo.html) 16:46:50 INFO - PROCESS | 28057 | RSS: Main: 267440128 16:46:50 INFO - PROCESS | 28057 | 16:46:50 INFO - PROCESS | 28057 | __start_tp_report 16:46:50 INFO - PROCESS | 28057 | _x_x_mozilla_page_load 16:46:50 INFO - PROCESS | 28057 | _x_x_mozilla_page_load_details 16:46:50 INFO - PROCESS | 28057 | |i|pagename|runs| 16:46:50 INFO - PROCESS | 28057 | |0;dojo.html;3212.8875489357874;3229.531717900904;3238.2228265216354;3241.2707140893317;3281.5077738339246;5613.887722245558;5661.830145095643;5695.430456954307;5716.514096301809;5730.169125476808;5623.718814059301;5708.315002199742;5712.457316554931;5716.628419152763;5718.428157184279;5135.255387968742;5140.485951404858;5144.25408315794;5179.482051794828;5216.452272511388;5024.949750502503;5199.584033277329;5210.973945130269;5216.087184742655;5245.868853278679;4963.45402005779;5012.172991456406;5020.447750747415;5067.189249720045;5086.440491545945;5046;5079.365079365061;5083.847484575469;5144.099782538078;5177.663451875616;4975.427825800043;5042.571381432583;5088.109580823341;5105.463926287724;5105.489451054902;5467.972660136674;5471.8084867029465;5481.643693159932;5483.314585676791;5501.4223506532035;5868.823935281948;5886.175935369051;5920.289565252154;5977.641341519479;5980.880382392328;5928.377520360344;6000.939990600125;6009.939900600982;6016.759329626853;6019.518438524908;5586.469285417878;5589.329280486327;5621.100623900197;5664.405237450091;5667.1782591524;6234.407731265523;6244.063390491372;6246.593971391845;6255.0617407388345;6258.186435763321;5718.942810571924;5793.478586927196;5975.282966044016;5985.042393217064;5989.580729348903;5922.851928701817;5985.34161242263;5989.850253743692;6022;6031.607945483617;5973.283206015219;5976.402359763989;5977.043673012297;5981.312149102821;5986.4013598639785;5772.422757724194;5876.676782776988;5958.731857066442;5967;5970.283565972111;4574.611158051536;4593.724376537419;4606.930896036562;4633.4208223972;4658.697184682985;4079.6532294754684;4104.179164167179;4107.9178416431505;4134.834606615762;4145.025918909054;5543.6396634218645;5749.942500574941;5751.338596061423;5753.482186603226;5754.424557544296;5985.730642121115;6018.879622407528;6030.788922387783;6072.483838873657;6122.051082082285;5731.885362292731;5734.77060917567;5738.8278351649515;5742.3396309424115;5742.483176514133;5131.153359695609;5246.422893481714;5249.606279529051;5253.921191182136;5256.868578285574;6113.263474096239;6122.2761798032625;6143.631382117087;6246.593971391936;6252.030935205142;6052.636841789506;6070.817875463743;6072.939270607238;6090.712312096581;6091.878162436815;6067.666278354733;6213.254409470712;6259.499240060693;6284.183056202664;6293.527985401205;5935.881282374242;5962.493188079062;5986.132010858314;6002.369751176064;6010.248718910137;6110.51115910735;6111.388861114031;6120.296165940974;6122.357152498924;6124.050772130327;5812.9709351452975;5956.13636022783;5967.6121052131475;5967.6121052131475;5968.074948383008;5667.716614169358;5832.650040997553;5838.241028666246;5915.497182739343;5949.672767997628;5825.417458254141;5863.1791549184;5873.353931067664;5879.235699359227;5882.23530940975;5675.971620141873;5925.40745925404;5936.46571808522;5967.880642387041;5983.34183239852;5708.914366284426;5755.798547050833;5767.048437007847;5801.564882633735;5835.94164058371 16:46:50 INFO - PROCESS | 28057 | |1;ext.html;583.7723287917713;631.4727202785674;653.1085068880978;660.3132741948377;664.1233571685372;34025.31949361014;46079;46609.30086048712;46787.999999999956;46800.531994680045;46666;47289.290660640116;47314.290285645744;47340.526594734045;47368.28947565789;8381.497110173379;9262.675806346797;9406.529673516297;9408.858867116998;9424.717258482257;10942.17933654975;11215.046721028722;11216.214864959456;11611.129165312594;11612.303261804318;11285.04077153443;11412.714682132972;11415.771684566304;11418.543258269701;11422.42887855608;11175.161862860277;11228.66314010577;11231.550737970472;11246.606368777135;11250.3812290324;11091.168162387772;11146.27549209307;11159.888401115966;11165.609203677832;11171.497282622302;45858.54141458609;46121.308180377324;46182.30726539105;46244;46307;3778.301014312361;3820;3851.056491159684;3866.961330386688;3879.7478163919536;34026.65973340284;34271.4859277111;34510.48234276488;34696.82651586726;35774.64225357765;4504.391907092533;4515.435570553681;4552.203364411214;4625.259958406672;4695.577825499102;340777;344679;344683;346186.26906865556;346997;36781.26437471236;38120.23759524794;65727;66337.6683116591;66387;39528.40707389391;39977;40015.59984400119;40053.799731001156;40123.79938100291;16521.504354869132;16690.582735431468;16696.66606667884;16700.58248543796;16713.247903844356;12854;13708.725825483434;14123.435062597586;14546.418143274363;14732.779008314883;9072.90927090734;9073.59168837404;9127.726168214964;9164.037776033554;9201.723948281562;3494.7553671243277;3551.698105660996;3556.3776339140545;3558.252766918976;3574.821258937043;1053.378506681062;1108.1854836695022;1126.830975353687;1162.0761494611804;1190.3334132885611;34633.134171645914;35133.121671958404;35232.29535409278;35303.953182849305;35360;1107.8670559532907;1140.6235942139074;1176.5352685689277;1202.7714734200474;1217.11150859871;16192.676146477004;18550.44348669542;19093.331733389266;19332.130054147598;19401.514962125777;696192;702031;703269;705887;706348;65909.34090659033;66422.33577664258;66517;66566;66624.33375666279;1328.6014195741238;1429.5496918470649;1433.1830856411746;1455.5415044261024;1464.2605484230608;34278.657213428545;35605.10987225237;35760.46359304665;36334.8183259082;36390.63609363978;53492.732536338626;54132.18801717899;54145.72927135339;54195.645108872595;54227;431379;433975;435486;435570;439918;28233.858830705714;29304;29453;30605.387892241586;31567.526487102252;48594.514054859;49126.50873491362;49389.75305123451;49763.75118124531;50401.49598503968;41289.380659290735;44792.328115077646;45216.54783452123;46392.536074638825;48858.511414886816;44528.554714453734;45400.54599454095;45593.54406455893;47067;47083.76458117824;44716.10567788691;44898.326525102806;45240.773796132125;45384.77307613573;45854.770726146155;38810.223795523365;40388.394174087996;40479.59520404876;40802.387964179965;41301.1739765197;29590.704092958797;30183.849080754455;30434;30644.693553064182;30709 16:46:50 INFO - PROCESS | 28057 | |2;jquery.html;417.5177669791392;428.2911781002441;432.7922597153366;434.7934731002773;445.4543184598867;20383.592328153427;20603.896980515095;21090.261840835574;21344.57310853782;21543.138274469;19600.607987840234;20552.486187845312;20608.484787880312;20745.481362965933;20790.89604551979;5766.9711651441785;6043.1841701370195;6047;6047.788327408542;6049.153118563405;5722.771089156428;5871.29544454666;5874.5006674432725;5892.587518873681;5895.469407753301;5876.6180198286975;5878.88242235155;5885.176075349455;5886.88226235475;5888.97055514724;5650.180723795047;5819;5849;5851.473367396933;5852.76588936442;5250.6324557281005;5274.630775845692;5282.867928301823;5286.15421532553;5288.048151332759;23873.164439244716;24075.157369492157;24210.515789684283;24395;24449.755502445103;2762.9447411051665;2792.1204820481485;2812.3109838089595;3112.7821052526333;3128.9530657040164;15164.620884477867;15320.693586128327;15346.769798453031;15431.845681543153;16021.279042443119;2028.7261219735294;2117.697615966185;2126.5109024924354;2133.637281662121;2183.9781602184094;58316.708416458074;60235;72815;80719.5964020182;86120.99999999937;11596.826047609293;11621.302721836548;11736.589219377282;11762.764744705059;11933.343666098273;16142.112183830002;16193.271302791403;16260;16277.511674649779;16328.75506867398;9042.734017237592;9090.54547272647;9104.317176211678;9166.954165229265;9206.493642849708;8715.607797649121;8731.476111433334;8810.414125457351;8969.103089690978;9044.638214471348;6987;7046.224915259318;7057.630589300841;7145.714171433085;7150.10623672041;2005.4785755703622;2035.9389218323474;2055.8340156159347;2090.968635470469;2094.5601423700887;947.4930911962067;1050.5242710749;1054.9208809339177;1088.4231357380602;1117.8155604325357;20616.38150855477;21175.78824211769;21787.12851485923;22026.88986555057;22062.558748824933;1079.3469950679882;1079.6545105566302;1105.5743538737484;1131.801934661431;1148.1388958281289;11447.25592836463;11768.117391195694;11880.287182768889;11907.821382679267;11921;593265;598514;604311.9137617223;625131;629198;70115.64942175256;87624.12375876159;93211;96590;100279.49860250652;1059.708580140452;1098.747288123736;1114.6934592986993;1116.810142275815;1117.5529788084832;19026.334078307194;19306.517337066125;19451;19461.416157515298;19522.511937201685;43893.561064390226;44388.55799685183;44924;45674.31488527741;46194.30708539308;63632.36367636264;64285.67857160871;64328;65137.34862651502;65360.019599707;8399;8531.8720219198;8713.085126061673;8759.649614015369;8787.209151176425;9727.07592778685;12827.230366178059;13058.804117938429;13341.599752007456;13634.727305454035;11266;11365.829512557484;11453.427328633368;11463.541458341573;11545.191836571359;11228.943855280999;11413.714657133638;11454.312741235553;11485.885141148816;11589.594364197214;11391.41303916502;12048.578299759467;12134.393280335771;12190.146689731633;12192.024638028759;8566.52884091381;8626.827463450822;8648.870266945874;8672.176143266379;8784;11650.155618502353;11800.881991179978;11864.406779661154;12018.218815776947;12106.757864842477 16:46:50 INFO - PROCESS | 28057 | |3;mootools.html;1488.6278430392401;1500.7223663622228;1674.6650669866021;1822.489702883193;1823.3618233618226;8447;9106.99999999999;9108.043655416168;9117.589708463118;9127.224185944186;7447.143578488475;7647.311741943224;7659.540427574349;7868.685252589904;8936;2351.0478256306224;2440.7803297703203;2443.816713746476;2462.6675398821108;2469.1851688942697;2438.563327032131;2446.412860913386;2490.3027152397267;2490.68866391701;2496.500699860026;2447.449323902126;2452.583060879646;2465.48224872776;2481.2060140754966;2485.3538080099124;2376.7742064503846;2410.7348191698898;2418.443757935676;2421.9515609687796;2433.6471211674207;2369.869657168855;2371.205646108556;2375.1924345722364;2377.5720370333333;2378.4410663494064;8838.027816940132;8848.51333176675;8917.375783695144;8935.748995140686;8956.149165829236;5237.3453318335205;5255.500727430926;5343.919841202385;5357.624966252364;5358.946410535884;3426.468897320919;3445.173158441981;3497.265574229415;3505.719542436599;3521.683048525645;1182.9290242585387;1232.6548566401423;1243.104964425612;1247.8440194975628;1267.575362253646;12548.937255313665;12597.685057873536;12604.11771176018;12613.180143290656;12622.495100195982;4584.679072464962;4687.929681054787;4849.975750121297;4864.367632207861;4883.609311255091;10669;10673.146148308117;10674.57301707923;10681.252312338062;10690.144788416908;5467.589930755209;5498.037843377393;5502.477264659851;5504.2294078829;5523.143912693539;4391.165678521071;4904.509549045067;5003.674761140514;5039.571636410946;5073.670211436245;4061.857834975821;4125.690573207022;4168.812403441852;4196.496420429568;4218.831246750157;1320.8613095625012;1332.253937794838;1333.4666133546466;1334.2128144394853;1343.1940835498622;955.9856602150974;1007.1489591295343;1020.6682828080903;1024.4416792847917;1045.581767293089;5764.193012978187;5971.014782560916;6102.237220347457;6116.908246376308;6153.261608606996;484.21557077533913;484.4477295882697;488.2359107995975;492.1117383123425;497.1870990929856;4026.5168179818024;4051.918961620751;4069.267531844237;4089.5705950875326;4120.340745480708;10777.407242601732;11086.501107450184;11176.552937882394;11178.944105279421;11190.048845848194;11084.833727494093;11101.666949991513;11104.888951110546;11107.222494425474;11117.110631149488;7919.2872641462545;8293.626786794488;8294.668213271521;8380.497170169809;8493.193146651058;5910.911336329872;5918.704064796829;6059.666718330358;6092.817215483543;6187.84530386744;10418.68743937683;10423.270371073952;10431.11335536488;10433.634822781167;10483.37099774016;10168.33905796121;10352.378857268588;10547.103496202908;10687.5190616424;10697.358158510515;7129.750458733919;7352.816179595553;7751.3411360035025;7980.12218655959;8208.507489550646;8592.527410992456;8940.910590894267;8947.865782013145;8949.731508054769;8956.104389560991;7765.844683106419;7942.12636609984;7957.7612671619945;8002.639881205476;8107.189281072082;7810.414218933716;7816.4137689674635;7925.920740792519;7929.444938854225;7998.960005199937;7205.279472052963;7316.378107860892;7423.703051877865;7436.070491188601;7440.665170067252;5104.617153713412;5181.37823461172;5197.610179236648;5270.8945821084135;5287.286216360742;5077.441481436965;5159.974200128975;5279.313689220377;5301.602379821606;5454.018276710231 16:46:50 INFO - PROCESS | 28057 | |4;prototype.html;335.1437078265033;348.2460473075791;353.1559572621434;354.485995306805;355.8185325484003;5616.52259557938;5622.550195984321;5639.407862174469;5698.800541981026;5722.856928576788;5243.895122097556;5376;5378;5382.67703937764;5508.779648814053;4310.849120280791;4345.914400775438;4381.014271788854;4387.978060109695;4434.357018232355;4395.978020109895;4408.589251439541;4410.250257456224;4428.446444194476;4442.222611043071;4305.1487860220095;4337.368557059641;4348.456442944632;4357.564243575633;4373.868783936471;4351.390805287262;4354.107407981356;4354.608085272341;4355.716878402894;4359.149965756678;3912.0219945013746;3913.7456065355946;3947.940780888289;3962.920741585152;3971.1660551284267;5192.480751924815;5428.6742795432;5450.809221677222;5462.371827239878;5507.669539827583;4954.711774938506;4975.134324628264;4992.575631071363;4993.176125939216;5021.196608542652;5793.7392817323325;5801.216835727165;5803.506701930299;5827.155062515912;5833.687420330391;4116.423700681907;4197.286461301586;4214.473190851144;4219.2405367033925;4223.092035212444;6025.487833534155;6026.975414179644;6049.153118563405;6057.9398605243905;6061.818145455643;4307.655387568987;4311.374850646702;4359.912801743947;4363.280058790264;4372.868813935587;4166.687498437629;4388.232059389594;4394.714343567658;4397.6042156206095;4431.933520997188;4929.7781599828095;4953.975230123899;4969.975150124226;4972.552470277692;4979.90040199194;5826.970865145647;5838.803045375773;5847.3335099496435;5888.764449421975;5891.764329426775;1423.309694798022;1431.77807439847;1480.2302802542833;1499.137995652504;1510.9546713598609;1028.2647906746713;1029.0429900192744;1043.9008294212038;1053.210092430677;1055.7413433708791;5515.944840551623;5537.833864984057;5571.916421253684;5600.159976003551;5602.635828671123;5827.446392592697;5833.708314584339;5845.181674565564;5849.151872978479;5851.765929362863;5405.270288511001;5420.53925416344;5495.477929596682;5583.553315734732;5622.718864056863;2767.3911739417294;2780.7914406419195;2797.090945442739;2798.636177296979;2803.663560372768;5303;5359.785608575691;5373.838784836461;5380.542653874464;5421.620486565981;4989.850304490871;5314.441983591744;5334.786608535693;5334.946650533522;5355.651882627617;5351.839444816739;5361.705106219196;5375.274337964403;5385.784568617211;5394.568434525228;5163.4578369270685;5184.370940341701;5199.516324062963;5209.291168592235;5272.525472707322;5488.533474654699;5900.144479050599;5913.231279933581;5928.117083310439;5933.851653708692;6235.220597425322;6249.562530622995;6253.687315634109;6256.7810126645345;6291.822629572037;4216;4303.182395344875;4308.827646894089;4314.784260786885;4328;6038.426349496791;6038.7958395186315;6040.063790112539;6041.462585714098;6046.516278697782;5630.943690563042;5711.314642242957;5841.269841269841;5854.297484301911;5892.852678683067;5851.97590421666;5861.560382971209;5872.324682661549;5878.295294364757;5879.9710050741805;5603.915941260802;5794.594378393472;5812.128180772918;5826.359100498857;5836.993948116638;5851.327097383855;5863.00328944072;5863.853403664943;5867.237259156283;5881.38245484218;5614.635048721983;5724.4847963681805;5747.511461525817;5793.159991801081;5794.594378393472 16:46:50 INFO - PROCESS | 28057 | |5;yui.html;776.8368642585057;802.4984384759525;807.6769292283086;826.4215049465375;909.5588639509837;13860.930695346522;14571.489997850078;14606.269686515658;14614.123152610851;14654.63363415915;12965.222086674807;13046.217226966388;13095.934520327384;13105.213687178775;13125.93437032816;706.9045678833369;826.7271800305884;850.8681154421043;865.4980111535294;871.6252011634995;859.3984211052257;866.5927014303268;866.7313132928781;867.8134201146752;867.8437881181385;909.8953620333647;910.4582773249939;911.8723378726984;912.8630705394178;914.2777206007246;912.2656261709321;914.6524320758136;914.8033172867832;915.1763412928351;916.3814425262921;671.4427025568766;671.7279501801778;672.6065251827638;672.7544446277122;672.9798106056826;13044.934775326157;13053.477860885554;13060.34698265083;13073.607791766262;13084.411201495956;15227.695446091016;15717.685646287124;15756.44852430171;15781.493156697621;15978.680426391524;11664.416779161093;11907.106966977512;11935.820962685566;11948.223365481303;11991.280523168638;4059.2084543514025;4109.609587089221;4145.689073319513;4173.165366926627;4174.248635245654;83290.58354708187;84112;84938.57530712368;85430.57284713537;86804.13195868024;11706.180567360203;11950.701232469259;12003.459844307026;12242.755144897052;12304.507819687113;21141.68287475689;21145.894270528548;21169.576608467745;21178.364649060553;21267.680984785242;8623.439476434012;8714.52070136136;8994.235489983294;9150.084991500797;9596.280278979104;4979.526944940225;4980.352554167935;5023.824166154167;5138.177891537336;5184.326037615161;3740.233252183296;3851.1527463957887;3856.267309211297;3856.8842934712;3860.1507668312925;633.1610615933906;638.655126231839;648.2188962300417;649.6297110646981;649.7271146118642;684.8630273945131;688.721067967474;790.6397809310431;796.2873228460495;804.4690504267272;9806.31355805101;10280.845787313196;10288.537015834305;10436.791264174826;11078.944605277084;737.2664199121908;747.9738209162762;748.1022772672815;760.8326168242978;768.6425811997336;4869.2228927041015;4925.088858561177;4941.975290123598;4970.2793095001025;5088.160453525201;95935;134491.32754336167;135738.32130839283;139070.3046484761;146849;114767;118251.40874295746;119741.40129299297;122346.38826805809;123409;95140;95494.52252738691;96033;96371;100144;9515.571799269188;9768.511574421393;9846.606135754777;9898.851517227391;9920.206383489447;57191.71404142953;57622.42377576171;57873;58127.70936145292;58653.413465864796;48252.27621585749;48335.274970874765;48973.75513122412;55199.72400138134;84882.15117848743;11644.767104658029;11990.760184796081;12039.939800301292;12152.1493495458;12198.81701774492;13137.408816603467;13255.072144949761;13544.187348759106;14060.296985150906;14534.85465145335;13692.178469291874;13702.725945481234;13732.45070197181;13914.373853176428;13986.860131398556;12285.631431057083;13309.134906231064;13921.930390347983;14332.140071595737;14809.703805923606;12222.87777122253;12364.505419783109;13181.143225690299;13570.86429135696;13953.162810231419;9762.219022478042;9986.850197246902;10139.188864890939;10180;10278.126359259546;10714.089302409382;11308.208425410143;11488.885111148782;11500.482478288664;11684.766304674029 16:46:50 INFO - PROCESS | 28057 | __end_tp_report 16:46:50 INFO - PROCESS | 28057 | __start_cc_report 16:46:50 INFO - PROCESS | 28057 | _x_x_mozilla_cycle_collect,1012 16:46:50 INFO - PROCESS | 28057 | __end_cc_report 16:46:50 INFO - PROCESS | 28057 | __startTimestamp1478389610091__endTimestamp 16:46:50 INFO - PROCESS | 28057 | 16:46:50 INFO - PROCESS | 28057 | ------- Summary: start ------- 16:46:50 INFO - PROCESS | 28057 | Number of tests: 6 16:46:50 INFO - PROCESS | 28057 | 16:46:50 INFO - PROCESS | 28057 | [#0] dojo.html Cycles:165 Average:5592.22 Median:5793.48 stddev:637.75 (11.0%) stddev-sans-first:611.79 16:46:50 INFO - PROCESS | 28057 | Values: 3212.9 3229.5 3238.2 3241.3 3281.5 5613.9 5661.8 5695.4 5716.5 5730.2 5623.7 5708.3 5712.5 5716.6 5718.4 5135.3 5140.5 5144.3 5179.5 5216.5 5024.9 5199.6 5211.0 5216.1 5245.9 4963.5 5012.2 5020.4 5067.2 5086.4 5046.0 5079.4 5083.8 5144.1 5177.7 4975.4 5042.6 5088.1 5105.5 5105.5 5468.0 5471.8 5481.6 5483.3 5501.4 5868.8 5886.2 5920.3 5977.6 5980.9 5928.4 6000.9 6009.9 6016.8 6019.5 5586.5 5589.3 5621.1 5664.4 5667.2 6234.4 6244.1 6246.6 6255.1 6258.2 5718.9 5793.5 5975.3 5985.0 5989.6 5922.9 5985.3 5989.9 6022.0 6031.6 5973.3 5976.4 5977.0 5981.3 5986.4 5772.4 5876.7 5958.7 5967.0 5970.3 4574.6 4593.7 4606.9 4633.4 4658.7 4079.7 4104.2 4107.9 4134.8 4145.0 5543.6 5749.9 5751.3 5753.5 5754.4 5985.7 6018.9 6030.8 6072.5 6122.1 5731.9 5734.8 5738.8 5742.3 5742.5 5131.2 5246.4 5249.6 5253.9 5256.9 6113.3 6122.3 6143.6 6246.6 6252.0 6052.6 6070.8 6072.9 6090.7 6091.9 6067.7 6213.3 6259.5 6284.2 6293.5 5935.9 5962.5 5986.1 6002.4 6010.2 6110.5 6111.4 6120.3 6122.4 6124.1 5813.0 5956.1 5967.6 5967.6 5968.1 5667.7 5832.7 5838.2 5915.5 5949.7 5825.4 5863.2 5873.4 5879.2 5882.2 5676.0 5925.4 5936.5 5967.9 5983.3 5708.9 5755.8 5767.0 5801.6 5835.9 16:46:50 INFO - PROCESS | 28057 | 16:46:50 INFO - PROCESS | 28057 | [#1] ext.html Cycles:180 Average:65441.58 Median:3859.01 stddev:138199.28 (3581.2%) stddev-sans-first:138501.17 16:46:50 INFO - PROCESS | 28057 | Values: 583.8 631.5 653.1 660.3 664.1 34025.3 46079.0 46609.3 46788.0 46800.5 46666.0 47289.3 47314.3 47340.5 47368.3 8381.5 9262.7 9406.5 9408.9 9424.7 10942.2 11215.0 11216.2 11611.1 11612.3 11285.0 11412.7 11415.8 11418.5 11422.4 11175.2 11228.7 11231.6 11246.6 11250.4 11091.2 11146.3 11159.9 11165.6 11171.5 45858.5 46121.3 46182.3 46244.0 46307.0 3778.3 3820.0 3851.1 3867.0 3879.7 34026.7 34271.5 34510.5 34696.8 35774.6 4504.4 4515.4 4552.2 4625.3 4695.6 340777.0 344679.0 344683.0 346186.3 346997.0 36781.3 38120.2 65727.0 66337.7 66387.0 39528.4 39977.0 40015.6 40053.8 40123.8 16521.5 16690.6 16696.7 16700.6 16713.2 12854.0 13708.7 14123.4 14546.4 14732.8 9072.9 9073.6 9127.7 9164.0 9201.7 3494.8 3551.7 3556.4 3558.3 3574.8 1053.4 1108.2 1126.8 1162.1 1190.3 34633.1 35133.1 35232.3 35304.0 35360.0 1107.9 1140.6 1176.5 1202.8 1217.1 16192.7 18550.4 19093.3 19332.1 19401.5 696192.0 702031.0 703269.0 705887.0 706348.0 65909.3 66422.3 66517.0 66566.0 66624.3 1328.6 1429.5 1433.2 1455.5 1464.3 34278.7 35605.1 35760.5 36334.8 36390.6 53492.7 54132.2 54145.7 54195.6 54227.0 431379.0 433975.0 435486.0 435570.0 439918.0 28233.9 29304.0 29453.0 30605.4 31567.5 48594.5 49126.5 49389.8 49763.8 50401.5 41289.4 44792.3 45216.5 46392.5 48858.5 44528.6 45400.5 45593.5 47067.0 47083.8 44716.1 44898.3 45240.8 45384.8 45854.8 38810.2 40388.4 40479.6 40802.4 41301.2 29590.7 30183.8 30434.0 30644.7 30709.0 16:46:50 INFO - PROCESS | 28057 | 16:46:50 INFO - PROCESS | 28057 | [#2] jquery.html Cycles:180 Average:32883.48 Median:24142.84 stddev:99913.45 (413.8%) stddev-sans-first:100163.99 16:46:50 INFO - PROCESS | 28057 | Values: 417.5 428.3 432.8 434.8 445.5 20383.6 20603.9 21090.3 21344.6 21543.1 19600.6 20552.5 20608.5 20745.5 20790.9 5767.0 6043.2 6047.0 6047.8 6049.2 5722.8 5871.3 5874.5 5892.6 5895.5 5876.6 5878.9 5885.2 5886.9 5889.0 5650.2 5819.0 5849.0 5851.5 5852.8 5250.6 5274.6 5282.9 5286.2 5288.0 23873.2 24075.2 24210.5 24395.0 24449.8 2762.9 2792.1 2812.3 3112.8 3129.0 15164.6 15320.7 15346.8 15431.8 16021.3 2028.7 2117.7 2126.5 2133.6 2184.0 58316.7 60235.0 72815.0 80719.6 86121.0 11596.8 11621.3 11736.6 11762.8 11933.3 16142.1 16193.3 16260.0 16277.5 16328.8 9042.7 9090.5 9104.3 9167.0 9206.5 8715.6 8731.5 8810.4 8969.1 9044.6 6987.0 7046.2 7057.6 7145.7 7150.1 2005.5 2035.9 2055.8 2091.0 2094.6 947.5 1050.5 1054.9 1088.4 1117.8 20616.4 21175.8 21787.1 22026.9 22062.6 1079.3 1079.7 1105.6 1131.8 1148.1 11447.3 11768.1 11880.3 11907.8 11921.0 593265.0 598514.0 604311.9 625131.0 629198.0 70115.6 87624.1 93211.0 96590.0 100279.5 1059.7 1098.7 1114.7 1116.8 1117.6 19026.3 19306.5 19451.0 19461.4 19522.5 43893.6 44388.6 44924.0 45674.3 46194.3 63632.4 64285.7 64328.0 65137.3 65360.0 8399.0 8531.9 8713.1 8759.6 8787.2 9727.1 12827.2 13058.8 13341.6 13634.7 11266.0 11365.8 11453.4 11463.5 11545.2 11228.9 11413.7 11454.3 11485.9 11589.6 11391.4 12048.6 12134.4 12190.1 12192.0 8566.5 8626.8 8648.9 8672.2 8784.0 11650.2 11800.9 11864.4 12018.2 12106.8 16:46:50 INFO - PROCESS | 28057 | 16:46:50 INFO - PROCESS | 28057 | [#3] mootools.html Cycles:180 Average:5911.48 Median:4636.30 stddev:3361.71 (72.5%) stddev-sans-first:3354.71 16:46:50 INFO - PROCESS | 28057 | Values: 1488.6 1500.7 1674.7 1822.5 1823.4 8447.0 9107.0 9108.0 9117.6 9127.2 7447.1 7647.3 7659.5 7868.7 8936.0 2351.0 2440.8 2443.8 2462.7 2469.2 2438.6 2446.4 2490.3 2490.7 2496.5 2447.4 2452.6 2465.5 2481.2 2485.4 2376.8 2410.7 2418.4 2422.0 2433.6 2369.9 2371.2 2375.2 2377.6 2378.4 8838.0 8848.5 8917.4 8935.7 8956.1 5237.3 5255.5 5343.9 5357.6 5358.9 3426.5 3445.2 3497.3 3505.7 3521.7 1182.9 1232.7 1243.1 1247.8 1267.6 12548.9 12597.7 12604.1 12613.2 12622.5 4584.7 4687.9 4850.0 4864.4 4883.6 10669.0 10673.1 10674.6 10681.3 10690.1 5467.6 5498.0 5502.5 5504.2 5523.1 4391.2 4904.5 5003.7 5039.6 5073.7 4061.9 4125.7 4168.8 4196.5 4218.8 1320.9 1332.3 1333.5 1334.2 1343.2 956.0 1007.1 1020.7 1024.4 1045.6 5764.2 5971.0 6102.2 6116.9 6153.3 484.2 484.4 488.2 492.1 497.2 4026.5 4051.9 4069.3 4089.6 4120.3 10777.4 11086.5 11176.6 11178.9 11190.0 11084.8 11101.7 11104.9 11107.2 11117.1 7919.3 8293.6 8294.7 8380.5 8493.2 5910.9 5918.7 6059.7 6092.8 6187.8 10418.7 10423.3 10431.1 10433.6 10483.4 10168.3 10352.4 10547.1 10687.5 10697.4 7129.8 7352.8 7751.3 7980.1 8208.5 8592.5 8940.9 8947.9 8949.7 8956.1 7765.8 7942.1 7957.8 8002.6 8107.2 7810.4 7816.4 7925.9 7929.4 7999.0 7205.3 7316.4 7423.7 7436.1 7440.7 5104.6 5181.4 5197.6 5270.9 5287.3 5077.4 5160.0 5279.3 5301.6 5454.0 16:46:50 INFO - PROCESS | 28057 | 16:46:50 INFO - PROCESS | 28057 | [#4] prototype.html Cycles:180 Average:4824.01 Median:5367.77 stddev:1397.09 (26.0%) stddev-sans-first:1359.78 16:46:50 INFO - PROCESS | 28057 | Values: 335.1 348.2 353.2 354.5 355.8 5616.5 5622.6 5639.4 5698.8 5722.9 5243.9 5376.0 5378.0 5382.7 5508.8 4310.8 4345.9 4381.0 4388.0 4434.4 4396.0 4408.6 4410.3 4428.4 4442.2 4305.1 4337.4 4348.5 4357.6 4373.9 4351.4 4354.1 4354.6 4355.7 4359.1 3912.0 3913.7 3947.9 3962.9 3971.2 5192.5 5428.7 5450.8 5462.4 5507.7 4954.7 4975.1 4992.6 4993.2 5021.2 5793.7 5801.2 5803.5 5827.2 5833.7 4116.4 4197.3 4214.5 4219.2 4223.1 6025.5 6027.0 6049.2 6057.9 6061.8 4307.7 4311.4 4359.9 4363.3 4372.9 4166.7 4388.2 4394.7 4397.6 4431.9 4929.8 4954.0 4970.0 4972.6 4979.9 5827.0 5838.8 5847.3 5888.8 5891.8 1423.3 1431.8 1480.2 1499.1 1511.0 1028.3 1029.0 1043.9 1053.2 1055.7 5515.9 5537.8 5571.9 5600.2 5602.6 5827.4 5833.7 5845.2 5849.2 5851.8 5405.3 5420.5 5495.5 5583.6 5622.7 2767.4 2780.8 2797.1 2798.6 2803.7 5303.0 5359.8 5373.8 5380.5 5421.6 4989.9 5314.4 5334.8 5334.9 5355.7 5351.8 5361.7 5375.3 5385.8 5394.6 5163.5 5184.4 5199.5 5209.3 5272.5 5488.5 5900.1 5913.2 5928.1 5933.9 6235.2 6249.6 6253.7 6256.8 6291.8 4216.0 4303.2 4308.8 4314.8 4328.0 6038.4 6038.8 6040.1 6041.5 6046.5 5630.9 5711.3 5841.3 5854.3 5892.9 5852.0 5861.6 5872.3 5878.3 5880.0 5603.9 5794.6 5812.1 5826.4 5837.0 5851.3 5863.0 5863.9 5867.2 5881.4 5614.6 5724.5 5747.5 5793.2 5794.6 16:46:50 INFO - PROCESS | 28057 | 16:46:50 INFO - PROCESS | 28057 | [#5] yui.html Cycles:180 Average:21948.30 Median:4173.71 stddev:33720.59 (807.9%) stddev-sans-first:33777.72 16:46:50 INFO - PROCESS | 28057 | Values: 776.8 802.5 807.7 826.4 909.6 13860.9 14571.5 14606.3 14614.1 14654.6 12965.2 13046.2 13095.9 13105.2 13125.9 706.9 826.7 850.9 865.5 871.6 859.4 866.6 866.7 867.8 867.8 909.9 910.5 911.9 912.9 914.3 912.3 914.7 914.8 915.2 916.4 671.4 671.7 672.6 672.8 673.0 13044.9 13053.5 13060.3 13073.6 13084.4 15227.7 15717.7 15756.4 15781.5 15978.7 11664.4 11907.1 11935.8 11948.2 11991.3 4059.2 4109.6 4145.7 4173.2 4174.2 83290.6 84112.0 84938.6 85430.6 86804.1 11706.2 11950.7 12003.5 12242.8 12304.5 21141.7 21145.9 21169.6 21178.4 21267.7 8623.4 8714.5 8994.2 9150.1 9596.3 4979.5 4980.4 5023.8 5138.2 5184.3 3740.2 3851.2 3856.3 3856.9 3860.2 633.2 638.7 648.2 649.6 649.7 684.9 688.7 790.6 796.3 804.5 9806.3 10280.8 10288.5 10436.8 11078.9 737.3 748.0 748.1 760.8 768.6 4869.2 4925.1 4942.0 4970.3 5088.2 95935.0 134491.3 135738.3 139070.3 146849.0 114767.0 118251.4 119741.4 122346.4 123409.0 95140.0 95494.5 96033.0 96371.0 100144.0 9515.6 9768.5 9846.6 9898.9 9920.2 57191.7 57622.4 57873.0 58127.7 58653.4 48252.3 48335.3 48973.8 55199.7 84882.2 11644.8 11990.8 12039.9 12152.1 12198.8 13137.4 13255.1 13544.2 14060.3 14534.9 13692.2 13702.7 13732.5 13914.4 13986.9 12285.6 13309.1 13921.9 14332.1 14809.7 12222.9 12364.5 13181.1 13570.9 13953.2 9762.2 9986.9 10139.2 10180.0 10278.1 10714.1 11308.2 11488.9 11500.5 11684.8 16:46:50 INFO - PROCESS | 28057 | -------- Summary: end -------- 16:46:50 INFO - PROCESS | 28057 | 16:46:50 INFO - TEST-INFO | 28057: exit 0 16:46:50 INFO - TEST-OK | dromaeo_css | took 1113208ms 16:46:50 INFO - TEST-START | kraken 16:46:50 INFO - operating with platform_type : linux_ 16:46:50 INFO - Initialising browser for kraken test... 16:46:50 INFO - TEST-INFO | started process 28254 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdf7Wbp/profile http://localhost:53765/getInfo.html) 16:46:54 INFO - PROCESS | 28254 | __metrics Screen width/height:1600/1200 16:46:54 INFO - PROCESS | 28254 | colorDepth:24 16:46:54 INFO - PROCESS | 28254 | Browser inner width/height: 1024/697 16:46:54 INFO - PROCESS | 28254 | __metrics 16:46:55 INFO - PROCESS | 28254 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:46:55 INFO - PROCESS | 28254 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:46:56 INFO - TEST-INFO | 28254: exit 0 16:46:56 INFO - Browser initialized. 16:46:56 INFO - Running cycle 1/1 for kraken test... 16:46:56 INFO - TEST-INFO | started process 28344 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdf7Wbp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:47:08 INFO - PROCESS | 28344 | RSS: Main: 144642048 16:47:08 INFO - PROCESS | 28344 | 16:47:10 INFO - PROCESS | 28344 | Cycle 1(1): loaded http://localhost:53765/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53765/tests/kraken/driver.html?testName=audio-beat-detection) 16:47:11 INFO - PROCESS | 28344 | RSS: Main: 199499776 16:47:11 INFO - PROCESS | 28344 | 16:47:14 INFO - PROCESS | 28344 | Cycle 1(1): loaded http://localhost:53765/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53765/tests/kraken/driver.html?testName=audio-dft) 16:47:14 INFO - PROCESS | 28344 | RSS: Main: 183406592 16:47:14 INFO - PROCESS | 28344 | 16:47:17 INFO - PROCESS | 28344 | Cycle 1(1): loaded http://localhost:53765/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53765/tests/kraken/driver.html?testName=audio-fft) 16:47:18 INFO - PROCESS | 28344 | RSS: Main: 275103744 16:47:18 INFO - PROCESS | 28344 | 16:47:20 INFO - PROCESS | 28344 | Cycle 1(1): loaded http://localhost:53765/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53765/tests/kraken/driver.html?testName=audio-oscillator) 16:47:21 INFO - PROCESS | 28344 | RSS: Main: 180531200 16:47:21 INFO - PROCESS | 28344 | 16:47:23 INFO - PROCESS | 28344 | Cycle 1(1): loaded http://localhost:53765/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53765/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:47:23 INFO - PROCESS | 28344 | RSS: Main: 179920896 16:47:23 INFO - PROCESS | 28344 | 16:47:26 INFO - PROCESS | 28344 | Cycle 1(1): loaded http://localhost:53765/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53765/tests/kraken/driver.html?testName=imaging-darkroom) 16:47:27 INFO - PROCESS | 28344 | RSS: Main: 216006656 16:47:27 INFO - PROCESS | 28344 | 16:47:30 INFO - PROCESS | 28344 | Cycle 1(1): loaded http://localhost:53765/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53765/tests/kraken/driver.html?testName=imaging-desaturate) 16:47:31 INFO - PROCESS | 28344 | RSS: Main: 214171648 16:47:31 INFO - PROCESS | 28344 | 16:47:33 INFO - PROCESS | 28344 | Cycle 1(1): loaded http://localhost:53765/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53765/tests/kraken/driver.html?testName=json-parse-financial) 16:47:34 INFO - PROCESS | 28344 | RSS: Main: 213913600 16:47:34 INFO - PROCESS | 28344 | 16:47:36 INFO - PROCESS | 28344 | Cycle 1(1): loaded http://localhost:53765/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53765/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:47:36 INFO - PROCESS | 28344 | RSS: Main: 176713728 16:47:36 INFO - PROCESS | 28344 | 16:47:38 INFO - PROCESS | 28344 | Cycle 1(1): loaded http://localhost:53765/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53765/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:47:38 INFO - PROCESS | 28344 | RSS: Main: 184205312 16:47:38 INFO - PROCESS | 28344 | 16:47:40 INFO - PROCESS | 28344 | Cycle 1(1): loaded http://localhost:53765/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53765/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:47:41 INFO - PROCESS | 28344 | RSS: Main: 190517248 16:47:41 INFO - PROCESS | 28344 | 16:47:44 INFO - PROCESS | 28344 | Cycle 1(1): loaded http://localhost:53765/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53765/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:47:44 INFO - PROCESS | 28344 | RSS: Main: 198656000 16:47:44 INFO - PROCESS | 28344 | 16:47:47 INFO - PROCESS | 28344 | Cycle 1(1): loaded http://localhost:53765/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53765/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:47:47 INFO - PROCESS | 28344 | RSS: Main: 195072000 16:47:47 INFO - PROCESS | 28344 | 16:47:49 INFO - PROCESS | 28344 | Cycle 1(1): loaded http://localhost:53765/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53765/tests/kraken/driver.html?testName=ai-astar) 16:47:49 INFO - PROCESS | 28344 | RSS: Main: 243683328 16:47:49 INFO - PROCESS | 28344 | 16:47:49 INFO - PROCESS | 28344 | __start_tp_report 16:47:49 INFO - PROCESS | 28344 | _x_x_mozilla_page_load 16:47:49 INFO - PROCESS | 28344 | _x_x_mozilla_page_load_details 16:47:49 INFO - PROCESS | 28344 | |i|pagename|runs| 16:47:49 INFO - PROCESS | 28344 | |0;ai-astar;111;111;120;109;110;112;110;112;108;110 16:47:49 INFO - PROCESS | 28344 | |1;audio-beat-detection;147;116;150;184;121;142;145;123;172;116 16:47:49 INFO - PROCESS | 28344 | |2;audio-dft;161;161;161;163;162;160;160;161;160;159 16:47:49 INFO - PROCESS | 28344 | |3;audio-fft;75;75;77;175;77;76;77;76;157;76 16:47:49 INFO - PROCESS | 28344 | |4;audio-oscillator;83;85;162;84;83;84;134;83;83;153 16:47:49 INFO - PROCESS | 28344 | |5;imaging-gaussian-blur;116;117;117;117;117;116;117;116;116;117 16:47:49 INFO - PROCESS | 28344 | |6;imaging-darkroom;146;147;147;145;146;146;146;146;147;146 16:47:49 INFO - PROCESS | 28344 | |7;imaging-desaturate;94;95;96;94;95;94;95;94;95;94 16:47:49 INFO - PROCESS | 28344 | |8;json-parse-financial;54;52;54;53;53;52;53;52;51;53 16:47:49 INFO - PROCESS | 28344 | |9;json-stringify-tinderbox;60;59;61;60;60;59;60;59;60;60 16:47:49 INFO - PROCESS | 28344 | |10;stanford-crypto-aes;72;77;81;73;74;82;73;73;78;75 16:47:49 INFO - PROCESS | 28344 | |11;stanford-crypto-ccm;119;120;121;127;118;120;117;117;173;134 16:47:49 INFO - PROCESS | 28344 | |12;stanford-crypto-pbkdf2;153;153;152;155;152;157;157;152;158;151 16:47:49 INFO - PROCESS | 28344 | |13;stanford-crypto-sha256-iterative;64;61;61;62;60;64;65;61;60;60 16:47:49 INFO - PROCESS | 28344 | __end_tp_report 16:47:49 INFO - PROCESS | 28344 | __start_cc_report 16:47:49 INFO - PROCESS | 28344 | _x_x_mozilla_cycle_collect,1670 16:47:49 INFO - PROCESS | 28344 | __end_cc_report 16:47:49 INFO - PROCESS | 28344 | __startTimestamp1478389669836__endTimestamp 16:47:49 INFO - PROCESS | 28344 | 16:47:49 INFO - PROCESS | 28344 | ------- Summary: start ------- 16:47:49 INFO - PROCESS | 28344 | Number of tests: 14 16:47:49 INFO - PROCESS | 28344 | 16:47:49 INFO - PROCESS | 28344 | [#0] ai-astar Cycles:10 Average:111.30 Median:111.00 stddev:3.30 (3.0%) stddev-sans-first:3.50 16:47:49 INFO - PROCESS | 28344 | Values: 111.0 111.0 120.0 109.0 110.0 112.0 110.0 112.0 108.0 110.0 16:47:49 INFO - PROCESS | 28344 | 16:47:49 INFO - PROCESS | 28344 | [#1] audio-beat-detection Cycles:10 Average:141.60 Median:146.00 stddev:23.37 (16.0%) stddev-sans-first:24.70 16:47:49 INFO - PROCESS | 28344 | Values: 147.0 116.0 150.0 184.0 121.0 142.0 145.0 123.0 172.0 116.0 16:47:49 INFO - PROCESS | 28344 | 16:47:49 INFO - PROCESS | 28344 | [#2] audio-dft Cycles:10 Average:160.80 Median:161.00 stddev:1.14 (0.7%) stddev-sans-first:1.20 16:47:49 INFO - PROCESS | 28344 | Values: 161.0 161.0 161.0 163.0 162.0 160.0 160.0 161.0 160.0 159.0 16:47:49 INFO - PROCESS | 28344 | 16:47:49 INFO - PROCESS | 28344 | [#3] audio-fft Cycles:10 Average:94.10 Median:76.00 stddev:38.14 (50.2%) stddev-sans-first:39.82 16:47:49 INFO - PROCESS | 28344 | Values: 75.0 75.0 77.0 175.0 77.0 76.0 77.0 76.0 157.0 76.0 16:47:49 INFO - PROCESS | 28344 | 16:47:49 INFO - PROCESS | 28344 | [#4] audio-oscillator Cycles:10 Average:103.40 Median:83.00 stddev:32.64 (39.3%) stddev-sans-first:33.77 16:47:49 INFO - PROCESS | 28344 | Values: 83.0 85.0 162.0 84.0 83.0 84.0 134.0 83.0 83.0 153.0 16:47:49 INFO - PROCESS | 28344 | 16:47:49 INFO - PROCESS | 28344 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.50 16:47:49 INFO - PROCESS | 28344 | Values: 116.0 117.0 117.0 117.0 117.0 116.0 117.0 116.0 116.0 117.0 16:47:49 INFO - PROCESS | 28344 | 16:47:49 INFO - PROCESS | 28344 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:146.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 16:47:49 INFO - PROCESS | 28344 | Values: 146.0 147.0 147.0 145.0 146.0 146.0 146.0 146.0 147.0 146.0 16:47:49 INFO - PROCESS | 28344 | 16:47:49 INFO - PROCESS | 28344 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.70 (0.7%) stddev-sans-first:0.71 16:47:49 INFO - PROCESS | 28344 | Values: 94.0 95.0 96.0 94.0 95.0 94.0 95.0 94.0 95.0 94.0 16:47:49 INFO - PROCESS | 28344 | 16:47:49 INFO - PROCESS | 28344 | [#8] json-parse-financial Cycles:10 Average:52.70 Median:53.00 stddev:0.95 (1.8%) stddev-sans-first:0.88 16:47:49 INFO - PROCESS | 28344 | Values: 54.0 52.0 54.0 53.0 53.0 52.0 53.0 52.0 51.0 53.0 16:47:49 INFO - PROCESS | 28344 | 16:47:49 INFO - PROCESS | 28344 | [#9] json-stringify-tinderbox Cycles:10 Average:59.80 Median:60.00 stddev:0.63 (1.1%) stddev-sans-first:0.67 16:47:49 INFO - PROCESS | 28344 | Values: 60.0 59.0 61.0 60.0 60.0 59.0 60.0 59.0 60.0 60.0 16:47:49 INFO - PROCESS | 28344 | 16:47:49 INFO - PROCESS | 28344 | [#10] stanford-crypto-aes Cycles:10 Average:75.80 Median:76.00 stddev:3.55 (4.7%) stddev-sans-first:3.49 16:47:49 INFO - PROCESS | 28344 | Values: 72.0 77.0 81.0 73.0 74.0 82.0 73.0 73.0 78.0 75.0 16:47:49 INFO - PROCESS | 28344 | 16:47:49 INFO - PROCESS | 28344 | [#11] stanford-crypto-ccm Cycles:10 Average:126.60 Median:120.50 stddev:17.13 (14.2%) stddev-sans-first:17.95 16:47:49 INFO - PROCESS | 28344 | Values: 119.0 120.0 121.0 127.0 118.0 120.0 117.0 117.0 173.0 134.0 16:47:49 INFO - PROCESS | 28344 | 16:47:49 INFO - PROCESS | 28344 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.00 Median:154.00 stddev:2.54 (1.6%) stddev-sans-first:2.67 16:47:49 INFO - PROCESS | 28344 | Values: 153.0 153.0 152.0 155.0 152.0 157.0 157.0 152.0 158.0 151.0 16:47:49 INFO - PROCESS | 28344 | 16:47:49 INFO - PROCESS | 28344 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.80 Median:61.50 stddev:1.87 (3.0%) stddev-sans-first:1.81 16:47:49 INFO - PROCESS | 28344 | Values: 64.0 61.0 61.0 62.0 60.0 64.0 65.0 61.0 60.0 60.0 16:47:49 INFO - PROCESS | 28344 | -------- Summary: end -------- 16:47:49 INFO - PROCESS | 28344 | 16:47:50 INFO - TEST-INFO | 28344: exit 0 16:47:50 INFO - TEST-OK | kraken | took 60031ms 16:47:50 INFO - SUITE-END | took 1173s 16:47:51 INFO - Completed test suite (00:19:33) 16:47:51 INFO - javascript benchmark 16:47:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3212.8875489357874, 3229.531717900904, 3238.2228265216354, 3241.2707140893317, 3281.5077738339246, 5613.887722245558, 5661.830145095643, 5695.430456954307, 5716.514096301809, 5730.169125476808, 5623.718814059301, 5708.315002199742, 5712.457316554931, 5716.628419152763, 5718.428157184279, 5135.255387968742, 5140.485951404858, 5144.25408315794, 5179.482051794828, 5216.452272511388, 5024.949750502503, 5199.584033277329, 5210.973945130269, 5216.087184742655, 5245.868853278679, 4963.45402005779, 5012.172991456406, 5020.447750747415, 5067.189249720045, 5086.440491545945, 5046.0, 5079.365079365061, 5083.847484575469, 5144.099782538078, 5177.663451875616, 4975.427825800043, 5042.571381432583, 5088.109580823341, 5105.463926287724, 5105.489451054902, 5467.972660136674, 5471.8084867029465, 5481.643693159932, 5483.314585676791, 5501.4223506532035, 5868.823935281948, 5886.175935369051, 5920.289565252154, 5977.641341519479, 5980.880382392328, 5928.377520360344, 6000.939990600125, 6009.939900600982, 6016.759329626853, 6019.518438524908, 5586.469285417878, 5589.329280486327, 5621.100623900197, 5664.405237450091, 5667.1782591524, 6234.407731265523, 6244.063390491372, 6246.593971391845, 6255.0617407388345, 6258.186435763321, 5718.942810571924, 5793.478586927196, 5975.282966044016, 5985.042393217064, 5989.580729348903, 5922.851928701817, 5985.34161242263, 5989.850253743692, 6022.0, 6031.607945483617, 5973.283206015219, 5976.402359763989, 5977.043673012297, 5981.312149102821, 5986.4013598639785, 5772.422757724194, 5876.676782776988, 5958.731857066442, 5967.0, 5970.283565972111, 4574.611158051536, 4593.724376537419, 4606.930896036562, 4633.4208223972, 4658.697184682985, 4079.6532294754684, 4104.179164167179, 4107.9178416431505, 4134.834606615762, 4145.025918909054, 5543.6396634218645, 5749.942500574941, 5751.338596061423, 5753.482186603226, 5754.424557544296, 5985.730642121115, 6018.879622407528, 6030.788922387783, 6072.483838873657, 6122.051082082285, 5731.885362292731, 5734.77060917567, 5738.8278351649515, 5742.3396309424115, 5742.483176514133, 5131.153359695609, 5246.422893481714, 5249.606279529051, 5253.921191182136, 5256.868578285574, 6113.263474096239, 6122.2761798032625, 6143.631382117087, 6246.593971391936, 6252.030935205142, 6052.636841789506, 6070.817875463743, 6072.939270607238, 6090.712312096581, 6091.878162436815, 6067.666278354733, 6213.254409470712, 6259.499240060693, 6284.183056202664, 6293.527985401205, 5935.881282374242, 5962.493188079062, 5986.132010858314, 6002.369751176064, 6010.248718910137, 6110.51115910735, 6111.388861114031, 6120.296165940974, 6122.357152498924, 6124.050772130327, 5812.9709351452975, 5956.13636022783, 5967.6121052131475, 5967.6121052131475, 5968.074948383008, 5667.716614169358, 5832.650040997553, 5838.241028666246, 5915.497182739343, 5949.672767997628, 5825.417458254141, 5863.1791549184, 5873.353931067664, 5879.235699359227, 5882.23530940975, 5675.971620141873, 5925.40745925404, 5936.46571808522, 5967.880642387041, 5983.34183239852, 5708.914366284426, 5755.798547050833, 5767.048437007847, 5801.564882633735, 5835.94164058371], "value": 5548.885481603096, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [583.7723287917713, 631.4727202785674, 653.1085068880978, 660.3132741948377, 664.1233571685372, 34025.31949361014, 46079.0, 46609.30086048712, 46787.999999999956, 46800.531994680045, 46666.0, 47289.290660640116, 47314.290285645744, 47340.526594734045, 47368.28947565789, 8381.497110173379, 9262.675806346797, 9406.529673516297, 9408.858867116998, 9424.717258482257, 10942.17933654975, 11215.046721028722, 11216.214864959456, 11611.129165312594, 11612.303261804318, 11285.04077153443, 11412.714682132972, 11415.771684566304, 11418.543258269701, 11422.42887855608, 11175.161862860277, 11228.66314010577, 11231.550737970472, 11246.606368777135, 11250.3812290324, 11091.168162387772, 11146.27549209307, 11159.888401115966, 11165.609203677832, 11171.497282622302, 45858.54141458609, 46121.308180377324, 46182.30726539105, 46244.0, 46307.0, 3778.301014312361, 3820.0, 3851.056491159684, 3866.961330386688, 3879.7478163919536, 34026.65973340284, 34271.4859277111, 34510.48234276488, 34696.82651586726, 35774.64225357765, 4504.391907092533, 4515.435570553681, 4552.203364411214, 4625.259958406672, 4695.577825499102, 340777.0, 344679.0, 344683.0, 346186.26906865556, 346997.0, 36781.26437471236, 38120.23759524794, 65727.0, 66337.6683116591, 66387.0, 39528.40707389391, 39977.0, 40015.59984400119, 40053.799731001156, 40123.79938100291, 16521.504354869132, 16690.582735431468, 16696.66606667884, 16700.58248543796, 16713.247903844356, 12854.0, 13708.725825483434, 14123.435062597586, 14546.418143274363, 14732.779008314883, 9072.90927090734, 9073.59168837404, 9127.726168214964, 9164.037776033554, 9201.723948281562, 3494.7553671243277, 3551.698105660996, 3556.3776339140545, 3558.252766918976, 3574.821258937043, 1053.378506681062, 1108.1854836695022, 1126.830975353687, 1162.0761494611804, 1190.3334132885611, 34633.134171645914, 35133.121671958404, 35232.29535409278, 35303.953182849305, 35360.0, 1107.8670559532907, 1140.6235942139074, 1176.5352685689277, 1202.7714734200474, 1217.11150859871, 16192.676146477004, 18550.44348669542, 19093.331733389266, 19332.130054147598, 19401.514962125777, 696192.0, 702031.0, 703269.0, 705887.0, 706348.0, 65909.34090659033, 66422.33577664258, 66517.0, 66566.0, 66624.33375666279, 1328.6014195741238, 1429.5496918470649, 1433.1830856411746, 1455.5415044261024, 1464.2605484230608, 34278.657213428545, 35605.10987225237, 35760.46359304665, 36334.8183259082, 36390.63609363978, 53492.732536338626, 54132.18801717899, 54145.72927135339, 54195.645108872595, 54227.0, 431379.0, 433975.0, 435486.0, 435570.0, 439918.0, 28233.858830705714, 29304.0, 29453.0, 30605.387892241586, 31567.526487102252, 48594.514054859, 49126.50873491362, 49389.75305123451, 49763.75118124531, 50401.49598503968, 41289.380659290735, 44792.328115077646, 45216.54783452123, 46392.536074638825, 48858.511414886816, 44528.554714453734, 45400.54599454095, 45593.54406455893, 47067.0, 47083.76458117824, 44716.10567788691, 44898.326525102806, 45240.773796132125, 45384.77307613573, 45854.770726146155, 38810.223795523365, 40388.394174087996, 40479.59520404876, 40802.387964179965, 41301.1739765197, 29590.704092958797, 30183.849080754455, 30434.0, 30644.693553064182, 30709.0], "value": 20743.959304312186, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [417.5177669791392, 428.2911781002441, 432.7922597153366, 434.7934731002773, 445.4543184598867, 20383.592328153427, 20603.896980515095, 21090.261840835574, 21344.57310853782, 21543.138274469, 19600.607987840234, 20552.486187845312, 20608.484787880312, 20745.481362965933, 20790.89604551979, 5766.9711651441785, 6043.1841701370195, 6047.0, 6047.788327408542, 6049.153118563405, 5722.771089156428, 5871.29544454666, 5874.5006674432725, 5892.587518873681, 5895.469407753301, 5876.6180198286975, 5878.88242235155, 5885.176075349455, 5886.88226235475, 5888.97055514724, 5650.180723795047, 5819.0, 5849.0, 5851.473367396933, 5852.76588936442, 5250.6324557281005, 5274.630775845692, 5282.867928301823, 5286.15421532553, 5288.048151332759, 23873.164439244716, 24075.157369492157, 24210.515789684283, 24395.0, 24449.755502445103, 2762.9447411051665, 2792.1204820481485, 2812.3109838089595, 3112.7821052526333, 3128.9530657040164, 15164.620884477867, 15320.693586128327, 15346.769798453031, 15431.845681543153, 16021.279042443119, 2028.7261219735294, 2117.697615966185, 2126.5109024924354, 2133.637281662121, 2183.9781602184094, 58316.708416458074, 60235.0, 72815.0, 80719.5964020182, 86120.99999999937, 11596.826047609293, 11621.302721836548, 11736.589219377282, 11762.764744705059, 11933.343666098273, 16142.112183830002, 16193.271302791403, 16260.0, 16277.511674649779, 16328.75506867398, 9042.734017237592, 9090.54547272647, 9104.317176211678, 9166.954165229265, 9206.493642849708, 8715.607797649121, 8731.476111433334, 8810.414125457351, 8969.103089690978, 9044.638214471348, 6987.0, 7046.224915259318, 7057.630589300841, 7145.714171433085, 7150.10623672041, 2005.4785755703622, 2035.9389218323474, 2055.8340156159347, 2090.968635470469, 2094.5601423700887, 947.4930911962067, 1050.5242710749, 1054.9208809339177, 1088.4231357380602, 1117.8155604325357, 20616.38150855477, 21175.78824211769, 21787.12851485923, 22026.88986555057, 22062.558748824933, 1079.3469950679882, 1079.6545105566302, 1105.5743538737484, 1131.801934661431, 1148.1388958281289, 11447.25592836463, 11768.117391195694, 11880.287182768889, 11907.821382679267, 11921.0, 593265.0, 598514.0, 604311.9137617223, 625131.0, 629198.0, 70115.64942175256, 87624.12375876159, 93211.0, 96590.0, 100279.49860250652, 1059.708580140452, 1098.747288123736, 1114.6934592986993, 1116.810142275815, 1117.5529788084832, 19026.334078307194, 19306.517337066125, 19451.0, 19461.416157515298, 19522.511937201685, 43893.561064390226, 44388.55799685183, 44924.0, 45674.31488527741, 46194.30708539308, 63632.36367636264, 64285.67857160871, 64328.0, 65137.34862651502, 65360.019599707, 8399.0, 8531.8720219198, 8713.085126061673, 8759.649614015369, 8787.209151176425, 9727.07592778685, 12827.230366178059, 13058.804117938429, 13341.599752007456, 13634.727305454035, 11266.0, 11365.829512557484, 11453.427328633368, 11463.541458341573, 11545.191836571359, 11228.943855280999, 11413.714657133638, 11454.312741235553, 11485.885141148816, 11589.594364197214, 11391.41303916502, 12048.578299759467, 12134.393280335771, 12190.146689731633, 12192.024638028759, 8566.52884091381, 8626.827463450822, 8648.870266945874, 8672.176143266379, 8784.0, 11650.155618502353, 11800.881991179978, 11864.406779661154, 12018.218815776947, 12106.757864842477], "value": 9925.393631892897, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1488.6278430392401, 1500.7223663622228, 1674.6650669866021, 1822.489702883193, 1823.3618233618226, 8447.0, 9106.99999999999, 9108.043655416168, 9117.589708463118, 9127.224185944186, 7447.143578488475, 7647.311741943224, 7659.540427574349, 7868.685252589904, 8936.0, 2351.0478256306224, 2440.7803297703203, 2443.816713746476, 2462.6675398821108, 2469.1851688942697, 2438.563327032131, 2446.412860913386, 2490.3027152397267, 2490.68866391701, 2496.500699860026, 2447.449323902126, 2452.583060879646, 2465.48224872776, 2481.2060140754966, 2485.3538080099124, 2376.7742064503846, 2410.7348191698898, 2418.443757935676, 2421.9515609687796, 2433.6471211674207, 2369.869657168855, 2371.205646108556, 2375.1924345722364, 2377.5720370333333, 2378.4410663494064, 8838.027816940132, 8848.51333176675, 8917.375783695144, 8935.748995140686, 8956.149165829236, 5237.3453318335205, 5255.500727430926, 5343.919841202385, 5357.624966252364, 5358.946410535884, 3426.468897320919, 3445.173158441981, 3497.265574229415, 3505.719542436599, 3521.683048525645, 1182.9290242585387, 1232.6548566401423, 1243.104964425612, 1247.8440194975628, 1267.575362253646, 12548.937255313665, 12597.685057873536, 12604.11771176018, 12613.180143290656, 12622.495100195982, 4584.679072464962, 4687.929681054787, 4849.975750121297, 4864.367632207861, 4883.609311255091, 10669.0, 10673.146148308117, 10674.57301707923, 10681.252312338062, 10690.144788416908, 5467.589930755209, 5498.037843377393, 5502.477264659851, 5504.2294078829, 5523.143912693539, 4391.165678521071, 4904.509549045067, 5003.674761140514, 5039.571636410946, 5073.670211436245, 4061.857834975821, 4125.690573207022, 4168.812403441852, 4196.496420429568, 4218.831246750157, 1320.8613095625012, 1332.253937794838, 1333.4666133546466, 1334.2128144394853, 1343.1940835498622, 955.9856602150974, 1007.1489591295343, 1020.6682828080903, 1024.4416792847917, 1045.581767293089, 5764.193012978187, 5971.014782560916, 6102.237220347457, 6116.908246376308, 6153.261608606996, 484.21557077533913, 484.4477295882697, 488.2359107995975, 492.1117383123425, 497.1870990929856, 4026.5168179818024, 4051.918961620751, 4069.267531844237, 4089.5705950875326, 4120.340745480708, 10777.407242601732, 11086.501107450184, 11176.552937882394, 11178.944105279421, 11190.048845848194, 11084.833727494093, 11101.666949991513, 11104.888951110546, 11107.222494425474, 11117.110631149488, 7919.2872641462545, 8293.626786794488, 8294.668213271521, 8380.497170169809, 8493.193146651058, 5910.911336329872, 5918.704064796829, 6059.666718330358, 6092.817215483543, 6187.84530386744, 10418.68743937683, 10423.270371073952, 10431.11335536488, 10433.634822781167, 10483.37099774016, 10168.33905796121, 10352.378857268588, 10547.103496202908, 10687.5190616424, 10697.358158510515, 7129.750458733919, 7352.816179595553, 7751.3411360035025, 7980.12218655959, 8208.507489550646, 8592.527410992456, 8940.910590894267, 8947.865782013145, 8949.731508054769, 8956.104389560991, 7765.844683106419, 7942.12636609984, 7957.7612671619945, 8002.639881205476, 8107.189281072082, 7810.414218933716, 7816.4137689674635, 7925.920740792519, 7929.444938854225, 7998.960005199937, 7205.279472052963, 7316.378107860892, 7423.703051877865, 7436.070491188601, 7440.665170067252, 5104.617153713412, 5181.37823461172, 5197.610179236648, 5270.8945821084135, 5287.286216360742, 5077.441481436965, 5159.974200128975, 5279.313689220377, 5301.602379821606, 5454.018276710231], "value": 4682.889612084924, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [335.1437078265033, 348.2460473075791, 353.1559572621434, 354.485995306805, 355.8185325484003, 5616.52259557938, 5622.550195984321, 5639.407862174469, 5698.800541981026, 5722.856928576788, 5243.895122097556, 5376.0, 5378.0, 5382.67703937764, 5508.779648814053, 4310.849120280791, 4345.914400775438, 4381.014271788854, 4387.978060109695, 4434.357018232355, 4395.978020109895, 4408.589251439541, 4410.250257456224, 4428.446444194476, 4442.222611043071, 4305.1487860220095, 4337.368557059641, 4348.456442944632, 4357.564243575633, 4373.868783936471, 4351.390805287262, 4354.107407981356, 4354.608085272341, 4355.716878402894, 4359.149965756678, 3912.0219945013746, 3913.7456065355946, 3947.940780888289, 3962.920741585152, 3971.1660551284267, 5192.480751924815, 5428.6742795432, 5450.809221677222, 5462.371827239878, 5507.669539827583, 4954.711774938506, 4975.134324628264, 4992.575631071363, 4993.176125939216, 5021.196608542652, 5793.7392817323325, 5801.216835727165, 5803.506701930299, 5827.155062515912, 5833.687420330391, 4116.423700681907, 4197.286461301586, 4214.473190851144, 4219.2405367033925, 4223.092035212444, 6025.487833534155, 6026.975414179644, 6049.153118563405, 6057.9398605243905, 6061.818145455643, 4307.655387568987, 4311.374850646702, 4359.912801743947, 4363.280058790264, 4372.868813935587, 4166.687498437629, 4388.232059389594, 4394.714343567658, 4397.6042156206095, 4431.933520997188, 4929.7781599828095, 4953.975230123899, 4969.975150124226, 4972.552470277692, 4979.90040199194, 5826.970865145647, 5838.803045375773, 5847.3335099496435, 5888.764449421975, 5891.764329426775, 1423.309694798022, 1431.77807439847, 1480.2302802542833, 1499.137995652504, 1510.9546713598609, 1028.2647906746713, 1029.0429900192744, 1043.9008294212038, 1053.210092430677, 1055.7413433708791, 5515.944840551623, 5537.833864984057, 5571.916421253684, 5600.159976003551, 5602.635828671123, 5827.446392592697, 5833.708314584339, 5845.181674565564, 5849.151872978479, 5851.765929362863, 5405.270288511001, 5420.53925416344, 5495.477929596682, 5583.553315734732, 5622.718864056863, 2767.3911739417294, 2780.7914406419195, 2797.090945442739, 2798.636177296979, 2803.663560372768, 5303.0, 5359.785608575691, 5373.838784836461, 5380.542653874464, 5421.620486565981, 4989.850304490871, 5314.441983591744, 5334.786608535693, 5334.946650533522, 5355.651882627617, 5351.839444816739, 5361.705106219196, 5375.274337964403, 5385.784568617211, 5394.568434525228, 5163.4578369270685, 5184.370940341701, 5199.516324062963, 5209.291168592235, 5272.525472707322, 5488.533474654699, 5900.144479050599, 5913.231279933581, 5928.117083310439, 5933.851653708692, 6235.220597425322, 6249.562530622995, 6253.687315634109, 6256.7810126645345, 6291.822629572037, 4216.0, 4303.182395344875, 4308.827646894089, 4314.784260786885, 4328.0, 6038.426349496791, 6038.7958395186315, 6040.063790112539, 6041.462585714098, 6046.516278697782, 5630.943690563042, 5711.314642242957, 5841.269841269841, 5854.297484301911, 5892.852678683067, 5851.97590421666, 5861.560382971209, 5872.324682661549, 5878.295294364757, 5879.9710050741805, 5603.915941260802, 5794.594378393472, 5812.128180772918, 5826.359100498857, 5836.993948116638, 5851.327097383855, 5863.00328944072, 5863.853403664943, 5867.237259156283, 5881.38245484218, 5614.635048721983, 5724.4847963681805, 5747.511461525817, 5793.159991801081, 5794.594378393472], "value": 4382.713385567092, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [776.8368642585057, 802.4984384759525, 807.6769292283086, 826.4215049465375, 909.5588639509837, 13860.930695346522, 14571.489997850078, 14606.269686515658, 14614.123152610851, 14654.63363415915, 12965.222086674807, 13046.217226966388, 13095.934520327384, 13105.213687178775, 13125.93437032816, 706.9045678833369, 826.7271800305884, 850.8681154421043, 865.4980111535294, 871.6252011634995, 859.3984211052257, 866.5927014303268, 866.7313132928781, 867.8134201146752, 867.8437881181385, 909.8953620333647, 910.4582773249939, 911.8723378726984, 912.8630705394178, 914.2777206007246, 912.2656261709321, 914.6524320758136, 914.8033172867832, 915.1763412928351, 916.3814425262921, 671.4427025568766, 671.7279501801778, 672.6065251827638, 672.7544446277122, 672.9798106056826, 13044.934775326157, 13053.477860885554, 13060.34698265083, 13073.607791766262, 13084.411201495956, 15227.695446091016, 15717.685646287124, 15756.44852430171, 15781.493156697621, 15978.680426391524, 11664.416779161093, 11907.106966977512, 11935.820962685566, 11948.223365481303, 11991.280523168638, 4059.2084543514025, 4109.609587089221, 4145.689073319513, 4173.165366926627, 4174.248635245654, 83290.58354708187, 84112.0, 84938.57530712368, 85430.57284713537, 86804.13195868024, 11706.180567360203, 11950.701232469259, 12003.459844307026, 12242.755144897052, 12304.507819687113, 21141.68287475689, 21145.894270528548, 21169.576608467745, 21178.364649060553, 21267.680984785242, 8623.439476434012, 8714.52070136136, 8994.235489983294, 9150.084991500797, 9596.280278979104, 4979.526944940225, 4980.352554167935, 5023.824166154167, 5138.177891537336, 5184.326037615161, 3740.233252183296, 3851.1527463957887, 3856.267309211297, 3856.8842934712, 3860.1507668312925, 633.1610615933906, 638.655126231839, 648.2188962300417, 649.6297110646981, 649.7271146118642, 684.8630273945131, 688.721067967474, 790.6397809310431, 796.2873228460495, 804.4690504267272, 9806.31355805101, 10280.845787313196, 10288.537015834305, 10436.791264174826, 11078.944605277084, 737.2664199121908, 747.9738209162762, 748.1022772672815, 760.8326168242978, 768.6425811997336, 4869.2228927041015, 4925.088858561177, 4941.975290123598, 4970.2793095001025, 5088.160453525201, 95935.0, 134491.32754336167, 135738.32130839283, 139070.3046484761, 146849.0, 114767.0, 118251.40874295746, 119741.40129299297, 122346.38826805809, 123409.0, 95140.0, 95494.52252738691, 96033.0, 96371.0, 100144.0, 9515.571799269188, 9768.511574421393, 9846.606135754777, 9898.851517227391, 9920.206383489447, 57191.71404142953, 57622.42377576171, 57873.0, 58127.70936145292, 58653.413465864796, 48252.27621585749, 48335.274970874765, 48973.75513122412, 55199.72400138134, 84882.15117848743, 11644.767104658029, 11990.760184796081, 12039.939800301292, 12152.1493495458, 12198.81701774492, 13137.408816603467, 13255.072144949761, 13544.187348759106, 14060.296985150906, 14534.85465145335, 13692.178469291874, 13702.725945481234, 13732.45070197181, 13914.373853176428, 13986.860131398556, 12285.631431057083, 13309.134906231064, 13921.930390347983, 14332.140071595737, 14809.703805923606, 12222.87777122253, 12364.505419783109, 13181.143225690299, 13570.86429135696, 13953.162810231419, 9762.219022478042, 9986.850197246902, 10139.188864890939, 10180.0, 10278.126359259546, 10714.089302409382, 11308.208425410143, 11488.885111148782, 11500.482478288664, 11684.766304674029], "value": 7747.788712212194, "unit": "score"}], "name": "dromaeo_css", "value": 7525.824455979065, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 111.0, 120.0, 109.0, 110.0, 112.0, 110.0, 112.0, 108.0, 110.0], "value": 111.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 116.0, 150.0, 184.0, 121.0, 142.0, 145.0, 123.0, 172.0, 116.0], "value": 141.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 161.0, 163.0, 162.0, 160.0, 160.0, 161.0, 160.0, 159.0], "value": 160.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 77.0, 175.0, 77.0, 76.0, 77.0, 76.0, 157.0, 76.0], "value": 94.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 162.0, 84.0, 83.0, 84.0, 134.0, 83.0, 83.0, 153.0], "value": 103.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 116.0, 116.0, 117.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 147.0, 145.0, 146.0, 146.0, 146.0, 146.0, 147.0, 146.0], "value": 146.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 96.0, 94.0, 95.0, 94.0, 95.0, 94.0, 95.0, 94.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 52.0, 54.0, 53.0, 53.0, 52.0, 53.0, 52.0, 51.0, 53.0], "value": 52.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 59.0, 61.0, 60.0, 60.0, 59.0, 60.0, 59.0, 60.0, 60.0], "value": 59.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 77.0, 81.0, 73.0, 74.0, 82.0, 73.0, 73.0, 78.0, 75.0], "value": 75.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 120.0, 121.0, 127.0, 118.0, 120.0, 117.0, 117.0, 173.0, 134.0], "value": 126.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 153.0, 152.0, 155.0, 152.0, 157.0, 157.0, 152.0, 158.0, 151.0], "value": 154.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 61.0, 61.0, 62.0, 60.0, 64.0, 65.0, 61.0, 60.0, 60.0], "value": 61.8, "unit": "score"}], "name": "kraken", "value": 1499.3, "alertThreshold": 2.0}]} 16:47:51 INFO - Return code: 0 16:47:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:47:51 INFO - # TBPL SUCCESS # 16:47:51 INFO - Running post-action listener: _resource_record_post_action 16:47:51 INFO - [mozharness: 2016-11-05 23:47:51.129336Z] Finished run-tests step (success) 16:47:51 INFO - Running post-run listener: _resource_record_post_run 16:47:51 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 1736704; Write bytes: 288722944; Read time: 76; Write time: 266500 16:47:51 INFO - TinderboxPrint: CPU usage
12.9% 16:47:51 INFO - TinderboxPrint: I/O read bytes / time
1,736,704 / 76 16:47:51 INFO - TinderboxPrint: I/O write bytes / time
288,722,944 / 266,500 16:47:51 INFO - TinderboxPrint: CPU idle
8,249.2 (87.0%) 16:47:51 INFO - TinderboxPrint: CPU user
1,182.5 (12.5%) 16:47:51 INFO - TinderboxPrint: Swap in / out
0 / 0 16:47:51 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 71393280; Read time: 0; Write time: 105268 16:47:51 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 4096; Write bytes: 217329664; Read time: 20; Write time: 161232 16:47:51 INFO - Running post-run listener: _upload_blobber_files 16:47:51 INFO - Blob upload gear active. 16:47:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:47:51 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 16:47:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:47:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:47:52 INFO - (blobuploader) - INFO - Open directory for files ... 16:47:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:47:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:47:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:47:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:47:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:47:52 INFO - (blobuploader) - INFO - Done attempting. 16:47:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:47:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:47:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:47:56 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:47:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:47:56 INFO - (blobuploader) - INFO - Done attempting. 16:47:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:47:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:47:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:47:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:47:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:47:57 INFO - (blobuploader) - INFO - Done attempting. 16:47:57 INFO - (blobuploader) - INFO - Iteration through files over. 16:47:57 INFO - Return code: 0 16:47:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:47:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:47:57 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f2467f95079cd70bbb66a659dfe3a27fca9b0efb02909c1705abe3899adc8cbd482d2b6400ebd955d66e9a7a6790a82a67125135dd3cf7b1375f7544af5d0f15", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0ab7b800e61ba10726362b5b5f894848337e34a4b8728aba7bfcc418d4a3ec0ba638f4716aef68a369951926b19608c513b9034c1ad247b23831ba9a1b6e851a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4e4f9c40088c241c1ca5d6b95f91387b1e21766a72e821f4ecab35979f03d6a83c9235ae19a5048f774d4b48d0e3f6ee2b96e0f0b7754559daf01c611d2045c8"} 16:47:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:47:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:47:57 INFO - Contents: 16:47:57 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f2467f95079cd70bbb66a659dfe3a27fca9b0efb02909c1705abe3899adc8cbd482d2b6400ebd955d66e9a7a6790a82a67125135dd3cf7b1375f7544af5d0f15", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0ab7b800e61ba10726362b5b5f894848337e34a4b8728aba7bfcc418d4a3ec0ba638f4716aef68a369951926b19608c513b9034c1ad247b23831ba9a1b6e851a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4e4f9c40088c241c1ca5d6b95f91387b1e21766a72e821f4ecab35979f03d6a83c9235ae19a5048f774d4b48d0e3f6ee2b96e0f0b7754559daf01c611d2045c8"} 16:47:57 INFO - Running post-run listener: copy_logs_to_upload_dir 16:47:57 INFO - Copying logs to upload dir... 16:47:57 INFO - mkdir: /builds/slave/test/build/upload/logs 16:47:57 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1674.404725 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 54 secs) (at 2016-11-05 16:47:56.649979) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-05 16:47:56.653589) ========= 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=cbe409c6de289db6ac707de3000001d4-1478388000.885512-1859117507 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f2467f95079cd70bbb66a659dfe3a27fca9b0efb02909c1705abe3899adc8cbd482d2b6400ebd955d66e9a7a6790a82a67125135dd3cf7b1375f7544af5d0f15", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0ab7b800e61ba10726362b5b5f894848337e34a4b8728aba7bfcc418d4a3ec0ba638f4716aef68a369951926b19608c513b9034c1ad247b23831ba9a1b6e851a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4e4f9c40088c241c1ca5d6b95f91387b1e21766a72e821f4ecab35979f03d6a83c9235ae19a5048f774d4b48d0e3f6ee2b96e0f0b7754559daf01c611d2045c8"} build_url:https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011488 build_url: 'https://queue.taskcluster.net/v1/task/cPEudMkWRHGTOZCvuHLilg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f2467f95079cd70bbb66a659dfe3a27fca9b0efb02909c1705abe3899adc8cbd482d2b6400ebd955d66e9a7a6790a82a67125135dd3cf7b1375f7544af5d0f15", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0ab7b800e61ba10726362b5b5f894848337e34a4b8728aba7bfcc418d4a3ec0ba638f4716aef68a369951926b19608c513b9034c1ad247b23831ba9a1b6e851a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4e4f9c40088c241c1ca5d6b95f91387b1e21766a72e821f4ecab35979f03d6a83c9235ae19a5048f774d4b48d0e3f6ee2b96e0f0b7754559daf01c611d2045c8"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-05 16:47:56.680382) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-05 16:47:56.680729) ========= 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=cbe409c6de289db6ac707de3000001d4-1478388000.885512-1859117507 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004427 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-05 16:47:56.764079) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-05 16:47:56.764394) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-05 16:47:56.764725) ========= ========= Total master_lag: 0.16 =========