builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-047 starttime: 1471905598.3 results: success (0) buildid: 20160822124915 builduid: 63bc2500c2334edda63f0710f73a35f8 revision: 07189ee916253fe9692528551ad1b96bc6c1bccc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-22 15:39:58.300920) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-22 15:39:58.301386) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-22 15:39:58.301655) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-22 15:39:58.316054) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 15:39:58.316415) ========= 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=03627c79f6bb3d1578c4a402000001ba-1471905597.564307-1900834039 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-22 15:39:58-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 32.4M=0s 2016-08-22 15:39:58 (32.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.086962 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 15:39:58.420119) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 15:39:58.420468) ========= 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=03627c79f6bb3d1578c4a402000001ba-1471905597.564307-1900834039 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020540 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 15:39:58.469809) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-22 15:39:58.473255) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 07189ee916253fe9692528551ad1b96bc6c1bccc --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 07189ee916253fe9692528551ad1b96bc6c1bccc --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=03627c79f6bb3d1578c4a402000001ba-1471905597.564307-1900834039 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-22 15:39:58,705 truncating revision to first 12 chars 2016-08-22 15:39:58,705 Setting DEBUG logging. 2016-08-22 15:39:58,705 attempt 1/10 2016-08-22 15:39:58,705 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/07189ee91625?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-22 15:39:59,421 unpacking tar archive at: mozilla-aurora-07189ee91625/testing/mozharness/ program finished with exit code 0 elapsedTime=1.001097 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-22 15:39:59.487954) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-22 15:39:59.488309) ========= script_repo_revision: 07189ee916253fe9692528551ad1b96bc6c1bccc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-22 15:39:59.488702) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-22 15:39:59.488976) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-22 15:39:59.497574) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 0 secs) (at 2016-08-22 15:39:59.497965) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=03627c79f6bb3d1578c4a402000001ba-1471905597.564307-1900834039 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:39:59 INFO - MultiFileLogger online at 20160822 15:39:59 in /builds/slave/test 15:39:59 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 15:39:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:39:59 INFO - {'append_to_log': False, 15:39:59 INFO - 'base_work_dir': '/builds/slave/test', 15:39:59 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 15:39:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:39:59 INFO - 'branch': 'Mozilla-Aurora', 15:39:59 INFO - 'buildbot_json_path': 'buildprops.json', 15:39:59 INFO - 'config_files': ('talos/linux_config.py',), 15:39:59 INFO - 'default_actions': ('clobber', 15:39:59 INFO - 'read-buildbot-config', 15:39:59 INFO - 'download-and-extract', 15:39:59 INFO - 'populate-webroot', 15:39:59 INFO - 'create-virtualenv', 15:39:59 INFO - 'install', 15:39:59 INFO - 'run-tests'), 15:39:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:39:59 INFO - 'download_minidump_stackwalk': True, 15:39:59 INFO - 'download_symbols': 'ondemand', 15:39:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:39:59 INFO - 'tooltool.py': '/tools/tooltool.py', 15:39:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:39:59 INFO - '/tools/misc-python/virtualenv.py')}, 15:39:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:39:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:39:59 INFO - 'installer_path': 'installer.exe', 15:39:59 INFO - 'log_level': 'info', 15:39:59 INFO - 'log_name': 'talos', 15:39:59 INFO - 'log_to_console': True, 15:39:59 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:39:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:39:59 INFO - 'opt_config_files': (), 15:39:59 INFO - 'pip_index': False, 15:39:59 INFO - 'sps_profile': False, 15:39:59 INFO - 'sps_profile_interval': 0, 15:39:59 INFO - 'suite': 'dromaeojs-e10s', 15:39:59 INFO - 'system_bits': '32', 15:39:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:39:59 INFO - 'title': 'talos-linux64-ix-047', 15:39:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:39:59 INFO - 'use_talos_json': True, 15:39:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:39:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:39:59 INFO - 'work_dir': 'build'} 15:39:59 INFO - [mozharness: 2016-08-22 22:39:59.822940Z] Running clobber step. 15:39:59 INFO - Running pre-action listener: _resource_record_pre_action 15:39:59 INFO - Running main action method: clobber 15:39:59 INFO - rmtree: /builds/slave/test/build 15:39:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:40:00 INFO - Running post-action listener: _resource_record_post_action 15:40:00 INFO - [mozharness: 2016-08-22 22:40:00.290556Z] Finished clobber step (success) 15:40:00 INFO - [mozharness: 2016-08-22 22:40:00.290659Z] Running read-buildbot-config step. 15:40:00 INFO - Running pre-action listener: _resource_record_pre_action 15:40:00 INFO - Running main action method: read_buildbot_config 15:40:00 INFO - Using buildbot properties: 15:40:00 INFO - { 15:40:00 INFO - "project": "", 15:40:00 INFO - "product": "firefox", 15:40:00 INFO - "script_repo_revision": "production", 15:40:00 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s", 15:40:00 INFO - "repository": "", 15:40:00 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs-e10s", 15:40:00 INFO - "buildid": "20160822124915", 15:40:00 INFO - "pgo_build": "True", 15:40:00 INFO - "basedir": "/builds/slave/test", 15:40:00 INFO - "buildnumber": 501, 15:40:00 INFO - "slavename": "talos-linux64-ix-047", 15:40:00 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 15:40:00 INFO - "platform": "ubuntu64_hw", 15:40:00 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 15:40:00 INFO - "branch": "mozilla-aurora", 15:40:00 INFO - "repo_path": "releases/mozilla-aurora", 15:40:00 INFO - "revision": "07189ee916253fe9692528551ad1b96bc6c1bccc", 15:40:00 INFO - "stage_platform": "linux64-pgo", 15:40:00 INFO - "builduid": "63bc2500c2334edda63f0710f73a35f8", 15:40:00 INFO - "slavebuilddir": "test" 15:40:00 INFO - } 15:40:00 INFO - Found installer url https://queue.taskcluster.net/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 15:40:00 INFO - Running post-action listener: _resource_record_post_action 15:40:00 INFO - [mozharness: 2016-08-22 22:40:00.293118Z] Finished read-buildbot-config step (success) 15:40:00 INFO - [mozharness: 2016-08-22 22:40:00.293208Z] Running download-and-extract step. 15:40:00 INFO - Running pre-action listener: _resource_record_pre_action 15:40:00 INFO - Running main action method: download_and_extract 15:40:00 INFO - mkdir: /builds/slave/test/build/tests 15:40:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:40:00 INFO - https://queue.taskcluster.net/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 15:40:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 15:40:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 15:40:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 15:40:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 15:40:00 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 15:40:00 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 15:40:00 INFO - retry: Failed, sleeping 30 seconds before retrying 15:40:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #2 15:40:30 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 15:40:30 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 15:40:30 INFO - retry: Failed, sleeping 60 seconds before retrying 15:41:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #3 15:41:30 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 15:41:30 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 15:41:30 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json! 15:41:30 INFO - Caught exception: HTTP Error 404: Not Found 15:41:30 INFO - Caught exception: HTTP Error 404: Not Found 15:41:30 INFO - Caught exception: HTTP Error 404: Not Found 15:41:30 INFO - trying https://queue.taskcluster.net/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 15:41:30 INFO - Downloading https://queue.taskcluster.net/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 15:41:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 15:41:30 INFO - Downloaded 1361 bytes. 15:41:30 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 15:41:30 INFO - Using the following test package requirements: 15:41:30 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 15:41:30 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 15:41:30 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 15:41:30 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 15:41:30 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 15:41:30 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 15:41:30 INFO - u'jsshell-linux-x86_64.zip'], 15:41:30 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 15:41:30 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 15:41:30 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 15:41:30 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 15:41:30 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 15:41:30 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 15:41:30 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 15:41:30 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 15:41:30 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 15:41:30 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 15:41:30 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 15:41:30 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:41:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:41:30 INFO - https://queue.taskcluster.net/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:41:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 15:41:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 15:41:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 15:41:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:41:30 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 15:41:30 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 15:41:30 INFO - retry: Failed, sleeping 30 seconds before retrying 15:42:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 15:42:01 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 15:42:01 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 15:42:01 INFO - retry: Failed, sleeping 60 seconds before retrying 15:43:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 15:43:01 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 15:43:01 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 15:43:01 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 15:43:01 INFO - Caught exception: HTTP Error 404: Not Found 15:43:01 INFO - Caught exception: HTTP Error 404: Not Found 15:43:01 INFO - Caught exception: HTTP Error 404: Not Found 15:43:01 INFO - trying https://queue.taskcluster.net/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 15:43:01 INFO - Downloading https://queue.taskcluster.net/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 15:43:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:43:01 INFO - Downloaded 21767602 bytes. 15:43:01 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:43:01 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 15:43:03 INFO - Return code: 0 15:43:03 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 15:43: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')]} 15:43:03 INFO - https://queue.taskcluster.net/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:43:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 15:43:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 15:43:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 15:43:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:43:03 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 15:43:03 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 15:43:03 INFO - retry: Failed, sleeping 30 seconds before retrying 15:43:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 15:43:33 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 15:43:33 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 15:43:33 INFO - retry: Failed, sleeping 60 seconds before retrying 15:44:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 15:44:33 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 15:44:33 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 15:44:33 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 15:44:33 INFO - Caught exception: HTTP Error 404: Not Found 15:44:33 INFO - Caught exception: HTTP Error 404: Not Found 15:44:33 INFO - Caught exception: HTTP Error 404: Not Found 15:44:33 INFO - trying https://queue.taskcluster.net/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 15:44:33 INFO - Downloading https://queue.taskcluster.net/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 15:44:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:44:37 INFO - Downloaded 21767602 bytes. 15:44:37 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:44:37 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 15:44:38 INFO - Return code: 0 15:44: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')]} 15:44:38 INFO - https://queue.taskcluster.net/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 15:44:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 15:44:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 15:44:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 15:44:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 15:44:38 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 15:44:38 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 15:44:38 INFO - retry: Failed, sleeping 30 seconds before retrying 15:45:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 15:45:08 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 15:45:08 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 15:45:08 INFO - retry: Failed, sleeping 60 seconds before retrying 15:46:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 15:46:08 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 15:46:08 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 15:46:08 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip! 15:46:08 INFO - Caught exception: HTTP Error 404: Not Found 15:46:08 INFO - Caught exception: HTTP Error 404: Not Found 15:46:08 INFO - Caught exception: HTTP Error 404: Not Found 15:46:08 INFO - trying https://queue.taskcluster.net/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 15:46:08 INFO - Downloading https://queue.taskcluster.net/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 15:46:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 15:46:09 INFO - Downloaded 14413723 bytes. 15:46:09 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:46:09 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 15:46:09 INFO - Return code: 0 15:46: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')]} 15:46:09 INFO - https://queue.taskcluster.net/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 15:46:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 15:46:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 15:46:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 15:46:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 15:46:09 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 15:46:09 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 15:46:09 INFO - retry: Failed, sleeping 30 seconds before retrying 15:46:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #2 15:46:39 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 15:46:39 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 15:46:39 INFO - retry: Failed, sleeping 60 seconds before retrying 15:47:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #3 15:47:39 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 15:47:39 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 15:47:39 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2! 15:47:39 INFO - Caught exception: HTTP Error 404: Not Found 15:47:39 INFO - Caught exception: HTTP Error 404: Not Found 15:47:39 INFO - Caught exception: HTTP Error 404: Not Found 15:47:39 INFO - trying https://queue.taskcluster.net/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 15:47:39 INFO - Downloading https://queue.taskcluster.net/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 15:47:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 15:47:40 INFO - Downloaded 63747869 bytes. 15:47:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 15:47:40 INFO - mkdir: /builds/slave/test/properties 15:47:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:47:40 INFO - Writing to file /builds/slave/test/properties/build_url 15:47:40 INFO - Contents: 15:47:40 INFO - build_url:https://queue.taskcluster.net/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 15:47:41 INFO - Running post-action listener: _resource_record_post_action 15:47:41 INFO - Running post-action listener: set_extra_try_arguments 15:47:41 INFO - [mozharness: 2016-08-22 22:47:41.691224Z] Finished download-and-extract step (success) 15:47:41 INFO - [mozharness: 2016-08-22 22:47:41.691460Z] Running populate-webroot step. 15:47:41 INFO - Running pre-action listener: _resource_record_pre_action 15:47:41 INFO - Running main action method: populate_webroot 15:47:41 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:47:41 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:47:41 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:47:41 INFO - '--tppagecycles', 15:47:41 INFO - '7'], 15:47:41 INFO - 'tests': ['tsvgm']}}, 15:47:41 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:47:41 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:47:41 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:47:41 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:47:41 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:47:41 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:47:41 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:47:41 INFO - 'talos_options': ['--disable-e10s'], 15:47:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:47:41 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:47:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:47:41 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:47:41 INFO - 'talos_options': ['--disable-e10s'], 15:47:41 INFO - 'tests': ['damp', 'tps']}, 15:47:41 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:47:41 INFO - 'tests': ['damp', 'tps']}, 15:47:41 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:47:41 INFO - 'tests': ['dromaeo_dom']}, 15:47:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:47:41 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:47:41 INFO - 'tests': ['basic_compositor_video']}, 15:47:41 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:47:41 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:47:41 INFO - 'tests': ['a11yr', 15:47:41 INFO - 'ts_paint', 15:47:41 INFO - 'tpaint', 15:47:41 INFO - 'sessionrestore', 15:47:41 INFO - 'sessionrestore_no_auto_restore', 15:47:41 INFO - 'tabpaint']}, 15:47:41 INFO - 'other-e10s': {'tests': ['a11yr', 15:47:41 INFO - 'ts_paint', 15:47:41 INFO - 'tpaint', 15:47:41 INFO - 'sessionrestore', 15:47:41 INFO - 'sessionrestore_no_auto_restore', 15:47:41 INFO - 'tabpaint']}, 15:47:41 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:47:41 INFO - 'ts_paint', 15:47:41 INFO - 'tpaint', 15:47:41 INFO - 'sessionrestore', 15:47:41 INFO - 'sessionrestore_no_auto_restore', 15:47:41 INFO - 'tabpaint']}, 15:47:41 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:47:41 INFO - 'ts_paint', 15:47:41 INFO - 'tpaint', 15:47:41 INFO - 'sessionrestore', 15:47:41 INFO - 'sessionrestore_no_auto_restore', 15:47:41 INFO - 'tabpaint']}, 15:47:41 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:47:41 INFO - 'tests': ['a11yr', 15:47:41 INFO - 'ts_paint', 15:47:41 INFO - 'tpaint', 15:47:41 INFO - 'sessionrestore', 15:47:41 INFO - 'sessionrestore_no_auto_restore', 15:47:41 INFO - 'tabpaint']}, 15:47:41 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:47:41 INFO - 'tests': ['a11yr', 15:47:41 INFO - 'ts_paint', 15:47:41 INFO - 'tpaint', 15:47:41 INFO - 'sessionrestore', 15:47:41 INFO - 'sessionrestore_no_auto_restore', 15:47:41 INFO - 'tabpaint']}, 15:47:41 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:47:41 INFO - 'tests': ['tsvgx', 15:47:41 INFO - 'tsvgr_opacity', 15:47:41 INFO - 'tart', 15:47:41 INFO - 'tscrollx', 15:47:41 INFO - 'cart']}, 15:47:41 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:47:41 INFO - 'tsvgr_opacity', 15:47:41 INFO - 'tart', 15:47:41 INFO - 'tscrollx', 15:47:41 INFO - 'cart']}, 15:47:41 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:47:41 INFO - 'talos_options': ['--disable-e10s'], 15:47:41 INFO - 'tests': ['tp5o']}, 15:47:41 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:47:41 INFO - 'tests': ['tp5o']}, 15:47:41 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:47:41 INFO - 'talos_options': ['--disable-e10s', 15:47:41 INFO - '--xperf_path', 15:47:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:47:41 INFO - 'tests': ['tp5n']}, 15:47:41 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:47:41 INFO - 'talos_options': ['--xperf_path', 15:47:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:47:41 INFO - 'tests': ['tp5n']}}, 15:47:41 INFO - 'talos.zip': {'path': '', 15:47:41 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:47:41 INFO - Running post-action listener: _resource_record_post_action 15:47:41 INFO - [mozharness: 2016-08-22 22:47:41.711146Z] Finished populate-webroot step (success) 15:47:41 INFO - [mozharness: 2016-08-22 22:47:41.711235Z] Running create-virtualenv step. 15:47:41 INFO - Running pre-action listener: _resource_record_pre_action 15:47:41 INFO - Running main action method: create_virtualenv 15:47:41 INFO - Creating virtualenv /builds/slave/test/build/venv 15:47: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 15:47:41 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:47:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:47:41 INFO - Using real prefix '/usr' 15:47:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:47:42 INFO - Installing distribute.............................................................................................................................................................................................done. 15:47:44 INFO - Installing pip.................done. 15:47:44 INFO - Return code: 0 15:47:44 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:47: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')]} 15:47:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:47:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47: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')]} 15:47:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:47:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47: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 0x2344030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2573400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2602570>, '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 0x2603470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2603900>, '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': '03627c79f6bb3d1578c4a402000001ba-1471905597.564307-1900834039', '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 15:47: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 15:47: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 15:47:44 INFO - Using env: {'DISPLAY': ':0', 15:47:44 INFO - 'HOME': '/home/cltbld', 15:47:44 INFO - 'LANG': 'en_US.UTF-8', 15:47:44 INFO - 'LANGUAGE': 'en_US:en', 15:47:44 INFO - 'LOGNAME': 'cltbld', 15:47:44 INFO - 'MAIL': '/var/mail/cltbld', 15:47:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:44 INFO - 'MOZ_NO_REMOTE': '1', 15:47:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:47:44 INFO - 'NO_EM_RESTART': '1', 15:47:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:47:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:47:44 INFO - 'PWD': '/builds/slave/test', 15:47:44 INFO - 'SHELL': '/bin/bash', 15:47:44 INFO - 'SHLVL': '1', 15:47:44 INFO - 'TERM': 'linux', 15:47:44 INFO - 'TMOUT': '86400', 15:47:44 INFO - 'USER': 'cltbld', 15:47:44 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1471905597.564307-1900834039', 15:47:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:44 INFO - '_': '/tools/buildbot/bin/python'} 15:47:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:47: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 15:47:44 INFO - Cleaning up... 15:47:44 INFO - Return code: 0 15:47:44 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:47: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')]} 15:47:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:47:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47: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')]} 15:47:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:47:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47: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 0x2344030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2573400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2602570>, '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 0x2603470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2603900>, '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': '03627c79f6bb3d1578c4a402000001ba-1471905597.564307-1900834039', '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 15:47: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 15:47: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 15:47:44 INFO - Using env: {'DISPLAY': ':0', 15:47:44 INFO - 'HOME': '/home/cltbld', 15:47:44 INFO - 'LANG': 'en_US.UTF-8', 15:47:44 INFO - 'LANGUAGE': 'en_US:en', 15:47:44 INFO - 'LOGNAME': 'cltbld', 15:47:44 INFO - 'MAIL': '/var/mail/cltbld', 15:47:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:44 INFO - 'MOZ_NO_REMOTE': '1', 15:47:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:47:44 INFO - 'NO_EM_RESTART': '1', 15:47:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:47:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:47:44 INFO - 'PWD': '/builds/slave/test', 15:47:44 INFO - 'SHELL': '/bin/bash', 15:47:44 INFO - 'SHLVL': '1', 15:47:44 INFO - 'TERM': 'linux', 15:47:44 INFO - 'TMOUT': '86400', 15:47:44 INFO - 'USER': 'cltbld', 15:47:44 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1471905597.564307-1900834039', 15:47:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:44 INFO - '_': '/tools/buildbot/bin/python'} 15:47:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:47:44 INFO - Downloading/unpacking psutil>=3.1.1 15:47: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 15:47: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 15:47: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 15:47: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 15:47: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 15:47: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 15:47:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:47:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:47:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:47:46 INFO - Installing collected packages: psutil 15:47:46 INFO - Running setup.py install for psutil 15:47:46 INFO - building 'psutil._psutil_linux' extension 15:47: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 15:47:46 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 15:47:46 INFO - building 'psutil._psutil_posix' extension 15:47:46 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 15:47: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 15:47:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:47:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:47:47 INFO - Successfully installed psutil 15:47:47 INFO - Cleaning up... 15:47:47 INFO - Return code: 0 15:47:47 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:47: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')]} 15:47:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:47:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47: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')]} 15:47:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:47:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47: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 0x2344030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2573400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2602570>, '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 0x2603470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2603900>, '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': '03627c79f6bb3d1578c4a402000001ba-1471905597.564307-1900834039', '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 15:47: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 15:47: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 15:47:47 INFO - Using env: {'DISPLAY': ':0', 15:47:47 INFO - 'HOME': '/home/cltbld', 15:47:47 INFO - 'LANG': 'en_US.UTF-8', 15:47:47 INFO - 'LANGUAGE': 'en_US:en', 15:47:47 INFO - 'LOGNAME': 'cltbld', 15:47:47 INFO - 'MAIL': '/var/mail/cltbld', 15:47:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:47 INFO - 'MOZ_NO_REMOTE': '1', 15:47:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:47:47 INFO - 'NO_EM_RESTART': '1', 15:47:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:47:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:47:47 INFO - 'PWD': '/builds/slave/test', 15:47:47 INFO - 'SHELL': '/bin/bash', 15:47:47 INFO - 'SHLVL': '1', 15:47:47 INFO - 'TERM': 'linux', 15:47:47 INFO - 'TMOUT': '86400', 15:47:47 INFO - 'USER': 'cltbld', 15:47:47 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1471905597.564307-1900834039', 15:47:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:47 INFO - '_': '/tools/buildbot/bin/python'} 15:47:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:47:47 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:47: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 15:47: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 15:47: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 15:47: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 15:47: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 15:47: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 15:47:49 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:47:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:47:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:47:49 INFO - Installing collected packages: mozsystemmonitor 15:47:49 INFO - Running setup.py install for mozsystemmonitor 15:47:49 INFO - Successfully installed mozsystemmonitor 15:47:49 INFO - Cleaning up... 15:47:49 INFO - Return code: 0 15:47:49 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:47: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')]} 15:47:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:47:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47: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')]} 15:47:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:47:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47: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 0x2344030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2573400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2602570>, '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 0x2603470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2603900>, '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': '03627c79f6bb3d1578c4a402000001ba-1471905597.564307-1900834039', '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 15:47: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 15:47: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 15:47:49 INFO - Using env: {'DISPLAY': ':0', 15:47:49 INFO - 'HOME': '/home/cltbld', 15:47:49 INFO - 'LANG': 'en_US.UTF-8', 15:47:49 INFO - 'LANGUAGE': 'en_US:en', 15:47:49 INFO - 'LOGNAME': 'cltbld', 15:47:49 INFO - 'MAIL': '/var/mail/cltbld', 15:47:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:49 INFO - 'MOZ_NO_REMOTE': '1', 15:47:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:47:49 INFO - 'NO_EM_RESTART': '1', 15:47:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:47:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:47:49 INFO - 'PWD': '/builds/slave/test', 15:47:49 INFO - 'SHELL': '/bin/bash', 15:47:49 INFO - 'SHLVL': '1', 15:47:49 INFO - 'TERM': 'linux', 15:47:49 INFO - 'TMOUT': '86400', 15:47:49 INFO - 'USER': 'cltbld', 15:47:49 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1471905597.564307-1900834039', 15:47:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:49 INFO - '_': '/tools/buildbot/bin/python'} 15:47:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:47:49 INFO - Downloading/unpacking jsonschema==2.5.1 15:47: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 15:47: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 15:47: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 15:47: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 15:47: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 15:47: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 15:47:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:47:51 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:47: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 15:47: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 15:47: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 15:47: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 15:47: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 15:47: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 15:47:51 INFO - Downloading functools32-3.2.3-2.tar.gz 15:47:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:47:51 INFO - warning: no files found matching '*.txt' 15:47:51 INFO - no previously-included directories found matching 'build' 15:47:51 INFO - no previously-included directories found matching 'dist' 15:47:51 INFO - no previously-included directories found matching '.git*' 15:47:51 INFO - Installing collected packages: jsonschema, functools32 15:47:51 INFO - Running setup.py install for functools32 15:47:52 INFO - warning: no files found matching '*.txt' 15:47:52 INFO - no previously-included directories found matching 'build' 15:47:52 INFO - no previously-included directories found matching 'dist' 15:47:52 INFO - no previously-included directories found matching '.git*' 15:47:52 INFO - Successfully installed jsonschema functools32 15:47:52 INFO - Cleaning up... 15:47:52 INFO - Return code: 0 15:47:52 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:47: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')]} 15:47:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:47:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47: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')]} 15:47:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:47:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47: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 0x2344030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2573400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2602570>, '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 0x2603470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2603900>, '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': '03627c79f6bb3d1578c4a402000001ba-1471905597.564307-1900834039', '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 15:47: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 15:47: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 15:47:52 INFO - Using env: {'DISPLAY': ':0', 15:47:52 INFO - 'HOME': '/home/cltbld', 15:47:52 INFO - 'LANG': 'en_US.UTF-8', 15:47:52 INFO - 'LANGUAGE': 'en_US:en', 15:47:52 INFO - 'LOGNAME': 'cltbld', 15:47:52 INFO - 'MAIL': '/var/mail/cltbld', 15:47:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:52 INFO - 'MOZ_NO_REMOTE': '1', 15:47:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:47:52 INFO - 'NO_EM_RESTART': '1', 15:47:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:47:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:47:52 INFO - 'PWD': '/builds/slave/test', 15:47:52 INFO - 'SHELL': '/bin/bash', 15:47:52 INFO - 'SHLVL': '1', 15:47:52 INFO - 'TERM': 'linux', 15:47:52 INFO - 'TMOUT': '86400', 15:47:52 INFO - 'USER': 'cltbld', 15:47:52 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1471905597.564307-1900834039', 15:47:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:52 INFO - '_': '/tools/buildbot/bin/python'} 15:47:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:47:52 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:47:52 INFO - Cleaning up... 15:47:52 INFO - Return code: 0 15:47:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:47: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')]} 15:47:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:47:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47: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')]} 15:47:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:47:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47: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 0x2344030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2573400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2602570>, '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 0x2603470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2603900>, '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': '03627c79f6bb3d1578c4a402000001ba-1471905597.564307-1900834039', '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 15:47: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 15:47: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 15:47:52 INFO - Using env: {'DISPLAY': ':0', 15:47:52 INFO - 'HOME': '/home/cltbld', 15:47:52 INFO - 'LANG': 'en_US.UTF-8', 15:47:52 INFO - 'LANGUAGE': 'en_US:en', 15:47:52 INFO - 'LOGNAME': 'cltbld', 15:47:52 INFO - 'MAIL': '/var/mail/cltbld', 15:47:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:52 INFO - 'MOZ_NO_REMOTE': '1', 15:47:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:47:52 INFO - 'NO_EM_RESTART': '1', 15:47:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:47:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:47:52 INFO - 'PWD': '/builds/slave/test', 15:47:52 INFO - 'SHELL': '/bin/bash', 15:47:52 INFO - 'SHLVL': '1', 15:47:52 INFO - 'TERM': 'linux', 15:47:52 INFO - 'TMOUT': '86400', 15:47:52 INFO - 'USER': 'cltbld', 15:47:52 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1471905597.564307-1900834039', 15:47:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:52 INFO - '_': '/tools/buildbot/bin/python'} 15:47:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:47:52 INFO - Downloading/unpacking blobuploader==1.2.4 15:47: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 15:47: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 15:47: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 15:47: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 15:47: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 15:47: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 15:47:54 INFO - Downloading blobuploader-1.2.4.tar.gz 15:47:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:47:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:47: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 15:47: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 15:47: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 15:47: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 15:47: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 15:47: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 15:47:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:47:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:47: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 15:47: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 15:47: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 15:47: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 15:47: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 15:47: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 15:47:55 INFO - Downloading docopt-0.6.1.tar.gz 15:47:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:47:55 INFO - Installing collected packages: blobuploader, requests, docopt 15:47:55 INFO - Running setup.py install for blobuploader 15:47:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:47:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:47:55 INFO - Running setup.py install for requests 15:47:55 INFO - Running setup.py install for docopt 15:47:55 INFO - Successfully installed blobuploader requests docopt 15:47:55 INFO - Cleaning up... 15:47:55 INFO - Return code: 0 15:47:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:47: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')]} 15:47:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:47:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47: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')]} 15:47:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:47:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47: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 0x2344030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2573400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2602570>, '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 0x2603470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2603900>, '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': '03627c79f6bb3d1578c4a402000001ba-1471905597.564307-1900834039', '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 15:47: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 15:47:55 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 15:47:55 INFO - Using env: {'DISPLAY': ':0', 15:47:55 INFO - 'HOME': '/home/cltbld', 15:47:55 INFO - 'LANG': 'en_US.UTF-8', 15:47:55 INFO - 'LANGUAGE': 'en_US:en', 15:47:55 INFO - 'LOGNAME': 'cltbld', 15:47:55 INFO - 'MAIL': '/var/mail/cltbld', 15:47:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:55 INFO - 'MOZ_NO_REMOTE': '1', 15:47:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:47:55 INFO - 'NO_EM_RESTART': '1', 15:47:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:47:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:47:55 INFO - 'PWD': '/builds/slave/test', 15:47:55 INFO - 'SHELL': '/bin/bash', 15:47:55 INFO - 'SHLVL': '1', 15:47:55 INFO - 'TERM': 'linux', 15:47:55 INFO - 'TMOUT': '86400', 15:47:55 INFO - 'USER': 'cltbld', 15:47:55 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1471905597.564307-1900834039', 15:47:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:55 INFO - '_': '/tools/buildbot/bin/python'} 15:47:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:47:56 INFO - Running setup.py (path:/tmp/pip-BEy0s5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:47:56 INFO - Running setup.py (path:/tmp/pip-827shl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:47:56 INFO - Running setup.py (path:/tmp/pip-LloZms-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:47:56 INFO - Running setup.py (path:/tmp/pip-V2LKmZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:47:56 INFO - Running setup.py (path:/tmp/pip-HxTTxz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:47:56 INFO - Running setup.py (path:/tmp/pip-ajUVnS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:47:56 INFO - Running setup.py (path:/tmp/pip-VeBl9b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:47:56 INFO - Running setup.py (path:/tmp/pip-iTalIo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:47:56 INFO - Running setup.py (path:/tmp/pip-b_Bnto-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:47:56 INFO - Running setup.py (path:/tmp/pip-RuzWGW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:47:56 INFO - Running setup.py (path:/tmp/pip-CLSX7n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:47:56 INFO - Running setup.py (path:/tmp/pip-QFXkB7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:47:56 INFO - Running setup.py (path:/tmp/pip-BnurOM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:47:56 INFO - Running setup.py (path:/tmp/pip-uyryaJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:47:57 INFO - Running setup.py (path:/tmp/pip-WfwWXm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:47:57 INFO - Running setup.py (path:/tmp/pip-uUyAqi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:47:57 INFO - Running setup.py (path:/tmp/pip-zZ7vrW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:47:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:47:57 INFO - Running setup.py install for manifestparser 15:47:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:47:57 INFO - Running setup.py install for mozcrash 15:47:57 INFO - Running setup.py install for mozdebug 15:47:57 INFO - Running setup.py install for mozdevice 15:47:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:47:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:47:57 INFO - Running setup.py install for mozfile 15:47:57 INFO - Running setup.py install for mozhttpd 15:47:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:47:57 INFO - Running setup.py install for mozinfo 15:47:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:47:57 INFO - Running setup.py install for mozInstall 15:47:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:47:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:47:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:47:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:47:58 INFO - Running setup.py install for mozleak 15:47:58 INFO - Running setup.py install for mozlog 15:47:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:47:58 INFO - Running setup.py install for moznetwork 15:47:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:47:58 INFO - Running setup.py install for mozprocess 15:47:58 INFO - Running setup.py install for mozprofile 15:47:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:47:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:47:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:47:58 INFO - Running setup.py install for mozrunner 15:47:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:47:58 INFO - Running setup.py install for mozscreenshot 15:47:58 INFO - Running setup.py install for moztest 15:47:58 INFO - Running setup.py install for mozversion 15:47:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:47:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:47:58 INFO - Cleaning up... 15:47:59 INFO - Return code: 0 15:47:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:47: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')]} 15:47:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:47:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47: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')]} 15:47:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:47:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47: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 0x2344030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2573400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2602570>, '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 0x2603470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2603900>, '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': '03627c79f6bb3d1578c4a402000001ba-1471905597.564307-1900834039', '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 15:47: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 15:47: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 15:47:59 INFO - Using env: {'DISPLAY': ':0', 15:47:59 INFO - 'HOME': '/home/cltbld', 15:47:59 INFO - 'LANG': 'en_US.UTF-8', 15:47:59 INFO - 'LANGUAGE': 'en_US:en', 15:47:59 INFO - 'LOGNAME': 'cltbld', 15:47:59 INFO - 'MAIL': '/var/mail/cltbld', 15:47:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:59 INFO - 'MOZ_NO_REMOTE': '1', 15:47:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:47:59 INFO - 'NO_EM_RESTART': '1', 15:47:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:47:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:47:59 INFO - 'PWD': '/builds/slave/test', 15:47:59 INFO - 'SHELL': '/bin/bash', 15:47:59 INFO - 'SHLVL': '1', 15:47:59 INFO - 'TERM': 'linux', 15:47:59 INFO - 'TMOUT': '86400', 15:47:59 INFO - 'USER': 'cltbld', 15:47:59 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1471905597.564307-1900834039', 15:47:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:59 INFO - '_': '/tools/buildbot/bin/python'} 15:47:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:47:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:47:59 INFO - Running setup.py (path:/tmp/pip-lAM64O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:47: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)) 15:47:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:47:59 INFO - Running setup.py (path:/tmp/pip-gEHgPP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:47: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)) 15:47:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:47:59 INFO - Running setup.py (path:/tmp/pip-kGB_t3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:47: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)) 15:47:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:47:59 INFO - Running setup.py (path:/tmp/pip-9pb7WJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:47: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)) 15:47:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:47:59 INFO - Running setup.py (path:/tmp/pip-EkQouz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:47: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)) 15:47:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:47:59 INFO - Running setup.py (path:/tmp/pip-fV_WqD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:47: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)) 15:47:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:47:59 INFO - Running setup.py (path:/tmp/pip-M_hJqt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:47: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)) 15:47:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:47:59 INFO - Running setup.py (path:/tmp/pip-zP0fAX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:47:59 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)) 15:47:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:47:59 INFO - Running setup.py (path:/tmp/pip-BjbOS5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:47:59 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)) 15:47:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:47:59 INFO - Running setup.py (path:/tmp/pip-WAZ9YO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:47:59 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)) 15:47:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:47:59 INFO - Running setup.py (path:/tmp/pip-Wdcd5q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:48: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)) 15:48:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:48:00 INFO - Running setup.py (path:/tmp/pip-aKwuW7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:48: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)) 15:48:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:48:00 INFO - Running setup.py (path:/tmp/pip-3B1qcd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:48: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)) 15:48:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:48:00 INFO - Running setup.py (path:/tmp/pip-QVIxrd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:48: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)) 15:48:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:48:00 INFO - Running setup.py (path:/tmp/pip-AxpAxp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:48: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)) 15:48:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:48:00 INFO - Running setup.py (path:/tmp/pip-Wi_6AL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:48: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)) 15:48:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:48:00 INFO - Running setup.py (path:/tmp/pip-k8P1LW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:48: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)) 15:48: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)) 15:48: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)) 15:48: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)) 15:48: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)) 15:48: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)) 15:48:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:48: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 15:48: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 15:48: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 15:48: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 15:48: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 15:48: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 15:48:02 INFO - Downloading blessings-1.6.tar.gz 15:48:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:48:02 INFO - Installing collected packages: blessings 15:48:02 INFO - Running setup.py install for blessings 15:48:02 INFO - Successfully installed blessings 15:48:02 INFO - Cleaning up... 15:48:02 INFO - Return code: 0 15:48:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:48:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:48:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:48:02 INFO - Reading from file tmpfile_stdout 15:48:02 INFO - Current package versions: 15:48:02 INFO - argparse == 1.2.1 15:48:02 INFO - blessings == 1.6 15:48:02 INFO - blobuploader == 1.2.4 15:48:02 INFO - docopt == 0.6.1 15:48:02 INFO - functools32 == 3.2.3-2 15:48:02 INFO - jsonschema == 2.5.1 15:48:02 INFO - manifestparser == 1.1 15:48:02 INFO - mozInstall == 1.12 15:48:02 INFO - mozcrash == 0.17 15:48:02 INFO - mozdebug == 0.1 15:48:02 INFO - mozdevice == 0.48 15:48:02 INFO - mozfile == 1.2 15:48:02 INFO - mozhttpd == 0.7 15:48:02 INFO - mozinfo == 0.9 15:48:02 INFO - mozleak == 0.1 15:48:02 INFO - mozlog == 3.3 15:48:02 INFO - moznetwork == 0.27 15:48:02 INFO - mozprocess == 0.23 15:48:02 INFO - mozprofile == 0.28 15:48:02 INFO - mozrunner == 6.12 15:48:02 INFO - mozscreenshot == 0.1 15:48:02 INFO - mozsystemmonitor == 0.3 15:48:02 INFO - moztest == 0.7 15:48:02 INFO - mozversion == 1.4 15:48:02 INFO - psutil == 3.1.1 15:48:02 INFO - requests == 1.2.3 15:48:02 INFO - wsgiref == 0.1.2 15:48:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:48:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:48:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:48:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:48:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:48:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:02 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 0x2344030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2573400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2602570>, '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 0x2603470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2603900>, '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': '03627c79f6bb3d1578c4a402000001ba-1471905597.564307-1900834039', '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 15:48:02 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 15:48:02 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 15:48:02 INFO - Using env: {'DISPLAY': ':0', 15:48:02 INFO - 'HOME': '/home/cltbld', 15:48:02 INFO - 'LANG': 'en_US.UTF-8', 15:48:02 INFO - 'LANGUAGE': 'en_US:en', 15:48:02 INFO - 'LOGNAME': 'cltbld', 15:48:02 INFO - 'MAIL': '/var/mail/cltbld', 15:48:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:02 INFO - 'MOZ_NO_REMOTE': '1', 15:48:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:48:02 INFO - 'NO_EM_RESTART': '1', 15:48:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:48:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:02 INFO - 'PWD': '/builds/slave/test', 15:48:02 INFO - 'SHELL': '/bin/bash', 15:48:02 INFO - 'SHLVL': '1', 15:48:02 INFO - 'TERM': 'linux', 15:48:02 INFO - 'TMOUT': '86400', 15:48:02 INFO - 'USER': 'cltbld', 15:48:02 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1471905597.564307-1900834039', 15:48:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:02 INFO - '_': '/tools/buildbot/bin/python'} 15:48:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:48:02 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)) 15:48: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)) 15:48: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)) 15:48: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)) 15:48: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)) 15:48: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)) 15:48: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)) 15:48: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)) 15:48: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)) 15:48: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)) 15:48: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)) 15:48:03 INFO - Cleaning up... 15:48:03 INFO - Return code: 0 15:48:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:48: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')]} 15:48:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:48:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48: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')]} 15:48:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:48:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48: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 0x2344030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2573400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2602570>, '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 0x2603470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2603900>, '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': '03627c79f6bb3d1578c4a402000001ba-1471905597.564307-1900834039', '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 15:48: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 15:48: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 15:48:03 INFO - Using env: {'DISPLAY': ':0', 15:48:03 INFO - 'HOME': '/home/cltbld', 15:48:03 INFO - 'LANG': 'en_US.UTF-8', 15:48:03 INFO - 'LANGUAGE': 'en_US:en', 15:48:03 INFO - 'LOGNAME': 'cltbld', 15:48:03 INFO - 'MAIL': '/var/mail/cltbld', 15:48:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:03 INFO - 'MOZ_NO_REMOTE': '1', 15:48:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:48:03 INFO - 'NO_EM_RESTART': '1', 15:48:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:48:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:03 INFO - 'PWD': '/builds/slave/test', 15:48:03 INFO - 'SHELL': '/bin/bash', 15:48:03 INFO - 'SHLVL': '1', 15:48:03 INFO - 'TERM': 'linux', 15:48:03 INFO - 'TMOUT': '86400', 15:48:03 INFO - 'USER': 'cltbld', 15:48:03 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1471905597.564307-1900834039', 15:48:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:03 INFO - '_': '/tools/buildbot/bin/python'} 15:48:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:48:03 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:48:03 INFO - Cleaning up... 15:48:03 INFO - Return code: 0 15:48:03 INFO - Running post-action listener: _resource_record_post_action 15:48:03 INFO - Running post-action listener: _start_resource_monitoring 15:48:03 INFO - Starting resource monitoring. 15:48:03 INFO - [mozharness: 2016-08-22 22:48:03.273489Z] Finished create-virtualenv step (success) 15:48:03 INFO - [mozharness: 2016-08-22 22:48:03.273924Z] Running install step. 15:48:03 INFO - Running pre-action listener: _resource_record_pre_action 15:48:03 INFO - Running main action method: install 15:48:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:48:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:48:03 INFO - Reading from file tmpfile_stdout 15:48:03 INFO - Detecting whether we're running mozinstall >=1.0... 15:48:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:48:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:48:03 INFO - Reading from file tmpfile_stdout 15:48:03 INFO - Output received: 15:48:03 INFO - Usage: mozinstall [options] installer 15:48:03 INFO - Options: 15:48:03 INFO - -h, --help show this help message and exit 15:48:03 INFO - -d DEST, --destination=DEST 15:48:03 INFO - Directory to install application into. [default: 15:48:03 INFO - "/builds/slave/test"] 15:48:03 INFO - --app=APP Application being installed. [default: firefox] 15:48:03 INFO - mkdir: /builds/slave/test/build/application 15:48:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 15:48:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 15:48:20 INFO - Reading from file tmpfile_stdout 15:48:20 INFO - Output received: 15:48:20 INFO - /builds/slave/test/build/application/firefox/firefox 15:48:20 INFO - Running post-action listener: _resource_record_post_action 15:48:20 INFO - [mozharness: 2016-08-22 22:48:20.213176Z] Finished install step (success) 15:48:20 INFO - [mozharness: 2016-08-22 22:48:20.213502Z] Running run-tests step. 15:48:20 INFO - Running pre-action listener: _resource_record_pre_action 15:48:20 INFO - Running main action method: run_tests 15:48:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:48:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:48:20 INFO - Python 2.7.3 15:48:20 INFO - Return code: 0 15:48:20 INFO - grabbing minidump binary from tooltool 15:48:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:48:20 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 0x2602570>, '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 0x2603470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2603900>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:48:20 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 15:48:20 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 15:48:20 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 15:48:20 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:48:20 INFO - Return code: 0 15:48:20 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:48:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:48:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:48:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:48:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:48:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-047', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 15:48:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-047 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 15:48:20 INFO - Using env: {'DISPLAY': ':0', 15:48:20 INFO - 'HOME': '/home/cltbld', 15:48:20 INFO - 'LANG': 'en_US.UTF-8', 15:48:20 INFO - 'LANGUAGE': 'en_US:en', 15:48:20 INFO - 'LOGNAME': 'cltbld', 15:48:20 INFO - 'MAIL': '/var/mail/cltbld', 15:48:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:48:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:48:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:20 INFO - 'MOZ_NO_REMOTE': '1', 15:48:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:48:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:48:20 INFO - 'NO_EM_RESTART': '1', 15:48:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:48:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:20 INFO - 'PWD': '/builds/slave/test', 15:48:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:48:20 INFO - 'SHELL': '/bin/bash', 15:48:20 INFO - 'SHLVL': '1', 15:48:20 INFO - 'TERM': 'linux', 15:48:20 INFO - 'TMOUT': '86400', 15:48:20 INFO - 'USER': 'cltbld', 15:48:20 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1471905597.564307-1900834039', 15:48:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:20 INFO - '_': '/tools/buildbot/bin/python'} 15:48:20 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-047', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 15:48:20 INFO - mozversion application_buildid: 20160822124915 15:48:20 INFO - mozversion application_changeset: 07189ee916253fe9692528551ad1b96bc6c1bccc 15:48:20 INFO - mozversion application_display_name: FirefoxDeveloperEdition 15:48:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:48:20 INFO - mozversion application_name: Firefox 15:48:20 INFO - mozversion application_remotingname: firefox-dev 15:48:20 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:48:20 INFO - mozversion application_vendor: Mozilla 15:48:20 INFO - mozversion application_version: 50.0a2 15:48:20 INFO - mozversion platform_buildid: 20160822124915 15:48:20 INFO - mozversion platform_changeset: 07189ee916253fe9692528551ad1b96bc6c1bccc 15:48:20 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:48:20 INFO - mozversion platform_version: 50.0a2 15:48:20 INFO - using testdate: 1471906100 15:48:20 INFO - actual date: 1471906100 15:48:20 INFO - starting webserver on 'localhost:50150' 15:48:20 INFO - SUITE-START | Running 2 tests 15:48:20 INFO - TEST-START | dromaeo_css 15:48:20 INFO - operating with platform_type : linux_ 15:48:20 INFO - Initialising browser for dromaeo_css test... 15:48:20 INFO - TEST-INFO | started process 4267 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0Pmzj7/profile http://localhost:50150/getInfo.html) 15:48:24 INFO - PROCESS | 4267 | __metrics Screen width/height:1600/1200 15:48:24 INFO - PROCESS | 4267 | colorDepth:24 15:48:24 INFO - PROCESS | 4267 | Browser inner width/height: 1024/697 15:48:24 INFO - PROCESS | 4267 | __metrics 15:48:25 ERROR - PROCESS | 4267 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 15:48:26 INFO - TEST-INFO | 4267: exit 0 15:48:26 INFO - Browser initialized. 15:48:26 INFO - Running cycle 1/1 for dromaeo_css test... 15:48:26 INFO - TEST-INFO | started process 4396 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0Pmzj7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:48:38 INFO - PROCESS | 4396 | RSS: Main: 152272896 15:48:38 INFO - PROCESS | 4396 | 15:49:27 INFO - PROCESS | 4396 | 1471906167921 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] 15:51:36 INFO - PROCESS | 4396 | Cycle 1(1): loaded http://localhost:50150/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50150/tests/dromaeo/cssquery-ext.html) 15:51:37 INFO - PROCESS | 4396 | RSS: Main: 157933568 15:51:37 INFO - PROCESS | 4396 | 15:54:39 INFO - PROCESS | 4396 | Cycle 1(1): loaded http://localhost:50150/tests/dromaeo/cssquery-ext.html (next: http://localhost:50150/tests/dromaeo/cssquery-jquery.html) 15:54:40 INFO - PROCESS | 4396 | RSS: Main: 153083904 15:54:40 INFO - PROCESS | 4396 | 15:57:41 INFO - PROCESS | 4396 | Cycle 1(1): loaded http://localhost:50150/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50150/tests/dromaeo/cssquery-mootools.html) 15:57:42 INFO - PROCESS | 4396 | RSS: Main: 154304512 15:57:42 INFO - PROCESS | 4396 | 16:00:44 INFO - PROCESS | 4396 | Cycle 1(1): loaded http://localhost:50150/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50150/tests/dromaeo/cssquery-prototype.html) 16:00:44 INFO - PROCESS | 4396 | RSS: Main: 152248320 16:00:44 INFO - PROCESS | 4396 | 16:03:47 INFO - PROCESS | 4396 | Cycle 1(1): loaded http://localhost:50150/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50150/tests/dromaeo/cssquery-yui.html) 16:03:48 INFO - PROCESS | 4396 | RSS: Main: 154923008 16:03:48 INFO - PROCESS | 4396 | 16:06:50 INFO - PROCESS | 4396 | Cycle 1(1): loaded http://localhost:50150/tests/dromaeo/cssquery-yui.html (next: http://localhost:50150/tests/dromaeo/cssquery-dojo.html) 16:06:50 INFO - PROCESS | 4396 | RSS: Main: 155672576 16:06:50 INFO - PROCESS | 4396 | 16:06:50 INFO - PROCESS | 4396 | __start_tp_report 16:06:50 INFO - PROCESS | 4396 | _x_x_mozilla_page_load 16:06:50 INFO - PROCESS | 4396 | _x_x_mozilla_page_load_details 16:06:50 INFO - PROCESS | 4396 | |i|pagename|runs| 16:06:50 INFO - PROCESS | 4396 | |0;dojo.html;3257.543943847861;3272.2637406583535;3289.8519566619498;3294.1599892027543;3295.950560741591;5716.8284951451415;5757.2803399575005;5760.711964401774;5771.855703607412;5779.104238842976;5744.368119506861;5763.337216220136;5774.942250577493;5787.276590426197;5791.942080579193;5154.819581314646;5196.220566914974;5208.479152084799;5232.502912223333;5282.102042652739;5156.3296771419855;5164.483551644843;5201.375834899817;5283.603729720267;5380.058489764295;5187.195984622371;5218;5231.764570594313;5415.891682166354;5471.097268950638;5263.815766448175;5283.260343551906;5284.841454756363;5287.569573283854;5288.65623734456;5082.822101226477;5115.61632877532;5177.326947496801;5214.0875346814155;5227.712475813829;5622.971885140548;5670.971645141748;5674.347450043216;5746.454086861741;5811.506021988136;6235.501159907242;6240.656763877985;6262.812115636538;6266.749330026793;6267.122602835607;5868.911966320508;6264.498840092781;6278.0269058296035;6291.339409362043;6302.653354065525;5741.971290143522;5793.159991801165;5824.504917082053;5874.383189765099;5912.497437717756;6377.457916077093;6379.298277189506;6389.7763578274535;6396.71214795326;6464.903026454607;5952.077427998667;6085.4523092921845;6159.291681456601;6174.845628859314;6204.968975155095;6114.266954506099;6160;6165.075238714139;6277.811665649946;6280.685965701697;6178.042403427476;6193.287771906198;6248.937510624926;6258.436740693269;6289.685515724195;6039.063945088518;6263.843403914939;6265.373462653789;6273.749050037947;6280.623162610258;4712.6229901607785;4749.548792864742;4757.500462451393;4772.642051846056;4816.470188279286;4216.536181020086;4239.639630631369;4246.702730808814;4254.744715317091;4269.316909294498;5753.424657534297;5982.670953097534;6029.16824611452;6038.396160383926;6073.544484163705;6112.533877023262;6123.740204357194;6128.503123582109;6288.308286088527;6300.338464461257;5952.821415357546;5969.283685957626;6000.279966403972;6017.398260173947;6029.246344206974;5189.6886186828915;5357.678539287655;5373.946260537423;5384.623076384615;5392.56859451243;6370.362963703593;6384.904226436607;6414.967925160345;6534.183227096613;6547.148870646785;6257.311695713559;6270.278917924497;6317.873642527215;6329.683515824098;6332.905006424815;6252.656103914328;6468.223813142453;6517.7067031984625;6519.5436319457185;6527.05357723118;6042.184305118929;6048.183495228088;6116.021436570128;6178.721957511832;6196.442320191204;6262.1546091278915;6293.465055470153;6296.5277604178955;6303.684815759102;6330;5896.70516474166;6124.173236613;6154.661493617894;6159.6612186330185;6161.260648722182;6003.0695242237525;6024.969875150597;6045.516358691206;6096.02463605821;6138.693065346804;6025.969870150797;6045.244344456943;6065.54508411862;6065.8180254592435;6085.726142323695;5964.731587078505;6075.301340345917;6099.695015249131;6158.599691020069;6186.659733714689;5858.47273745348;5864.208331875313;5923.466887980103;5991.64050156992;6010.068439391901 16:06:50 INFO - PROCESS | 4396 | |1;ext.html;586.9060950247962;640.5164101103667;661.0678942690804;663.5090033375302;702.6065403374114;43306;47526.287105693445;48046.519534804684;48087.51912480874;48357.51642483574;47811.76094119524;48322.51677483224;48510;48597;48747.756261218645;9067.183953444171;9196.632134714637;9199.86200206994;9208.953955230214;9243.907560924372;9147.4054186478;9247.768805779842;9260.768480787967;9262.39794413361;9267.165955064043;9306.255499560019;9355.812883742321;9376.953115234413;9388.295877809192;9405.153536181742;9247.815043699122;9248.121428464352;9259.907400925971;9270.768230794216;9315.580798864034;9107.225885799715;9201;9232.122948319899;9297.581608827619;9329.860052099224;44347.77826110881;44409.11181776346;44495.77752111251;44676.55323446757;44840.10319793619;3811.4092315691114;3924.646781789624;3974.1654252606986;4055.472788537501;4055.5336136344404;35145;35889.82055089734;35990.46014309761;36237.275254494765;36253.45619815705;4467.7542735149555;4494.438195225597;4499.527549607277;4521.253993091136;4555.498895121534;394034;394560;395749;398116;399040.9999999971;66061;66748;66786.66606666935;66799;66855;42336.78831605822;43330.7833460837;43496.130077398906;43579.56420435755;70833.64583177154;19407.223711051683;19552.706709399372;19562.413127606196;19594;19605.803941960683;14890.255487225595;15002.57475539822;15018.549443516711;15039.548813535612;15084.547463576111;21250.25624103149;21728.34814955554;21875.234366797406;21954.121835126734;21985.67021494647;7331.486795924341;7526.774196774106;7647.388208943271;7813.038720412465;8014.599270036475;1037.3361048928691;1133.3313345126414;1155.064397837754;1171.1860257121389;1271.505980472959;36072.45891311632;36376.81811590978;36384.27231455409;36476.45285320722;36510.087247819014;1160.1241062997383;1236.7773800715956;1259.8551166615773;1331.3077199856214;1355.1394864261265;17680.734788978174;18757.155927983273;18763.90618046901;18811.52971175704;18898.338558150397;671369;673927;674664;677918;678129;67702.66148669225;67721;67940.6602966982;68145;68182;1453.869151776324;1475.955721328362;1487.4868170481166;1517.1049081042238;1518.8481151884723;35017.12457188489;35151.47272790859;35156.824215878754;35252.647473524936;35899.641003590674;59085.40914590971;59211.40788592059;59462;59506.70246648739;59663;498045;502203;506588;507960;508260;31946.68053319437;32043.198920027186;32272.35455290928;32329.51505727369;32570.83714581507;53628.463715363905;53718.73140634272;54131.45868541422;54525.18212226899;55643.44356556382;45338;45651.27443307316;47223.874925627744;47627.76186119047;47818.28272575844;46755;46913.296300556205;47006.76496617495;47722.39911625309;48081.75959120182;46528.208307501656;47018;47422.28866566935;48076.27885581649;49602.25596615981;38586.42120368253;40924.1815163701;41757.164856703304;42273.365899512144;42814.78592607017;31413;31581.368372631958;31988.6801131995;32046;32144.517832232068 16:06:50 INFO - PROCESS | 4396 | |2;jquery.html;400.97594144351336;406.35186876931283;407.85724996251315;414.7635847566886;430.27928220231126;20501;20615.587688246225;21498.677519837194;21584.784152158474;21687.915604219765;20374;20697.344212463006;20985.895070524624;21372.35882923511;21457.141714331447;5791.82624521264;5967.433093856077;5968.88062238755;5972.79095231667;5995.460408563228;5836.5914385993;5836.649801011931;5864.413558644144;5911;5937.050071988483;5843.824685259449;5863.296404431474;5875.441833025877;5913.290405151366;6006.579539432241;5821.359650438449;5865.8826823463505;5866.413358664142;5915.822525324204;5974.761009559634;5291.603129765244;5360.571154307685;5382.354117505885;5392.083345831218;5397.8110766123;25017.124400646073;25146.748532514623;25262.873685631454;25375.23874283756;26040.479190416274;2456.754324567529;2485.825992180548;2487.5273697997504;2551.119863647039;2738.219607411878;15617.531474055797;15948.840511594852;16085.83914160855;16116.516504504883;16490.83509164905;1982.4052784164694;1994.3618042226494;2015.7177995080701;2030.3807338761671;2036.4094412620323;65314;73499.63250183768;77725;83721.58139209326;87646;10813.56745730162;10895.83656245157;11088.833667494993;11313.094952403788;11354.829677554842;15355.769663455058;15366.615834604225;15393.307301171475;15393.307301171475;15475.458358957383;8240.711575094843;8457;8726.563671816384;8970.686025989058;9143.177114059767;8998.775030624156;9052.728418147466;9097.726318315441;9116.58975346111;9322.813543729224;6847.760328388483;6881.518293719391;6885.965570172117;6952.687129079203;7022.157341119098;1838.9356372526897;1875.109148516833;1962.8822270663807;1979.6139752748077;1989.0651393844869;999.280518027034;1082.6860210538855;1144.7080994346554;1152.2510368260698;1199.9880001200052;21900.342989710334;21959.34121976343;22136.335909922727;22239.777602224094;22459.213927512457;1089.6622047165404;1101.3336931156696;1116.17403121691;1151.4645689754304;1166.2302880099094;12088.939555302168;12328.876711232951;12587.055970802227;12649.683757906127;12689.175203611736;545591;553539;555315;559232;561768.3823161798;51542.742286288325;52802.471975280525;52925;71563.64218178876;89692.5515372419;1060.9734756631146;1076.978460430787;1114.2924243105685;1125.5216532973454;1130.9604163854226;18448.907755461136;19005.429837104908;19285.517862053563;19300.517487062374;19412;39800;41317.38023929583;41376;42079.36880946849;42407.151856963304;57064.71467642635;57609;57969.71015144897;58078.4192158073;58530;8475.406721529434;8717.738467845975;8768.035516093098;8839.51382673959;8865.601047952729;13020.218786872818;13938.512152074629;14092.929535352258;14344.928275358974;14593.416263349347;11838.585649502225;12363.258204507758;12565;12590.559330423392;12686.365681716063;12126.151169418408;12197.207181533173;12365.876341236473;12381.133320667826;12384.256944583354;12652.683682908;12769.170003949714;12859.678508037374;12877.420516076612;12971.805422918853;8227.177282271725;8320.375971802017;8378.162183781573;8381.413301068867;8625.568721564023;10482.895171048496;12463.626091217278;12513.93743031279;12580;12625.116241862981 16:06:50 INFO - PROCESS | 4396 | |3;mootools.html;1598.2727858824237;1600.151919482674;1811.2573844723659;1872.8314451699343;1918.5683221275206;8525.658973641062;9171.633134674614;9347.112024357675;9979.251556133291;10010.199184065266;9393.20157786589;9761.121499065082;9774.853377199348;9776.364536305135;9845.11393974542;2530.0765220694407;2571.832830865997;2575.9484810303784;2576.6521519594803;2582.122078493312;2480.9007639694482;2572.7555882191164;2575.510652975938;2581.4062765563835;2587.7541633544784;2558.09187738353;2565.8460492370423;2598.116640342283;2603.4142317978494;2609.23027706826;2534.9493010139786;2538.0482319130324;2541.3900663840636;2550.6939167300043;2557.0538900606894;2488.315713178872;2511.1462102884925;2535.6323333116593;2540.453802432486;2541.6314634377914;9704.70885873425;9751.3661611995;9773.951130244302;9817.263705222136;9822.9508852456;5746.856328591777;5889.587728858982;5889.587728858982;5897.233359663259;5915.763369465216;3722.3113723961155;3795.449659799341;3817;3839.462475253467;3901.2977664020464;1230.2003697596544;1264.178284115323;1275.0628288208159;1276.7063575377622;1295.309620944667;13602.387892544859;13762.449502019907;13855.23796191199;13930.164190148624;13970.790438143436;5137.820176293886;5161.741912904415;5223.059849227099;5252.789888404421;5367;11545.826812597817;11555.711107222387;11559.190856639954;11576.131790115607;11596.072314214842;5889.705514724246;5940.049592065248;5972.641641501524;5972.970135149297;5985.730642121115;4660.487346391962;5362.436944120889;5445.809396671098;5477.397486276506;5503.147012213113;4356.324769660644;4434.623057040188;4443.311286750559;4463.509014008457;4471;1396.3018490754623;1406.3741634972544;1425.287356321839;1433.118632041308;1433.240382597225;945.5130607737021;1102.5259138570332;1104.447776111944;1110.4780753046055;1111.1388673777726;6544.70548825304;6558.278589355167;6617.735290588419;6694.698738556679;6752.662366881636;522.6341560907304;525.8606469285643;526.4866754914004;530.875244317585;532.8348212054276;4568.725876447357;4640.118377508263;4664.463586687507;4685.226937555333;4718.693284936468;12049.096317776202;12352.44414001372;12503.937480312541;12505.124641275206;12510.624681259576;11929.164958452826;12048.096392770578;12074.637760867188;12178.573749918887;12226.633201003984;8943.150400712053;8978.551072446351;9042.683506077256;9049.909500905038;9283.16451519353;6558.606483610999;6569.113169722026;6595.604263744191;6627.768028118992;6632.90050649231;10767.6231331903;11106.055985241344;11114.777704445703;11145.888541114486;11301.321920684784;10639.574417023232;11268.098552115647;11337.26307789991;11458.484368203284;11525.769484610428;7903.525788452711;8273.917260827315;8345.956755405574;8529.402941794264;8546.914530854861;9558.235341172829;9577.425354478753;9658.517074146404;9662.468564229035;9668;8207.220314070153;8358.791030224293;8461.957690211755;8697.8695319569;8743.825123497621;8477.321814254969;8524.573771311534;8532.829343413221;8607.18231767981;8692.91307086921;7978.680852765824;8091.87862182056;8152.388570857091;8164.346852251687;8177.34581233488;5808.662825352639;5878.353381128158;5924.674142922181;5956.612820166849;6067.939320606737;5885.087811389241;5952.910706339322;5978.581499295007;6043.909341359971;6108.480779133645 16:06:50 INFO - PROCESS | 4396 | |4;prototype.html;348.28253797177814;354.45591017787694;354.9609542950275;360.6321552016941;362.71889285803496;5635.436456354362;5740.6268592541455;5747.252857128573;5821.388754180808;5833.45413465432;5678.630888992223;5711.200431939532;5719.714014299289;5725.541956643458;5732.742026608813;4459.665525085616;4478.753668548228;4501.774911254441;4522;4544.477385100723;4449;4492.258777301741;4518.819247230107;4553.453585569736;4557.74932378719;4466.307722303049;4469.307257375096;4471.731696098244;4476.798544065525;4490.3937968374175;4452.243118669818;4514.367988481632;4553.180427523044;4568.954310456887;4574.908501829978;3885.8639947601982;3948.7828169450668;3962.5046869141356;3963.9009024774323;3984.621460961204;5614.456024593229;5624.746886390081;5630.774769009235;5670.979223739725;5684.459976302244;5078.64449488536;5212.582993360522;5213.087709650834;5217.452167522431;5274.525292723635;6078.39216078393;6082.239720034996;6087.847803804896;6094.116353128771;6105.236845394325;4344.717593356421;4349.086691794727;4361.760103194323;4364.607185353301;4376.256036473807;6021.849453763647;6260.499160067229;6263.96868015657;6291.276503202144;6342.682865856688;4574.336721175411;4610.53894610543;4626.791794369262;4634.536546345338;4642.605378542862;4683.882902927454;4699.412573428322;4706.3646407735205;4714.104320179155;4740.762961851962;5210.697325668583;5245.475452454801;5271.894562108813;5284;5299.867503312448;5896.439838215363;6060.636361818217;6068.666223357758;6093;6134.263888333339;1587.7935868337042;1598.1449924290448;1604.8796340274525;1608.1557182479291;1609.8551130398087;1014.2798612984864;1028.711960651019;1060.3213943076437;1066.847804371277;1067.9466026698633;5271.947280527145;5289.12236156159;5289.656172348785;5301.734913254322;5360.946390536123;5478.561715062785;5515;5529.640573362719;5570.106163904292;5585.273914391184;4974;5227.503387178212;5254.369475662945;5270.341207349082;5295.947040529622;2999.9550006749914;3001.7898747087493;3011.26224075103;3017.0797906638245;3029.7576193904433;5602.187682785973;5666.914996275059;5698.059820129715;5701.971490142606;5731.8822829547835;5404.810831620953;5617;5629.746661400247;5637.182608521742;5661.801082388437;5709.172170035322;5762.193292938992;5770.451807078321;5815.273090863642;5842.561807864512;5383.707910101626;5470.699111548903;5475.946520054979;5599.776008959759;5664.546836253172;5454.509094181542;5503.394626590992;5510.448955104457;5533.750981205775;5568.220449137043;5667.914981275202;5690.715464226855;5736.368999410145;5755.3381361144;5786.495511167042;4037.6366127048705;4137.834486620622;4171.749695018309;4182.498100227992;4190.895227619334;5429.69687275059;5492.615516913777;5561.499465048165;6083.452489275986;6331.936680633319;6083.239595050619;6138.232720909887;6157.291911430243;6166.660833654192;6233;6094.573379863367;6102.359252278625;6150.907736383869;6198.752049917953;6263.6241825490615;6064;6121.357257488078;6180.381961803783;6188.4739797117745;6198.318184999736;6208.844778880564;6222.844428889314;6285.811425657238;6293.842653933688;6303.212098487689;5827.737751801094;5962.582619216787;6038.184845045863;6049.274087109575;6065.484433823174 16:06:50 INFO - PROCESS | 4396 | |5;yui.html;815.6003558256455;834.3908946469078;843.7426584891609;902.8194361127775;916.755987809516;14947.402103915843;15764.84235157651;15860.206989650515;16030.118343491104;16127.19364031798;16194.433194838182;16520.58698532537;16607.750883736753;16622.50132496024;16664.66670666586;882.1311008656468;913.3058875254787;914.5610107148573;916.7616419730851;918.8897332320098;876.5310558851016;898.0256421782374;899.8515244984602;901.5447199164413;909.6179604566065;949.0367277213634;952.3857112162685;955.5508910811942;959.3140904253493;961.4759955824093;954.6420092465326;955.1642312976146;956.7608097975506;957.8371676814958;962.1821451766012;692.9930700692979;695.2942763095463;695.6174104242647;696.8919817428307;699.8355386484172;15989.200539972955;16028.19859007045;16053.598660033593;16103.597410064725;16126.919365403099;14791.186484743335;15385.384584616713;15791.605209869846;15940.362385504566;16096.517104486884;14787.556373308818;14976.400943962228;15031.849681503154;15120.697586048218;15327.080375177526;5037.647364684473;5055.545000949932;5058.696478211283;5060.746962651852;5131.461196574381;80877;93964.53017734867;94490;94532.52733736287;94815;13535.932320338336;13730.313484325743;13741.656458588415;13810.65473363174;13889.930550347184;25155.496890062463;25436.491270174494;25663;25735.356616084373;26102.738972610412;8945.284377249804;9480.90519094814;9829.410235385756;10024.749381265381;10418.06237438619;4873.41079019938;4888.780004899788;4996.850094497171;5106.055379754757;5156.613254005964;3926.862559810393;4031.7984100794843;4067.1458993611673;4068.5117785865887;4074.2258970796047;712.2770388056127;717.089296593334;721.9494635375473;731.0386841303668;742.0983505041401;618.9690515474208;703.6868593475854;748.8202831320444;774.4694884004475;775.2789905388044;9893.802123957481;10188.235882308856;10546.209034322457;10750;10806.567737290421;607.6840043177523;640.9583377080568;755.4900442201492;763.6983391560302;779.9571023593643;5215.426303106655;5295.232191332312;5305.31030965972;5318.813841515528;5353.892922141613;74661;105684;108691.45654271678;123479;144307.278463607;88316.5584172075;89900.10099899049;118326;118509.40745296219;119617.40191299161;79537;80194.59902700449;92956;94616.52691736635;94855.52572237095;9368;9368.95315523418;9474.336796424184;9520.666776662783;9534;55808;56377;56437;57073.42926570847;58032;67659;68419.31580684129;74077;78545.60727196328;79083.20916790988;14160.291985400894;14240.501582444565;14347.354369053626;14826.462205050659;15280.770788438405;15569.76645350298;16301.184940752677;16342.673146537241;16841.24214410379;16864.241109149872;15813.920930395274;15835.208239588204;15867.127307997709;15896;15936.840631594;14905.552833415015;15215.08709477435;15261.542153735405;16064.116473594064;16197.75703364427;15602.297896594908;16089.115098669685;16228.269727862038;16297.266623001757;16685.833141668914;12220.633380998584;12628.936855315664;12674.112812103065;13047.28239946774;13230.735385292433;10267.537960791931;11215.71960700989;11890.405479725807;12129.939350303192;12240.755184896074 16:06:50 INFO - PROCESS | 4396 | __end_tp_report 16:06:50 INFO - PROCESS | 4396 | __start_cc_report 16:06:50 INFO - PROCESS | 4396 | _x_x_mozilla_cycle_collect,325 16:06:50 INFO - PROCESS | 4396 | __end_cc_report 16:06:50 INFO - PROCESS | 4396 | __startTimestamp1471907210611__endTimestamp 16:06:50 INFO - PROCESS | 4396 | 16:06:50 INFO - PROCESS | 4396 | ------- Summary: start ------- 16:06:50 INFO - PROCESS | 4396 | Number of tests: 6 16:06:50 INFO - PROCESS | 4396 | 16:06:50 INFO - PROCESS | 4396 | [#0] dojo.html Cycles:165 Average:5774.33 Median:6025.97 stddev:679.33 (11.3%) stddev-sans-first:652.10 16:06:50 INFO - PROCESS | 4396 | Values: 3257.5 3272.3 3289.9 3294.2 3296.0 5716.8 5757.3 5760.7 5771.9 5779.1 5744.4 5763.3 5774.9 5787.3 5791.9 5154.8 5196.2 5208.5 5232.5 5282.1 5156.3 5164.5 5201.4 5283.6 5380.1 5187.2 5218.0 5231.8 5415.9 5471.1 5263.8 5283.3 5284.8 5287.6 5288.7 5082.8 5115.6 5177.3 5214.1 5227.7 5623.0 5671.0 5674.3 5746.5 5811.5 6235.5 6240.7 6262.8 6266.7 6267.1 5868.9 6264.5 6278.0 6291.3 6302.7 5742.0 5793.2 5824.5 5874.4 5912.5 6377.5 6379.3 6389.8 6396.7 6464.9 5952.1 6085.5 6159.3 6174.8 6205.0 6114.3 6160.0 6165.1 6277.8 6280.7 6178.0 6193.3 6248.9 6258.4 6289.7 6039.1 6263.8 6265.4 6273.7 6280.6 4712.6 4749.5 4757.5 4772.6 4816.5 4216.5 4239.6 4246.7 4254.7 4269.3 5753.4 5982.7 6029.2 6038.4 6073.5 6112.5 6123.7 6128.5 6288.3 6300.3 5952.8 5969.3 6000.3 6017.4 6029.2 5189.7 5357.7 5373.9 5384.6 5392.6 6370.4 6384.9 6415.0 6534.2 6547.1 6257.3 6270.3 6317.9 6329.7 6332.9 6252.7 6468.2 6517.7 6519.5 6527.1 6042.2 6048.2 6116.0 6178.7 6196.4 6262.2 6293.5 6296.5 6303.7 6330.0 5896.7 6124.2 6154.7 6159.7 6161.3 6003.1 6025.0 6045.5 6096.0 6138.7 6026.0 6045.2 6065.5 6065.8 6085.7 5964.7 6075.3 6099.7 6158.6 6186.7 5858.5 5864.2 5923.5 5991.6 6010.1 16:06:50 INFO - PROCESS | 4396 | 16:06:50 INFO - PROCESS | 4396 | [#1] ext.html Cycles:180 Average:69713.61 Median:25103.39 stddev:143139.21 (570.2%) stddev-sans-first:143446.66 16:06:50 INFO - PROCESS | 4396 | Values: 586.9 640.5 661.1 663.5 702.6 43306.0 47526.3 48046.5 48087.5 48357.5 47811.8 48322.5 48510.0 48597.0 48747.8 9067.2 9196.6 9199.9 9209.0 9243.9 9147.4 9247.8 9260.8 9262.4 9267.2 9306.3 9355.8 9377.0 9388.3 9405.2 9247.8 9248.1 9259.9 9270.8 9315.6 9107.2 9201.0 9232.1 9297.6 9329.9 44347.8 44409.1 44495.8 44676.6 44840.1 3811.4 3924.6 3974.2 4055.5 4055.5 35145.0 35889.8 35990.5 36237.3 36253.5 4467.8 4494.4 4499.5 4521.3 4555.5 394034.0 394560.0 395749.0 398116.0 399041.0 66061.0 66748.0 66786.7 66799.0 66855.0 42336.8 43330.8 43496.1 43579.6 70833.6 19407.2 19552.7 19562.4 19594.0 19605.8 14890.3 15002.6 15018.5 15039.5 15084.5 21250.3 21728.3 21875.2 21954.1 21985.7 7331.5 7526.8 7647.4 7813.0 8014.6 1037.3 1133.3 1155.1 1171.2 1271.5 36072.5 36376.8 36384.3 36476.5 36510.1 1160.1 1236.8 1259.9 1331.3 1355.1 17680.7 18757.2 18763.9 18811.5 18898.3 671369.0 673927.0 674664.0 677918.0 678129.0 67702.7 67721.0 67940.7 68145.0 68182.0 1453.9 1476.0 1487.5 1517.1 1518.8 35017.1 35151.5 35156.8 35252.6 35899.6 59085.4 59211.4 59462.0 59506.7 59663.0 498045.0 502203.0 506588.0 507960.0 508260.0 31946.7 32043.2 32272.4 32329.5 32570.8 53628.5 53718.7 54131.5 54525.2 55643.4 45338.0 45651.3 47223.9 47627.8 47818.3 46755.0 46913.3 47006.8 47722.4 48081.8 46528.2 47018.0 47422.3 48076.3 49602.3 38586.4 40924.2 41757.2 42273.4 42814.8 31413.0 31581.4 31988.7 32046.0 32144.5 16:06:50 INFO - PROCESS | 4396 | 16:06:50 INFO - PROCESS | 4396 | [#2] jquery.html Cycles:180 Average:30669.88 Median:2486.68 stddev:90657.12 (3645.7%) stddev-sans-first:90882.95 16:06:50 INFO - PROCESS | 4396 | Values: 401.0 406.4 407.9 414.8 430.3 20501.0 20615.6 21498.7 21584.8 21687.9 20374.0 20697.3 20985.9 21372.4 21457.1 5791.8 5967.4 5968.9 5972.8 5995.5 5836.6 5836.6 5864.4 5911.0 5937.1 5843.8 5863.3 5875.4 5913.3 6006.6 5821.4 5865.9 5866.4 5915.8 5974.8 5291.6 5360.6 5382.4 5392.1 5397.8 25017.1 25146.7 25262.9 25375.2 26040.5 2456.8 2485.8 2487.5 2551.1 2738.2 15617.5 15948.8 16085.8 16116.5 16490.8 1982.4 1994.4 2015.7 2030.4 2036.4 65314.0 73499.6 77725.0 83721.6 87646.0 10813.6 10895.8 11088.8 11313.1 11354.8 15355.8 15366.6 15393.3 15393.3 15475.5 8240.7 8457.0 8726.6 8970.7 9143.2 8998.8 9052.7 9097.7 9116.6 9322.8 6847.8 6881.5 6886.0 6952.7 7022.2 1838.9 1875.1 1962.9 1979.6 1989.1 999.3 1082.7 1144.7 1152.3 1200.0 21900.3 21959.3 22136.3 22239.8 22459.2 1089.7 1101.3 1116.2 1151.5 1166.2 12088.9 12328.9 12587.1 12649.7 12689.2 545591.0 553539.0 555315.0 559232.0 561768.4 51542.7 52802.5 52925.0 71563.6 89692.6 1061.0 1077.0 1114.3 1125.5 1131.0 18448.9 19005.4 19285.5 19300.5 19412.0 39800.0 41317.4 41376.0 42079.4 42407.2 57064.7 57609.0 57969.7 58078.4 58530.0 8475.4 8717.7 8768.0 8839.5 8865.6 13020.2 13938.5 14092.9 14344.9 14593.4 11838.6 12363.3 12565.0 12590.6 12686.4 12126.2 12197.2 12365.9 12381.1 12384.3 12652.7 12769.2 12859.7 12877.4 12971.8 8227.2 8320.4 8378.2 8381.4 8625.6 10482.9 12463.6 12513.9 12580.0 12625.1 16:06:50 INFO - PROCESS | 4396 | 16:06:50 INFO - PROCESS | 4396 | [#3] mootools.html Cycles:180 Average:6458.18 Median:4928.26 stddev:3689.26 (74.9%) stddev-sans-first:3681.53 16:06:50 INFO - PROCESS | 4396 | Values: 1598.3 1600.2 1811.3 1872.8 1918.6 8525.7 9171.6 9347.1 9979.3 10010.2 9393.2 9761.1 9774.9 9776.4 9845.1 2530.1 2571.8 2575.9 2576.7 2582.1 2480.9 2572.8 2575.5 2581.4 2587.8 2558.1 2565.8 2598.1 2603.4 2609.2 2534.9 2538.0 2541.4 2550.7 2557.1 2488.3 2511.1 2535.6 2540.5 2541.6 9704.7 9751.4 9774.0 9817.3 9823.0 5746.9 5889.6 5889.6 5897.2 5915.8 3722.3 3795.4 3817.0 3839.5 3901.3 1230.2 1264.2 1275.1 1276.7 1295.3 13602.4 13762.4 13855.2 13930.2 13970.8 5137.8 5161.7 5223.1 5252.8 5367.0 11545.8 11555.7 11559.2 11576.1 11596.1 5889.7 5940.0 5972.6 5973.0 5985.7 4660.5 5362.4 5445.8 5477.4 5503.1 4356.3 4434.6 4443.3 4463.5 4471.0 1396.3 1406.4 1425.3 1433.1 1433.2 945.5 1102.5 1104.4 1110.5 1111.1 6544.7 6558.3 6617.7 6694.7 6752.7 522.6 525.9 526.5 530.9 532.8 4568.7 4640.1 4664.5 4685.2 4718.7 12049.1 12352.4 12503.9 12505.1 12510.6 11929.2 12048.1 12074.6 12178.6 12226.6 8943.2 8978.6 9042.7 9049.9 9283.2 6558.6 6569.1 6595.6 6627.8 6632.9 10767.6 11106.1 11114.8 11145.9 11301.3 10639.6 11268.1 11337.3 11458.5 11525.8 7903.5 8273.9 8346.0 8529.4 8546.9 9558.2 9577.4 9658.5 9662.5 9668.0 8207.2 8358.8 8462.0 8697.9 8743.8 8477.3 8524.6 8532.8 8607.2 8692.9 7978.7 8091.9 8152.4 8164.3 8177.3 5808.7 5878.4 5924.7 5956.6 6067.9 5885.1 5952.9 5978.6 6043.9 6108.5 16:06:50 INFO - PROCESS | 4396 | 16:06:50 INFO - PROCESS | 4396 | [#4] prototype.html Cycles:180 Average:4947.92 Median:5477.25 stddev:1416.12 (25.9%) stddev-sans-first:1377.37 16:06:50 INFO - PROCESS | 4396 | Values: 348.3 354.5 355.0 360.6 362.7 5635.4 5740.6 5747.3 5821.4 5833.5 5678.6 5711.2 5719.7 5725.5 5732.7 4459.7 4478.8 4501.8 4522.0 4544.5 4449.0 4492.3 4518.8 4553.5 4557.7 4466.3 4469.3 4471.7 4476.8 4490.4 4452.2 4514.4 4553.2 4569.0 4574.9 3885.9 3948.8 3962.5 3963.9 3984.6 5614.5 5624.7 5630.8 5671.0 5684.5 5078.6 5212.6 5213.1 5217.5 5274.5 6078.4 6082.2 6087.8 6094.1 6105.2 4344.7 4349.1 4361.8 4364.6 4376.3 6021.8 6260.5 6264.0 6291.3 6342.7 4574.3 4610.5 4626.8 4634.5 4642.6 4683.9 4699.4 4706.4 4714.1 4740.8 5210.7 5245.5 5271.9 5284.0 5299.9 5896.4 6060.6 6068.7 6093.0 6134.3 1587.8 1598.1 1604.9 1608.2 1609.9 1014.3 1028.7 1060.3 1066.8 1067.9 5271.9 5289.1 5289.7 5301.7 5360.9 5478.6 5515.0 5529.6 5570.1 5585.3 4974.0 5227.5 5254.4 5270.3 5295.9 3000.0 3001.8 3011.3 3017.1 3029.8 5602.2 5666.9 5698.1 5702.0 5731.9 5404.8 5617.0 5629.7 5637.2 5661.8 5709.2 5762.2 5770.5 5815.3 5842.6 5383.7 5470.7 5475.9 5599.8 5664.5 5454.5 5503.4 5510.4 5533.8 5568.2 5667.9 5690.7 5736.4 5755.3 5786.5 4037.6 4137.8 4171.7 4182.5 4190.9 5429.7 5492.6 5561.5 6083.5 6331.9 6083.2 6138.2 6157.3 6166.7 6233.0 6094.6 6102.4 6150.9 6198.8 6263.6 6064.0 6121.4 6180.4 6188.5 6198.3 6208.8 6222.8 6285.8 6293.8 6303.2 5827.7 5962.6 6038.2 6049.3 6065.5 16:06:50 INFO - PROCESS | 4396 | 16:06:50 INFO - PROCESS | 4396 | [#5] yui.html Cycles:180 Average:22400.59 Median:5017.25 stddev:31490.14 (627.6%) stddev-sans-first:31536.77 16:06:50 INFO - PROCESS | 4396 | Values: 815.6 834.4 843.7 902.8 916.8 14947.4 15764.8 15860.2 16030.1 16127.2 16194.4 16520.6 16607.8 16622.5 16664.7 882.1 913.3 914.6 916.8 918.9 876.5 898.0 899.9 901.5 909.6 949.0 952.4 955.6 959.3 961.5 954.6 955.2 956.8 957.8 962.2 693.0 695.3 695.6 696.9 699.8 15989.2 16028.2 16053.6 16103.6 16126.9 14791.2 15385.4 15791.6 15940.4 16096.5 14787.6 14976.4 15031.8 15120.7 15327.1 5037.6 5055.5 5058.7 5060.7 5131.5 80877.0 93964.5 94490.0 94532.5 94815.0 13535.9 13730.3 13741.7 13810.7 13889.9 25155.5 25436.5 25663.0 25735.4 26102.7 8945.3 9480.9 9829.4 10024.7 10418.1 4873.4 4888.8 4996.9 5106.1 5156.6 3926.9 4031.8 4067.1 4068.5 4074.2 712.3 717.1 721.9 731.0 742.1 619.0 703.7 748.8 774.5 775.3 9893.8 10188.2 10546.2 10750.0 10806.6 607.7 641.0 755.5 763.7 780.0 5215.4 5295.2 5305.3 5318.8 5353.9 74661.0 105684.0 108691.5 123479.0 144307.3 88316.6 89900.1 118326.0 118509.4 119617.4 79537.0 80194.6 92956.0 94616.5 94855.5 9368.0 9369.0 9474.3 9520.7 9534.0 55808.0 56377.0 56437.0 57073.4 58032.0 67659.0 68419.3 74077.0 78545.6 79083.2 14160.3 14240.5 14347.4 14826.5 15280.8 15569.8 16301.2 16342.7 16841.2 16864.2 15813.9 15835.2 15867.1 15896.0 15936.8 14905.6 15215.1 15261.5 16064.1 16197.8 15602.3 16089.1 16228.3 16297.3 16685.8 12220.6 12628.9 12674.1 13047.3 13230.7 10267.5 11215.7 11890.4 12129.9 12240.8 16:06:50 INFO - PROCESS | 4396 | -------- Summary: end -------- 16:06:50 INFO - PROCESS | 4396 | 16:06:51 INFO - TEST-INFO | 4396: exit 0 16:06:51 INFO - TEST-OK | dromaeo_css | took 1110845ms 16:06:51 INFO - TEST-START | kraken 16:06:51 INFO - operating with platform_type : linux_ 16:06:51 INFO - Initialising browser for kraken test... 16:06:51 INFO - TEST-INFO | started process 4662 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcRLXbn/profile http://localhost:50150/getInfo.html) 16:06:55 INFO - PROCESS | 4662 | __metrics Screen width/height:1600/1200 16:06:55 INFO - PROCESS | 4662 | colorDepth:24 16:06:55 INFO - PROCESS | 4662 | Browser inner width/height: 1024/697 16:06:55 INFO - PROCESS | 4662 | __metrics 16:06:56 ERROR - PROCESS | 4662 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 16:06:57 INFO - TEST-INFO | 4662: exit 0 16:06:57 INFO - Browser initialized. 16:06:57 INFO - Running cycle 1/1 for kraken test... 16:06:57 INFO - TEST-INFO | started process 4789 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcRLXbn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:07:08 INFO - PROCESS | 4789 | RSS: Main: 152723456 16:07:08 INFO - PROCESS | 4789 | 16:07:11 INFO - PROCESS | 4789 | Cycle 1(1): loaded http://localhost:50150/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50150/tests/kraken/driver.html?testName=audio-beat-detection) 16:07:12 INFO - PROCESS | 4789 | RSS: Main: 157978624 16:07:12 INFO - PROCESS | 4789 | 16:07:14 INFO - PROCESS | 4789 | Cycle 1(1): loaded http://localhost:50150/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50150/tests/kraken/driver.html?testName=audio-dft) 16:07:15 INFO - PROCESS | 4789 | RSS: Main: 156209152 16:07:15 INFO - PROCESS | 4789 | 16:07:18 INFO - PROCESS | 4789 | Cycle 1(1): loaded http://localhost:50150/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50150/tests/kraken/driver.html?testName=audio-fft) 16:07:19 INFO - PROCESS | 4789 | RSS: Main: 156786688 16:07:19 INFO - PROCESS | 4789 | 16:07:21 INFO - PROCESS | 4789 | Cycle 1(1): loaded http://localhost:50150/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50150/tests/kraken/driver.html?testName=audio-oscillator) 16:07:21 INFO - PROCESS | 4789 | RSS: Main: 156454912 16:07:21 INFO - PROCESS | 4789 | 16:07:23 INFO - PROCESS | 4789 | Cycle 1(1): loaded http://localhost:50150/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50150/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:07:24 INFO - PROCESS | 4789 | RSS: Main: 156635136 16:07:24 INFO - PROCESS | 4789 | 16:07:27 INFO - PROCESS | 4789 | Cycle 1(1): loaded http://localhost:50150/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50150/tests/kraken/driver.html?testName=imaging-darkroom) 16:07:28 INFO - PROCESS | 4789 | RSS: Main: 156037120 16:07:28 INFO - PROCESS | 4789 | 16:07:31 INFO - PROCESS | 4789 | Cycle 1(1): loaded http://localhost:50150/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50150/tests/kraken/driver.html?testName=imaging-desaturate) 16:07:32 INFO - PROCESS | 4789 | RSS: Main: 156520448 16:07:32 INFO - PROCESS | 4789 | 16:07:35 INFO - PROCESS | 4789 | Cycle 1(1): loaded http://localhost:50150/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50150/tests/kraken/driver.html?testName=json-parse-financial) 16:07:35 INFO - PROCESS | 4789 | RSS: Main: 157470720 16:07:35 INFO - PROCESS | 4789 | 16:07:37 INFO - PROCESS | 4789 | Cycle 1(1): loaded http://localhost:50150/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50150/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:07:37 INFO - PROCESS | 4789 | RSS: Main: 157167616 16:07:37 INFO - PROCESS | 4789 | 16:07:39 INFO - PROCESS | 4789 | Cycle 1(1): loaded http://localhost:50150/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50150/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:07:40 INFO - PROCESS | 4789 | RSS: Main: 156676096 16:07:40 INFO - PROCESS | 4789 | 16:07:42 INFO - PROCESS | 4789 | Cycle 1(1): loaded http://localhost:50150/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50150/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:07:42 INFO - PROCESS | 4789 | RSS: Main: 157265920 16:07:42 INFO - PROCESS | 4789 | 16:07:45 INFO - PROCESS | 4789 | Cycle 1(1): loaded http://localhost:50150/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50150/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:07:46 INFO - PROCESS | 4789 | RSS: Main: 157483008 16:07:46 INFO - PROCESS | 4789 | 16:07:48 INFO - PROCESS | 4789 | Cycle 1(1): loaded http://localhost:50150/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50150/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:07:49 INFO - PROCESS | 4789 | RSS: Main: 157659136 16:07:49 INFO - PROCESS | 4789 | 16:07:51 INFO - PROCESS | 4789 | Cycle 1(1): loaded http://localhost:50150/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50150/tests/kraken/driver.html?testName=ai-astar) 16:07:51 INFO - PROCESS | 4789 | RSS: Main: 158560256 16:07:51 INFO - PROCESS | 4789 | 16:07:51 INFO - PROCESS | 4789 | __start_tp_report 16:07:51 INFO - PROCESS | 4789 | _x_x_mozilla_page_load 16:07:51 INFO - PROCESS | 4789 | _x_x_mozilla_page_load_details 16:07:51 INFO - PROCESS | 4789 | |i|pagename|runs| 16:07:51 INFO - PROCESS | 4789 | |0;ai-astar;102;111;107;114;113;112;112;110;111;113 16:07:51 INFO - PROCESS | 4789 | |1;audio-beat-detection;149;116;142;169;117;137;151;116;172;118 16:07:51 INFO - PROCESS | 4789 | |2;audio-dft;162;162;164;161;162;162;162;164;163;162 16:07:51 INFO - PROCESS | 4789 | |3;audio-fft;75;76;77;168;78;75;75;76;166;76 16:07:51 INFO - PROCESS | 4789 | |4;audio-oscillator;85;83;152;84;85;82;135;83;93;143 16:07:51 INFO - PROCESS | 4789 | |5;imaging-gaussian-blur;118;118;117;117;117;118;118;117;117;118 16:07:51 INFO - PROCESS | 4789 | |6;imaging-darkroom;149;147;147;148;146;147;146;148;148;147 16:07:51 INFO - PROCESS | 4789 | |7;imaging-desaturate;95;95;97;96;95;96;95;96;95;95 16:07:51 INFO - PROCESS | 4789 | |8;json-parse-financial;57;52;51;54;52;51;52;52;52;65 16:07:51 INFO - PROCESS | 4789 | |9;json-stringify-tinderbox;61;60;60;60;61;61;60;60;61;61 16:07:51 INFO - PROCESS | 4789 | |10;stanford-crypto-aes;73;73;76;76;74;72;77;73;78;78 16:07:51 INFO - PROCESS | 4789 | |11;stanford-crypto-ccm;128;123;126;135;122;130;117;118;163;135 16:07:51 INFO - PROCESS | 4789 | |12;stanford-crypto-pbkdf2;155;151;154;153;154;159;150;150;149;149 16:07:51 INFO - PROCESS | 4789 | |13;stanford-crypto-sha256-iterative;64;61;63;62;60;60;62;61;62;60 16:07:51 INFO - PROCESS | 4789 | __end_tp_report 16:07:51 INFO - PROCESS | 4789 | __start_cc_report 16:07:51 INFO - PROCESS | 4789 | _x_x_mozilla_cycle_collect,828 16:07:51 INFO - PROCESS | 4789 | __end_cc_report 16:07:51 INFO - PROCESS | 4789 | __startTimestamp1471907271304__endTimestamp 16:07:51 INFO - PROCESS | 4789 | 16:07:51 INFO - PROCESS | 4789 | ------- Summary: start ------- 16:07:51 INFO - PROCESS | 4789 | Number of tests: 14 16:07:51 INFO - PROCESS | 4789 | 16:07:51 INFO - PROCESS | 4789 | [#0] ai-astar Cycles:10 Average:110.50 Median:112.00 stddev:3.57 (3.2%) stddev-sans-first:2.07 16:07:51 INFO - PROCESS | 4789 | Values: 102.0 111.0 107.0 114.0 113.0 112.0 112.0 110.0 111.0 113.0 16:07:51 INFO - PROCESS | 4789 | 16:07:51 INFO - PROCESS | 4789 | [#1] audio-beat-detection Cycles:10 Average:138.70 Median:145.50 stddev:21.67 (14.9%) stddev-sans-first:22.67 16:07:51 INFO - PROCESS | 4789 | Values: 149.0 116.0 142.0 169.0 117.0 137.0 151.0 116.0 172.0 118.0 16:07:51 INFO - PROCESS | 4789 | 16:07:51 INFO - PROCESS | 4789 | [#2] audio-dft Cycles:10 Average:162.40 Median:162.00 stddev:0.97 (0.6%) stddev-sans-first:1.01 16:07:51 INFO - PROCESS | 4789 | Values: 162.0 162.0 164.0 161.0 162.0 162.0 162.0 164.0 163.0 162.0 16:07:51 INFO - PROCESS | 4789 | 16:07:51 INFO - PROCESS | 4789 | [#3] audio-fft Cycles:10 Average:94.20 Median:76.00 stddev:38.38 (50.5%) stddev-sans-first:40.08 16:07:51 INFO - PROCESS | 4789 | Values: 75.0 76.0 77.0 168.0 78.0 75.0 75.0 76.0 166.0 76.0 16:07:51 INFO - PROCESS | 4789 | 16:07:51 INFO - PROCESS | 4789 | [#4] audio-oscillator Cycles:10 Average:102.50 Median:83.50 stddev:28.62 (34.3%) stddev-sans-first:29.65 16:07:51 INFO - PROCESS | 4789 | Values: 85.0 83.0 152.0 84.0 85.0 82.0 135.0 83.0 93.0 143.0 16:07:51 INFO - PROCESS | 4789 | 16:07:51 INFO - PROCESS | 4789 | [#5] imaging-gaussian-blur Cycles:10 Average:117.50 Median:118.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 16:07:51 INFO - PROCESS | 4789 | Values: 118.0 118.0 117.0 117.0 117.0 118.0 118.0 117.0 117.0 118.0 16:07:51 INFO - PROCESS | 4789 | 16:07:51 INFO - PROCESS | 4789 | [#6] imaging-darkroom Cycles:10 Average:147.30 Median:147.50 stddev:0.95 (0.6%) stddev-sans-first:0.78 16:07:51 INFO - PROCESS | 4789 | Values: 149.0 147.0 147.0 148.0 146.0 147.0 146.0 148.0 148.0 147.0 16:07:51 INFO - PROCESS | 4789 | 16:07:51 INFO - PROCESS | 4789 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:95.50 stddev:0.71 (0.7%) stddev-sans-first:0.73 16:07:51 INFO - PROCESS | 4789 | Values: 95.0 95.0 97.0 96.0 95.0 96.0 95.0 96.0 95.0 95.0 16:07:51 INFO - PROCESS | 4789 | 16:07:51 INFO - PROCESS | 4789 | [#8] json-parse-financial Cycles:10 Average:53.80 Median:52.00 stddev:4.32 (8.3%) stddev-sans-first:4.42 16:07:51 INFO - PROCESS | 4789 | Values: 57.0 52.0 51.0 54.0 52.0 51.0 52.0 52.0 52.0 65.0 16:07:51 INFO - PROCESS | 4789 | 16:07:51 INFO - PROCESS | 4789 | [#9] json-stringify-tinderbox Cycles:10 Average:60.50 Median:61.00 stddev:0.53 (0.9%) stddev-sans-first:0.53 16:07:51 INFO - PROCESS | 4789 | Values: 61.0 60.0 60.0 60.0 61.0 61.0 60.0 60.0 61.0 61.0 16:07:51 INFO - PROCESS | 4789 | 16:07:51 INFO - PROCESS | 4789 | [#10] stanford-crypto-aes Cycles:10 Average:75.00 Median:76.00 stddev:2.26 (3.0%) stddev-sans-first:2.28 16:07:51 INFO - PROCESS | 4789 | Values: 73.0 73.0 76.0 76.0 74.0 72.0 77.0 73.0 78.0 78.0 16:07:51 INFO - PROCESS | 4789 | 16:07:51 INFO - PROCESS | 4789 | [#11] stanford-crypto-ccm Cycles:10 Average:129.70 Median:129.00 stddev:13.27 (10.3%) stddev-sans-first:14.06 16:07:51 INFO - PROCESS | 4789 | Values: 128.0 123.0 126.0 135.0 122.0 130.0 117.0 118.0 163.0 135.0 16:07:51 INFO - PROCESS | 4789 | 16:07:51 INFO - PROCESS | 4789 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.40 Median:153.50 stddev:3.20 (2.1%) stddev-sans-first:3.26 16:07:51 INFO - PROCESS | 4789 | Values: 155.0 151.0 154.0 153.0 154.0 159.0 150.0 150.0 149.0 149.0 16:07:51 INFO - PROCESS | 4789 | 16:07:51 INFO - PROCESS | 4789 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.50 Median:62.00 stddev:1.35 (2.2%) stddev-sans-first:1.09 16:07:51 INFO - PROCESS | 4789 | Values: 64.0 61.0 63.0 62.0 60.0 60.0 62.0 61.0 62.0 60.0 16:07:51 INFO - PROCESS | 4789 | -------- Summary: end -------- 16:07:51 INFO - PROCESS | 4789 | 16:07:52 INFO - TEST-INFO | 4789: exit 0 16:07:52 INFO - TEST-OK | kraken | took 60968ms 16:07:52 INFO - SUITE-END | took 1171s 16:07:52 INFO - Completed test suite (00:19:31) 16:07:52 INFO - javascript benchmark 16:07:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7883.217478953149, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3257.543943847861, 3272.2637406583535, 3289.8519566619498, 3294.1599892027543, 3295.950560741591, 5716.8284951451415, 5757.2803399575005, 5760.711964401774, 5771.855703607412, 5779.104238842976, 5744.368119506861, 5763.337216220136, 5774.942250577493, 5787.276590426197, 5791.942080579193, 5154.819581314646, 5196.220566914974, 5208.479152084799, 5232.502912223333, 5282.102042652739, 5156.3296771419855, 5164.483551644843, 5201.375834899817, 5283.603729720267, 5380.058489764295, 5187.195984622371, 5218.0, 5231.764570594313, 5415.891682166354, 5471.097268950638, 5263.815766448175, 5283.260343551906, 5284.841454756363, 5287.569573283854, 5288.65623734456, 5082.822101226477, 5115.61632877532, 5177.326947496801, 5214.0875346814155, 5227.712475813829, 5622.971885140548, 5670.971645141748, 5674.347450043216, 5746.454086861741, 5811.506021988136, 6235.501159907242, 6240.656763877985, 6262.812115636538, 6266.749330026793, 6267.122602835607, 5868.911966320508, 6264.498840092781, 6278.0269058296035, 6291.339409362043, 6302.653354065525, 5741.971290143522, 5793.159991801165, 5824.504917082053, 5874.383189765099, 5912.497437717756, 6377.457916077093, 6379.298277189506, 6389.7763578274535, 6396.71214795326, 6464.903026454607, 5952.077427998667, 6085.4523092921845, 6159.291681456601, 6174.845628859314, 6204.968975155095, 6114.266954506099, 6160.0, 6165.075238714139, 6277.811665649946, 6280.685965701697, 6178.042403427476, 6193.287771906198, 6248.937510624926, 6258.436740693269, 6289.685515724195, 6039.063945088518, 6263.843403914939, 6265.373462653789, 6273.749050037947, 6280.623162610258, 4712.6229901607785, 4749.548792864742, 4757.500462451393, 4772.642051846056, 4816.470188279286, 4216.536181020086, 4239.639630631369, 4246.702730808814, 4254.744715317091, 4269.316909294498, 5753.424657534297, 5982.670953097534, 6029.16824611452, 6038.396160383926, 6073.544484163705, 6112.533877023262, 6123.740204357194, 6128.503123582109, 6288.308286088527, 6300.338464461257, 5952.821415357546, 5969.283685957626, 6000.279966403972, 6017.398260173947, 6029.246344206974, 5189.6886186828915, 5357.678539287655, 5373.946260537423, 5384.623076384615, 5392.56859451243, 6370.362963703593, 6384.904226436607, 6414.967925160345, 6534.183227096613, 6547.148870646785, 6257.311695713559, 6270.278917924497, 6317.873642527215, 6329.683515824098, 6332.905006424815, 6252.656103914328, 6468.223813142453, 6517.7067031984625, 6519.5436319457185, 6527.05357723118, 6042.184305118929, 6048.183495228088, 6116.021436570128, 6178.721957511832, 6196.442320191204, 6262.1546091278915, 6293.465055470153, 6296.5277604178955, 6303.684815759102, 6330.0, 5896.70516474166, 6124.173236613, 6154.661493617894, 6159.6612186330185, 6161.260648722182, 6003.0695242237525, 6024.969875150597, 6045.516358691206, 6096.02463605821, 6138.693065346804, 6025.969870150797, 6045.244344456943, 6065.54508411862, 6065.8180254592435, 6085.726142323695, 5964.731587078505, 6075.301340345917, 6099.695015249131, 6158.599691020069, 6186.659733714689, 5858.47273745348, 5864.208331875313, 5923.466887980103, 5991.64050156992, 6010.068439391901], "value": 5726.739750729154, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [586.9060950247962, 640.5164101103667, 661.0678942690804, 663.5090033375302, 702.6065403374114, 43306.0, 47526.287105693445, 48046.519534804684, 48087.51912480874, 48357.51642483574, 47811.76094119524, 48322.51677483224, 48510.0, 48597.0, 48747.756261218645, 9067.183953444171, 9196.632134714637, 9199.86200206994, 9208.953955230214, 9243.907560924372, 9147.4054186478, 9247.768805779842, 9260.768480787967, 9262.39794413361, 9267.165955064043, 9306.255499560019, 9355.812883742321, 9376.953115234413, 9388.295877809192, 9405.153536181742, 9247.815043699122, 9248.121428464352, 9259.907400925971, 9270.768230794216, 9315.580798864034, 9107.225885799715, 9201.0, 9232.122948319899, 9297.581608827619, 9329.860052099224, 44347.77826110881, 44409.11181776346, 44495.77752111251, 44676.55323446757, 44840.10319793619, 3811.4092315691114, 3924.646781789624, 3974.1654252606986, 4055.472788537501, 4055.5336136344404, 35145.0, 35889.82055089734, 35990.46014309761, 36237.275254494765, 36253.45619815705, 4467.7542735149555, 4494.438195225597, 4499.527549607277, 4521.253993091136, 4555.498895121534, 394034.0, 394560.0, 395749.0, 398116.0, 399040.9999999971, 66061.0, 66748.0, 66786.66606666935, 66799.0, 66855.0, 42336.78831605822, 43330.7833460837, 43496.130077398906, 43579.56420435755, 70833.64583177154, 19407.223711051683, 19552.706709399372, 19562.413127606196, 19594.0, 19605.803941960683, 14890.255487225595, 15002.57475539822, 15018.549443516711, 15039.548813535612, 15084.547463576111, 21250.25624103149, 21728.34814955554, 21875.234366797406, 21954.121835126734, 21985.67021494647, 7331.486795924341, 7526.774196774106, 7647.388208943271, 7813.038720412465, 8014.599270036475, 1037.3361048928691, 1133.3313345126414, 1155.064397837754, 1171.1860257121389, 1271.505980472959, 36072.45891311632, 36376.81811590978, 36384.27231455409, 36476.45285320722, 36510.087247819014, 1160.1241062997383, 1236.7773800715956, 1259.8551166615773, 1331.3077199856214, 1355.1394864261265, 17680.734788978174, 18757.155927983273, 18763.90618046901, 18811.52971175704, 18898.338558150397, 671369.0, 673927.0, 674664.0, 677918.0, 678129.0, 67702.66148669225, 67721.0, 67940.6602966982, 68145.0, 68182.0, 1453.869151776324, 1475.955721328362, 1487.4868170481166, 1517.1049081042238, 1518.8481151884723, 35017.12457188489, 35151.47272790859, 35156.824215878754, 35252.647473524936, 35899.641003590674, 59085.40914590971, 59211.40788592059, 59462.0, 59506.70246648739, 59663.0, 498045.0, 502203.0, 506588.0, 507960.0, 508260.0, 31946.68053319437, 32043.198920027186, 32272.35455290928, 32329.51505727369, 32570.83714581507, 53628.463715363905, 53718.73140634272, 54131.45868541422, 54525.18212226899, 55643.44356556382, 45338.0, 45651.27443307316, 47223.874925627744, 47627.76186119047, 47818.28272575844, 46755.0, 46913.296300556205, 47006.76496617495, 47722.39911625309, 48081.75959120182, 46528.208307501656, 47018.0, 47422.28866566935, 48076.27885581649, 49602.25596615981, 38586.42120368253, 40924.1815163701, 41757.164856703304, 42273.365899512144, 42814.78592607017, 31413.0, 31581.368372631958, 31988.6801131995, 32046.0, 32144.517832232068], "value": 22244.527776781764, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [400.97594144351336, 406.35186876931283, 407.85724996251315, 414.7635847566886, 430.27928220231126, 20501.0, 20615.587688246225, 21498.677519837194, 21584.784152158474, 21687.915604219765, 20374.0, 20697.344212463006, 20985.895070524624, 21372.35882923511, 21457.141714331447, 5791.82624521264, 5967.433093856077, 5968.88062238755, 5972.79095231667, 5995.460408563228, 5836.5914385993, 5836.649801011931, 5864.413558644144, 5911.0, 5937.050071988483, 5843.824685259449, 5863.296404431474, 5875.441833025877, 5913.290405151366, 6006.579539432241, 5821.359650438449, 5865.8826823463505, 5866.413358664142, 5915.822525324204, 5974.761009559634, 5291.603129765244, 5360.571154307685, 5382.354117505885, 5392.083345831218, 5397.8110766123, 25017.124400646073, 25146.748532514623, 25262.873685631454, 25375.23874283756, 26040.479190416274, 2456.754324567529, 2485.825992180548, 2487.5273697997504, 2551.119863647039, 2738.219607411878, 15617.531474055797, 15948.840511594852, 16085.83914160855, 16116.516504504883, 16490.83509164905, 1982.4052784164694, 1994.3618042226494, 2015.7177995080701, 2030.3807338761671, 2036.4094412620323, 65314.0, 73499.63250183768, 77725.0, 83721.58139209326, 87646.0, 10813.56745730162, 10895.83656245157, 11088.833667494993, 11313.094952403788, 11354.829677554842, 15355.769663455058, 15366.615834604225, 15393.307301171475, 15393.307301171475, 15475.458358957383, 8240.711575094843, 8457.0, 8726.563671816384, 8970.686025989058, 9143.177114059767, 8998.775030624156, 9052.728418147466, 9097.726318315441, 9116.58975346111, 9322.813543729224, 6847.760328388483, 6881.518293719391, 6885.965570172117, 6952.687129079203, 7022.157341119098, 1838.9356372526897, 1875.109148516833, 1962.8822270663807, 1979.6139752748077, 1989.0651393844869, 999.280518027034, 1082.6860210538855, 1144.7080994346554, 1152.2510368260698, 1199.9880001200052, 21900.342989710334, 21959.34121976343, 22136.335909922727, 22239.777602224094, 22459.213927512457, 1089.6622047165404, 1101.3336931156696, 1116.17403121691, 1151.4645689754304, 1166.2302880099094, 12088.939555302168, 12328.876711232951, 12587.055970802227, 12649.683757906127, 12689.175203611736, 545591.0, 553539.0, 555315.0, 559232.0, 561768.3823161798, 51542.742286288325, 52802.471975280525, 52925.0, 71563.64218178876, 89692.5515372419, 1060.9734756631146, 1076.978460430787, 1114.2924243105685, 1125.5216532973454, 1130.9604163854226, 18448.907755461136, 19005.429837104908, 19285.517862053563, 19300.517487062374, 19412.0, 39800.0, 41317.38023929583, 41376.0, 42079.36880946849, 42407.151856963304, 57064.71467642635, 57609.0, 57969.71015144897, 58078.4192158073, 58530.0, 8475.406721529434, 8717.738467845975, 8768.035516093098, 8839.51382673959, 8865.601047952729, 13020.218786872818, 13938.512152074629, 14092.929535352258, 14344.928275358974, 14593.416263349347, 11838.585649502225, 12363.258204507758, 12565.0, 12590.559330423392, 12686.365681716063, 12126.151169418408, 12197.207181533173, 12365.876341236473, 12381.133320667826, 12384.256944583354, 12652.683682908, 12769.170003949714, 12859.678508037374, 12877.420516076612, 12971.805422918853, 8227.177282271725, 8320.375971802017, 8378.162183781573, 8381.413301068867, 8625.568721564023, 10482.895171048496, 12463.626091217278, 12513.93743031279, 12580.0, 12625.116241862981], "value": 9823.234890480508, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1598.2727858824237, 1600.151919482674, 1811.2573844723659, 1872.8314451699343, 1918.5683221275206, 8525.658973641062, 9171.633134674614, 9347.112024357675, 9979.251556133291, 10010.199184065266, 9393.20157786589, 9761.121499065082, 9774.853377199348, 9776.364536305135, 9845.11393974542, 2530.0765220694407, 2571.832830865997, 2575.9484810303784, 2576.6521519594803, 2582.122078493312, 2480.9007639694482, 2572.7555882191164, 2575.510652975938, 2581.4062765563835, 2587.7541633544784, 2558.09187738353, 2565.8460492370423, 2598.116640342283, 2603.4142317978494, 2609.23027706826, 2534.9493010139786, 2538.0482319130324, 2541.3900663840636, 2550.6939167300043, 2557.0538900606894, 2488.315713178872, 2511.1462102884925, 2535.6323333116593, 2540.453802432486, 2541.6314634377914, 9704.70885873425, 9751.3661611995, 9773.951130244302, 9817.263705222136, 9822.9508852456, 5746.856328591777, 5889.587728858982, 5889.587728858982, 5897.233359663259, 5915.763369465216, 3722.3113723961155, 3795.449659799341, 3817.0, 3839.462475253467, 3901.2977664020464, 1230.2003697596544, 1264.178284115323, 1275.0628288208159, 1276.7063575377622, 1295.309620944667, 13602.387892544859, 13762.449502019907, 13855.23796191199, 13930.164190148624, 13970.790438143436, 5137.820176293886, 5161.741912904415, 5223.059849227099, 5252.789888404421, 5367.0, 11545.826812597817, 11555.711107222387, 11559.190856639954, 11576.131790115607, 11596.072314214842, 5889.705514724246, 5940.049592065248, 5972.641641501524, 5972.970135149297, 5985.730642121115, 4660.487346391962, 5362.436944120889, 5445.809396671098, 5477.397486276506, 5503.147012213113, 4356.324769660644, 4434.623057040188, 4443.311286750559, 4463.509014008457, 4471.0, 1396.3018490754623, 1406.3741634972544, 1425.287356321839, 1433.118632041308, 1433.240382597225, 945.5130607737021, 1102.5259138570332, 1104.447776111944, 1110.4780753046055, 1111.1388673777726, 6544.70548825304, 6558.278589355167, 6617.735290588419, 6694.698738556679, 6752.662366881636, 522.6341560907304, 525.8606469285643, 526.4866754914004, 530.875244317585, 532.8348212054276, 4568.725876447357, 4640.118377508263, 4664.463586687507, 4685.226937555333, 4718.693284936468, 12049.096317776202, 12352.44414001372, 12503.937480312541, 12505.124641275206, 12510.624681259576, 11929.164958452826, 12048.096392770578, 12074.637760867188, 12178.573749918887, 12226.633201003984, 8943.150400712053, 8978.551072446351, 9042.683506077256, 9049.909500905038, 9283.16451519353, 6558.606483610999, 6569.113169722026, 6595.604263744191, 6627.768028118992, 6632.90050649231, 10767.6231331903, 11106.055985241344, 11114.777704445703, 11145.888541114486, 11301.321920684784, 10639.574417023232, 11268.098552115647, 11337.26307789991, 11458.484368203284, 11525.769484610428, 7903.525788452711, 8273.917260827315, 8345.956755405574, 8529.402941794264, 8546.914530854861, 9558.235341172829, 9577.425354478753, 9658.517074146404, 9662.468564229035, 9668.0, 8207.220314070153, 8358.791030224293, 8461.957690211755, 8697.8695319569, 8743.825123497621, 8477.321814254969, 8524.573771311534, 8532.829343413221, 8607.18231767981, 8692.91307086921, 7978.680852765824, 8091.87862182056, 8152.388570857091, 8164.346852251687, 8177.34581233488, 5808.662825352639, 5878.353381128158, 5924.674142922181, 5956.612820166849, 6067.939320606737, 5885.087811389241, 5952.910706339322, 5978.581499295007, 6043.909341359971, 6108.480779133645], "value": 5084.288758430444, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.28253797177814, 354.45591017787694, 354.9609542950275, 360.6321552016941, 362.71889285803496, 5635.436456354362, 5740.6268592541455, 5747.252857128573, 5821.388754180808, 5833.45413465432, 5678.630888992223, 5711.200431939532, 5719.714014299289, 5725.541956643458, 5732.742026608813, 4459.665525085616, 4478.753668548228, 4501.774911254441, 4522.0, 4544.477385100723, 4449.0, 4492.258777301741, 4518.819247230107, 4553.453585569736, 4557.74932378719, 4466.307722303049, 4469.307257375096, 4471.731696098244, 4476.798544065525, 4490.3937968374175, 4452.243118669818, 4514.367988481632, 4553.180427523044, 4568.954310456887, 4574.908501829978, 3885.8639947601982, 3948.7828169450668, 3962.5046869141356, 3963.9009024774323, 3984.621460961204, 5614.456024593229, 5624.746886390081, 5630.774769009235, 5670.979223739725, 5684.459976302244, 5078.64449488536, 5212.582993360522, 5213.087709650834, 5217.452167522431, 5274.525292723635, 6078.39216078393, 6082.239720034996, 6087.847803804896, 6094.116353128771, 6105.236845394325, 4344.717593356421, 4349.086691794727, 4361.760103194323, 4364.607185353301, 4376.256036473807, 6021.849453763647, 6260.499160067229, 6263.96868015657, 6291.276503202144, 6342.682865856688, 4574.336721175411, 4610.53894610543, 4626.791794369262, 4634.536546345338, 4642.605378542862, 4683.882902927454, 4699.412573428322, 4706.3646407735205, 4714.104320179155, 4740.762961851962, 5210.697325668583, 5245.475452454801, 5271.894562108813, 5284.0, 5299.867503312448, 5896.439838215363, 6060.636361818217, 6068.666223357758, 6093.0, 6134.263888333339, 1587.7935868337042, 1598.1449924290448, 1604.8796340274525, 1608.1557182479291, 1609.8551130398087, 1014.2798612984864, 1028.711960651019, 1060.3213943076437, 1066.847804371277, 1067.9466026698633, 5271.947280527145, 5289.12236156159, 5289.656172348785, 5301.734913254322, 5360.946390536123, 5478.561715062785, 5515.0, 5529.640573362719, 5570.106163904292, 5585.273914391184, 4974.0, 5227.503387178212, 5254.369475662945, 5270.341207349082, 5295.947040529622, 2999.9550006749914, 3001.7898747087493, 3011.26224075103, 3017.0797906638245, 3029.7576193904433, 5602.187682785973, 5666.914996275059, 5698.059820129715, 5701.971490142606, 5731.8822829547835, 5404.810831620953, 5617.0, 5629.746661400247, 5637.182608521742, 5661.801082388437, 5709.172170035322, 5762.193292938992, 5770.451807078321, 5815.273090863642, 5842.561807864512, 5383.707910101626, 5470.699111548903, 5475.946520054979, 5599.776008959759, 5664.546836253172, 5454.509094181542, 5503.394626590992, 5510.448955104457, 5533.750981205775, 5568.220449137043, 5667.914981275202, 5690.715464226855, 5736.368999410145, 5755.3381361144, 5786.495511167042, 4037.6366127048705, 4137.834486620622, 4171.749695018309, 4182.498100227992, 4190.895227619334, 5429.69687275059, 5492.615516913777, 5561.499465048165, 6083.452489275986, 6331.936680633319, 6083.239595050619, 6138.232720909887, 6157.291911430243, 6166.660833654192, 6233.0, 6094.573379863367, 6102.359252278625, 6150.907736383869, 6198.752049917953, 6263.6241825490615, 6064.0, 6121.357257488078, 6180.381961803783, 6188.4739797117745, 6198.318184999736, 6208.844778880564, 6222.844428889314, 6285.811425657238, 6293.842653933688, 6303.212098487689, 5827.737751801094, 5962.582619216787, 6038.184845045863, 6049.274087109575, 6065.484433823174], "value": 4501.940281893307, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [815.6003558256455, 834.3908946469078, 843.7426584891609, 902.8194361127775, 916.755987809516, 14947.402103915843, 15764.84235157651, 15860.206989650515, 16030.118343491104, 16127.19364031798, 16194.433194838182, 16520.58698532537, 16607.750883736753, 16622.50132496024, 16664.66670666586, 882.1311008656468, 913.3058875254787, 914.5610107148573, 916.7616419730851, 918.8897332320098, 876.5310558851016, 898.0256421782374, 899.8515244984602, 901.5447199164413, 909.6179604566065, 949.0367277213634, 952.3857112162685, 955.5508910811942, 959.3140904253493, 961.4759955824093, 954.6420092465326, 955.1642312976146, 956.7608097975506, 957.8371676814958, 962.1821451766012, 692.9930700692979, 695.2942763095463, 695.6174104242647, 696.8919817428307, 699.8355386484172, 15989.200539972955, 16028.19859007045, 16053.598660033593, 16103.597410064725, 16126.919365403099, 14791.186484743335, 15385.384584616713, 15791.605209869846, 15940.362385504566, 16096.517104486884, 14787.556373308818, 14976.400943962228, 15031.849681503154, 15120.697586048218, 15327.080375177526, 5037.647364684473, 5055.545000949932, 5058.696478211283, 5060.746962651852, 5131.461196574381, 80877.0, 93964.53017734867, 94490.0, 94532.52733736287, 94815.0, 13535.932320338336, 13730.313484325743, 13741.656458588415, 13810.65473363174, 13889.930550347184, 25155.496890062463, 25436.491270174494, 25663.0, 25735.356616084373, 26102.738972610412, 8945.284377249804, 9480.90519094814, 9829.410235385756, 10024.749381265381, 10418.06237438619, 4873.41079019938, 4888.780004899788, 4996.850094497171, 5106.055379754757, 5156.613254005964, 3926.862559810393, 4031.7984100794843, 4067.1458993611673, 4068.5117785865887, 4074.2258970796047, 712.2770388056127, 717.089296593334, 721.9494635375473, 731.0386841303668, 742.0983505041401, 618.9690515474208, 703.6868593475854, 748.8202831320444, 774.4694884004475, 775.2789905388044, 9893.802123957481, 10188.235882308856, 10546.209034322457, 10750.0, 10806.567737290421, 607.6840043177523, 640.9583377080568, 755.4900442201492, 763.6983391560302, 779.9571023593643, 5215.426303106655, 5295.232191332312, 5305.31030965972, 5318.813841515528, 5353.892922141613, 74661.0, 105684.0, 108691.45654271678, 123479.0, 144307.278463607, 88316.5584172075, 89900.10099899049, 118326.0, 118509.40745296219, 119617.40191299161, 79537.0, 80194.59902700449, 92956.0, 94616.52691736635, 94855.52572237095, 9368.0, 9368.95315523418, 9474.336796424184, 9520.666776662783, 9534.0, 55808.0, 56377.0, 56437.0, 57073.42926570847, 58032.0, 67659.0, 68419.31580684129, 74077.0, 78545.60727196328, 79083.20916790988, 14160.291985400894, 14240.501582444565, 14347.354369053626, 14826.462205050659, 15280.770788438405, 15569.76645350298, 16301.184940752677, 16342.673146537241, 16841.24214410379, 16864.241109149872, 15813.920930395274, 15835.208239588204, 15867.127307997709, 15896.0, 15936.840631594, 14905.552833415015, 15215.08709477435, 15261.542153735405, 16064.116473594064, 16197.75703364427, 15602.297896594908, 16089.115098669685, 16228.269727862038, 16297.266623001757, 16685.833141668914, 12220.633380998584, 12628.936855315664, 12674.112812103065, 13047.28239946774, 13230.735385292433, 10267.537960791931, 11215.71960700989, 11890.405479725807, 12129.939350303192, 12240.755184896074], "value": 8378.440819860645, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1501.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 111.0, 107.0, 114.0, 113.0, 112.0, 112.0, 110.0, 111.0, 113.0], "value": 110.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 116.0, 142.0, 169.0, 117.0, 137.0, 151.0, 116.0, 172.0, 118.0], "value": 138.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 164.0, 161.0, 162.0, 162.0, 162.0, 164.0, 163.0, 162.0], "value": 162.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 77.0, 168.0, 78.0, 75.0, 75.0, 76.0, 166.0, 76.0], "value": 94.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 152.0, 84.0, 85.0, 82.0, 135.0, 83.0, 93.0, 143.0], "value": 102.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 117.0, 117.0, 117.0, 118.0, 118.0, 117.0, 117.0, 118.0], "value": 117.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 147.0, 147.0, 148.0, 146.0, 147.0, 146.0, 148.0, 148.0, 147.0], "value": 147.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 97.0, 96.0, 95.0, 96.0, 95.0, 96.0, 95.0, 95.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 52.0, 51.0, 54.0, 52.0, 51.0, 52.0, 52.0, 52.0, 65.0], "value": 53.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 60.0, 60.0, 60.0, 61.0, 61.0, 60.0, 60.0, 61.0, 61.0], "value": 60.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 76.0, 76.0, 74.0, 72.0, 77.0, 73.0, 78.0, 78.0], "value": 75.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 123.0, 126.0, 135.0, 122.0, 130.0, 117.0, 118.0, 163.0, 135.0], "value": 129.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 151.0, 154.0, 153.0, 154.0, 159.0, 150.0, 150.0, 149.0, 149.0], "value": 152.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 61.0, 63.0, 62.0, 60.0, 60.0, 62.0, 61.0, 62.0, 60.0], "value": 61.5, "unit": "score"}]}]} 16:07:52 INFO - Return code: 0 16:07:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:07:52 ERROR - # TBPL SUCCESS # 16:07:52 INFO - Running post-action listener: _resource_record_post_action 16:07:52 INFO - [mozharness: 2016-08-22 23:07:52.600995Z] Finished run-tests step (success) 16:07:52 INFO - Running post-run listener: _resource_record_post_run 16:07:52 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 278528; Write bytes: 327942144; Read time: 268; Write time: 186028 16:07:52 INFO - TinderboxPrint: CPU usage
13.3% 16:07:52 INFO - TinderboxPrint: I/O read bytes / time
278,528 / 268 16:07:52 INFO - TinderboxPrint: I/O write bytes / time
327,942,144 / 186,028 16:07:52 INFO - TinderboxPrint: CPU idle
8,222.7 (86.7%) 16:07:52 INFO - TinderboxPrint: CPU user
1,205.8 (12.7%) 16:07:52 INFO - TinderboxPrint: Swap in / out
0 / 0 16:07:52 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 78299136; Read time: 0; Write time: 48932 16:07:52 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 278528; Write bytes: 249356288; Read time: 268; Write time: 137076 16:07:53 INFO - Running post-run listener: _upload_blobber_files 16:07:53 INFO - Blob upload gear active. 16:07:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:07:53 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:07:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:07:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:07:53 INFO - (blobuploader) - INFO - Open directory for files ... 16:07:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:07:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:07:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:07:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:07:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:07:58 INFO - (blobuploader) - INFO - Done attempting. 16:07:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:07:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:07:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:07:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:07:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:07:58 INFO - (blobuploader) - INFO - Done attempting. 16:07:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:07:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:07:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:07:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:07:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:07:59 INFO - (blobuploader) - INFO - Done attempting. 16:07:59 INFO - (blobuploader) - INFO - Iteration through files over. 16:07:59 INFO - Return code: 0 16:07:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:07:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:07:59 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/238873cdbdde54856944dd60836f6e6546e03477439fc610c5d1de1e6d2295b26292c457aa7416235a9b156c6c2bbe1a5974fae7bdd80e0c17888da5a0f17126", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cb653acd3bfe5ce6baddab3d06d425aa5d2526be0a3603912e3b0cb6a26c50aed35cc88cbcd8363959ac1e9261ed7f4761dcf15d228da7f473ba65897a3ca7d0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0b3066d8e53d2eb072e2676d799094341032322f95db220fe88bd73f9c29cc91fbe54abcab417f7e9190a7f430f6f661894274ba5c099a4c0ce922dd6cf2bd86"} 16:07:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:07:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:07:59 INFO - Contents: 16:07:59 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/238873cdbdde54856944dd60836f6e6546e03477439fc610c5d1de1e6d2295b26292c457aa7416235a9b156c6c2bbe1a5974fae7bdd80e0c17888da5a0f17126", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cb653acd3bfe5ce6baddab3d06d425aa5d2526be0a3603912e3b0cb6a26c50aed35cc88cbcd8363959ac1e9261ed7f4761dcf15d228da7f473ba65897a3ca7d0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0b3066d8e53d2eb072e2676d799094341032322f95db220fe88bd73f9c29cc91fbe54abcab417f7e9190a7f430f6f661894274ba5c099a4c0ce922dd6cf2bd86"} 16:07:59 INFO - Running post-run listener: copy_logs_to_upload_dir 16:07:59 INFO - Copying logs to upload dir... 16:07:59 INFO - mkdir: /builds/slave/test/build/upload/logs 16:07:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1680.179934 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 0 secs) (at 2016-08-22 16:07:59.701740) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-22 16:07:59.705570) ========= 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=03627c79f6bb3d1578c4a402000001ba-1471905597.564307-1900834039 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/238873cdbdde54856944dd60836f6e6546e03477439fc610c5d1de1e6d2295b26292c457aa7416235a9b156c6c2bbe1a5974fae7bdd80e0c17888da5a0f17126", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cb653acd3bfe5ce6baddab3d06d425aa5d2526be0a3603912e3b0cb6a26c50aed35cc88cbcd8363959ac1e9261ed7f4761dcf15d228da7f473ba65897a3ca7d0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0b3066d8e53d2eb072e2676d799094341032322f95db220fe88bd73f9c29cc91fbe54abcab417f7e9190a7f430f6f661894274ba5c099a4c0ce922dd6cf2bd86"} build_url:https://queue.taskcluster.net/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011533 build_url: 'https://queue.taskcluster.net/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/238873cdbdde54856944dd60836f6e6546e03477439fc610c5d1de1e6d2295b26292c457aa7416235a9b156c6c2bbe1a5974fae7bdd80e0c17888da5a0f17126", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cb653acd3bfe5ce6baddab3d06d425aa5d2526be0a3603912e3b0cb6a26c50aed35cc88cbcd8363959ac1e9261ed7f4761dcf15d228da7f473ba65897a3ca7d0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0b3066d8e53d2eb072e2676d799094341032322f95db220fe88bd73f9c29cc91fbe54abcab417f7e9190a7f430f6f661894274ba5c099a4c0ce922dd6cf2bd86"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-22 16:07:59.733385) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 16:07:59.733965) ========= 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=03627c79f6bb3d1578c4a402000001ba-1471905597.564307-1900834039 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004530 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 16:07:59.818377) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-22 16:07:59.818719) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-22 16:07:59.819090) ========= ========= Total master_lag: 0.18 =========