builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-008 starttime: 1473682356.74 results: success (0) buildid: 20160910055116 builduid: fb98bf9a800847589b1767e37e53bef3 revision: 8663d41e015f3c9933a08a65c8349b86f2263354 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-12 05:12:36.739412) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-12 05:12:36.739867) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-12 05:12:36.740172) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-12 05:12:37.145970) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 05:12:37.152614) ========= 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=32c5991c409366f7fec8a718000001d2-1473669411.660316-970082928 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-12 05:12:38-- 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% 70.3M=0s 2016-09-12 05:12:38 (70.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.218156 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 05:12:37.394716) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 05:12:37.398095) ========= 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=32c5991c409366f7fec8a718000001d2-1473669411.660316-970082928 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022639 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 05:12:37.451015) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-12 05:12:37.451326) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 8663d41e015f3c9933a08a65c8349b86f2263354 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 8663d41e015f3c9933a08a65c8349b86f2263354 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=32c5991c409366f7fec8a718000001d2-1473669411.660316-970082928 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-12 05:12:38,541 truncating revision to first 12 chars 2016-09-12 05:12:38,541 Setting DEBUG logging. 2016-09-12 05:12:38,541 attempt 1/10 2016-09-12 05:12:38,541 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/8663d41e015f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-12 05:12:39,254 unpacking tar archive at: mozilla-aurora-8663d41e015f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.063754 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-12 05:12:38.540513) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-12 05:12:38.540974) ========= script_repo_revision: 8663d41e015f3c9933a08a65c8349b86f2263354 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-12 05:12:38.541518) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-12 05:12:38.541999) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-12 05:12:38.553643) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 59 secs) (at 2016-09-12 05:12:38.554225) ========= /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=32c5991c409366f7fec8a718000001d2-1473669411.660316-970082928 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:12:39 INFO - MultiFileLogger online at 20160912 05:12:39 in /builds/slave/test 05:12:39 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 05:12:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:12:39 INFO - {'append_to_log': False, 05:12:39 INFO - 'base_work_dir': '/builds/slave/test', 05:12:39 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 05:12:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:12:39 INFO - 'branch': 'Mozilla-Aurora', 05:12:39 INFO - 'buildbot_json_path': 'buildprops.json', 05:12:39 INFO - 'config_files': ('talos/linux_config.py',), 05:12:39 INFO - 'default_actions': ('clobber', 05:12:39 INFO - 'read-buildbot-config', 05:12:39 INFO - 'download-and-extract', 05:12:39 INFO - 'populate-webroot', 05:12:39 INFO - 'create-virtualenv', 05:12:39 INFO - 'install', 05:12:39 INFO - 'run-tests'), 05:12:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:12:39 INFO - 'download_minidump_stackwalk': True, 05:12:39 INFO - 'download_symbols': 'ondemand', 05:12:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:12:39 INFO - 'tooltool.py': '/tools/tooltool.py', 05:12:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:12:39 INFO - '/tools/misc-python/virtualenv.py')}, 05:12:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:12:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:12:39 INFO - 'installer_path': 'installer.exe', 05:12:39 INFO - 'log_level': 'info', 05:12:39 INFO - 'log_name': 'talos', 05:12:39 INFO - 'log_to_console': True, 05:12:39 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:12:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:12:39 INFO - 'opt_config_files': (), 05:12:39 INFO - 'pip_index': False, 05:12:39 INFO - 'sps_profile': False, 05:12:39 INFO - 'sps_profile_interval': 0, 05:12:39 INFO - 'suite': 'dromaeojs-e10s', 05:12:39 INFO - 'system_bits': '32', 05:12:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:12:39 INFO - 'title': 'talos-linux64-ix-008', 05:12:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:12:39 INFO - 'use_talos_json': True, 05:12:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:12:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:12:39 INFO - 'work_dir': 'build'} 05:12:39 INFO - [mozharness: 2016-09-12 12:12:39.731510Z] Running clobber step. 05:12:39 INFO - Running pre-action listener: _resource_record_pre_action 05:12:39 INFO - Running main action method: clobber 05:12:39 INFO - rmtree: /builds/slave/test/build 05:12:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:12:40 INFO - Running post-action listener: _resource_record_post_action 05:12:40 INFO - [mozharness: 2016-09-12 12:12:40.696170Z] Finished clobber step (success) 05:12:40 INFO - [mozharness: 2016-09-12 12:12:40.696269Z] Running read-buildbot-config step. 05:12:40 INFO - Running pre-action listener: _resource_record_pre_action 05:12:40 INFO - Running main action method: read_buildbot_config 05:12:40 INFO - Using buildbot properties: 05:12:40 INFO - { 05:12:40 INFO - "project": "", 05:12:40 INFO - "product": "firefox", 05:12:40 INFO - "script_repo_revision": "production", 05:12:40 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s", 05:12:40 INFO - "repository": "", 05:12:40 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs-e10s", 05:12:40 INFO - "buildid": "20160910055116", 05:12:40 INFO - "pgo_build": "True", 05:12:40 INFO - "basedir": "/builds/slave/test", 05:12:40 INFO - "buildnumber": 496, 05:12:40 INFO - "slavename": "talos-linux64-ix-008", 05:12:40 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 05:12:40 INFO - "platform": "ubuntu64_hw", 05:12:40 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 05:12:40 INFO - "branch": "mozilla-aurora", 05:12:40 INFO - "repo_path": "releases/mozilla-aurora", 05:12:40 INFO - "revision": "8663d41e015f3c9933a08a65c8349b86f2263354", 05:12:40 INFO - "stage_platform": "linux64-pgo", 05:12:40 INFO - "builduid": "fb98bf9a800847589b1767e37e53bef3", 05:12:40 INFO - "slavebuilddir": "test" 05:12:40 INFO - } 05:12:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 05:12:40 INFO - Running post-action listener: _resource_record_post_action 05:12:40 INFO - [mozharness: 2016-09-12 12:12:40.698665Z] Finished read-buildbot-config step (success) 05:12:40 INFO - [mozharness: 2016-09-12 12:12:40.698752Z] Running download-and-extract step. 05:12:40 INFO - Running pre-action listener: _resource_record_pre_action 05:12:40 INFO - Running main action method: download_and_extract 05:12:40 INFO - mkdir: /builds/slave/test/build/tests 05:12:40 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')]} 05:12:40 INFO - https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 05:12:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 05:12:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 05:12:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:12:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:12:40 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 05:12:40 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 05:12:40 INFO - retry: Failed, sleeping 30 seconds before retrying 05:13:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:13:10 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 05:13:10 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 05:13:10 INFO - retry: Failed, sleeping 60 seconds before retrying 05:14:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:14:10 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 05:14:10 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 05:14:10 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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! 05:14:10 INFO - Caught exception: HTTP Error 404: Not Found 05:14:10 INFO - Caught exception: HTTP Error 404: Not Found 05:14:10 INFO - Caught exception: HTTP Error 404: Not Found 05:14:10 INFO - trying https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 05:14:10 INFO - Downloading https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:14:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:14:11 INFO - Downloaded 1361 bytes. 05:14:11 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 05:14:11 INFO - Using the following test package requirements: 05:14:11 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 05:14:11 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 05:14:11 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 05:14:11 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 05:14:11 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 05:14:11 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 05:14:11 INFO - u'jsshell-linux-x86_64.zip'], 05:14:11 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 05:14:11 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 05:14:11 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 05:14:11 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 05:14:11 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 05:14:11 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 05:14:11 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 05:14:11 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 05:14:11 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 05:14:11 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 05:14:11 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 05:14:11 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:14:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:11 INFO - https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:14:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 05:14:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 05:14:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:14:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:14:11 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 05:14:11 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 05:14:11 INFO - retry: Failed, sleeping 30 seconds before retrying 05:14:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:14:41 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 05:14:41 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 05:14:41 INFO - retry: Failed, sleeping 60 seconds before retrying 05:15:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:15:41 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 05:15:41 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 05:15:41 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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! 05:15:41 INFO - Caught exception: HTTP Error 404: Not Found 05:15:41 INFO - Caught exception: HTTP Error 404: Not Found 05:15:41 INFO - Caught exception: HTTP Error 404: Not Found 05:15:41 INFO - trying https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 05:15:41 INFO - Downloading https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:15:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:15:42 INFO - Downloaded 21770904 bytes. 05:15:42 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'] 05:15:42 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 05:15:43 INFO - Return code: 0 05:15:43 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 05:15:43 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')]} 05:15:43 INFO - https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:15:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 05:15:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 05:15:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:15:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:15:43 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 05:15:43 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 05:15:43 INFO - retry: Failed, sleeping 30 seconds before retrying 05:16:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:16:13 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 05:16:13 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 05:16:13 INFO - retry: Failed, sleeping 60 seconds before retrying 05:17:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:17:13 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 05:17:13 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 05:17:13 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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! 05:17:13 INFO - Caught exception: HTTP Error 404: Not Found 05:17:13 INFO - Caught exception: HTTP Error 404: Not Found 05:17:13 INFO - Caught exception: HTTP Error 404: Not Found 05:17:13 INFO - trying https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 05:17:13 INFO - Downloading https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:17:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:17:14 INFO - Downloaded 21770904 bytes. 05:17:14 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'] 05:17:14 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 05:17:16 INFO - Return code: 0 05:17:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:16 INFO - https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:17:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 05:17:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 05:17:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:17:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:17:16 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 05:17:16 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 05:17:16 INFO - retry: Failed, sleeping 30 seconds before retrying 05:17:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:17:46 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 05:17:46 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 05:17:46 INFO - retry: Failed, sleeping 60 seconds before retrying 05:18:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:18:46 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 05:18:46 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 05:18:46 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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! 05:18:46 INFO - Caught exception: HTTP Error 404: Not Found 05:18:46 INFO - Caught exception: HTTP Error 404: Not Found 05:18:46 INFO - Caught exception: HTTP Error 404: Not Found 05:18:46 INFO - trying https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 05:18:46 INFO - Downloading https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:18:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:18:46 INFO - Downloaded 14413740 bytes. 05:18:46 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'] 05:18:46 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 05:18:47 INFO - Return code: 0 05:18: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')]} 05:18:47 INFO - https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:18:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 05:18:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 05:18:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:18:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:18:47 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 05:18:47 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 05:18:47 INFO - retry: Failed, sleeping 30 seconds before retrying 05:19:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:19:17 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 05:19:17 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 05:19:17 INFO - retry: Failed, sleeping 60 seconds before retrying 05:20:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:20:17 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 05:20:17 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 05:20:17 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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! 05:20:17 INFO - Caught exception: HTTP Error 404: Not Found 05:20:17 INFO - Caught exception: HTTP Error 404: Not Found 05:20:17 INFO - Caught exception: HTTP Error 404: Not Found 05:20:17 INFO - trying https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 05:20:17 INFO - Downloading https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:20:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:20:19 INFO - Downloaded 63895992 bytes. 05:20:19 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 05:20:19 INFO - mkdir: /builds/slave/test/properties 05:20:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:20:19 INFO - Writing to file /builds/slave/test/properties/build_url 05:20:19 INFO - Contents: 05:20:19 INFO - build_url:https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 05:20:20 INFO - Running post-action listener: _resource_record_post_action 05:20:20 INFO - Running post-action listener: set_extra_try_arguments 05:20:20 INFO - [mozharness: 2016-09-12 12:20:20.167317Z] Finished download-and-extract step (success) 05:20:20 INFO - [mozharness: 2016-09-12 12:20:20.167549Z] Running populate-webroot step. 05:20:20 INFO - Running pre-action listener: _resource_record_pre_action 05:20:20 INFO - Running main action method: populate_webroot 05:20:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:20:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:20:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:20:20 INFO - '--tppagecycles', 05:20:20 INFO - '7'], 05:20:20 INFO - 'tests': ['tsvgm']}}, 05:20:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:20:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:20:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:20:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:20:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:20:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:20:20 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:20 INFO - 'talos_options': ['--disable-e10s'], 05:20:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:20:20 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:20:20 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:20 INFO - 'talos_options': ['--disable-e10s'], 05:20:20 INFO - 'tests': ['damp', 'tps']}, 05:20:20 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:20 INFO - 'tests': ['damp', 'tps']}, 05:20:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:20:20 INFO - 'tests': ['dromaeo_dom']}, 05:20:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:20:20 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:20:20 INFO - 'tests': ['basic_compositor_video']}, 05:20:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:20:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:20:20 INFO - 'tests': ['a11yr', 05:20:20 INFO - 'ts_paint', 05:20:20 INFO - 'tpaint', 05:20:20 INFO - 'sessionrestore', 05:20:20 INFO - 'sessionrestore_no_auto_restore', 05:20:20 INFO - 'tabpaint']}, 05:20:20 INFO - 'other-e10s': {'tests': ['a11yr', 05:20:20 INFO - 'ts_paint', 05:20:20 INFO - 'tpaint', 05:20:20 INFO - 'sessionrestore', 05:20:20 INFO - 'sessionrestore_no_auto_restore', 05:20:20 INFO - 'tabpaint']}, 05:20:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:20:20 INFO - 'ts_paint', 05:20:20 INFO - 'tpaint', 05:20:20 INFO - 'sessionrestore', 05:20:20 INFO - 'sessionrestore_no_auto_restore', 05:20:20 INFO - 'tabpaint']}, 05:20:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:20:20 INFO - 'ts_paint', 05:20:20 INFO - 'tpaint', 05:20:20 INFO - 'sessionrestore', 05:20:20 INFO - 'sessionrestore_no_auto_restore', 05:20:20 INFO - 'tabpaint']}, 05:20:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:20:20 INFO - 'tests': ['a11yr', 05:20:20 INFO - 'ts_paint', 05:20:20 INFO - 'tpaint', 05:20:20 INFO - 'sessionrestore', 05:20:20 INFO - 'sessionrestore_no_auto_restore', 05:20:20 INFO - 'tabpaint']}, 05:20:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:20:20 INFO - 'tests': ['a11yr', 05:20:20 INFO - 'ts_paint', 05:20:20 INFO - 'tpaint', 05:20:20 INFO - 'sessionrestore', 05:20:20 INFO - 'sessionrestore_no_auto_restore', 05:20:20 INFO - 'tabpaint']}, 05:20:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:20:20 INFO - 'tests': ['tsvgx', 05:20:20 INFO - 'tsvgr_opacity', 05:20:20 INFO - 'tart', 05:20:20 INFO - 'tscrollx', 05:20:20 INFO - 'cart']}, 05:20:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:20:20 INFO - 'tsvgr_opacity', 05:20:20 INFO - 'tart', 05:20:20 INFO - 'tscrollx', 05:20:20 INFO - 'cart']}, 05:20:20 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:20 INFO - 'talos_options': ['--disable-e10s'], 05:20:20 INFO - 'tests': ['tp5o']}, 05:20:20 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:20 INFO - 'tests': ['tp5o']}, 05:20:20 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:20 INFO - 'talos_options': ['--disable-e10s', 05:20:20 INFO - '--xperf_path', 05:20:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:20:20 INFO - 'tests': ['tp5n']}, 05:20:20 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:20 INFO - 'talos_options': ['--xperf_path', 05:20:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:20:20 INFO - 'tests': ['tp5n']}}, 05:20:20 INFO - 'talos.zip': {'path': '', 05:20:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:20:20 INFO - Running post-action listener: _resource_record_post_action 05:20:20 INFO - [mozharness: 2016-09-12 12:20:20.183157Z] Finished populate-webroot step (success) 05:20:20 INFO - [mozharness: 2016-09-12 12:20:20.183248Z] Running create-virtualenv step. 05:20:20 INFO - Running pre-action listener: _resource_record_pre_action 05:20:20 INFO - Running main action method: create_virtualenv 05:20:20 INFO - Creating virtualenv /builds/slave/test/build/venv 05:20:20 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 05:20:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:20:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:20:20 INFO - Using real prefix '/usr' 05:20:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:20:20 INFO - Installing distribute.............................................................................................................................................................................................done. 05:20:22 INFO - Installing pip.................done. 05:20:22 INFO - Return code: 0 05:20:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:20:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:22 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 0x19c1030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b248a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bf0400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c7f570>, '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 0x1c80470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c80900>, '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': '32c5991c409366f7fec8a718000001d2-1473669411.660316-970082928', '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 05:20:22 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 05:20:22 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 05:20:22 INFO - Using env: {'DISPLAY': ':0', 05:20:22 INFO - 'HOME': '/home/cltbld', 05:20:22 INFO - 'LANG': 'en_US.UTF-8', 05:20:22 INFO - 'LANGUAGE': 'en_US:en', 05:20:22 INFO - 'LOGNAME': 'cltbld', 05:20:22 INFO - 'MAIL': '/var/mail/cltbld', 05:20:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:22 INFO - 'MOZ_NO_REMOTE': '1', 05:20:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:22 INFO - 'NO_EM_RESTART': '1', 05:20:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:22 INFO - 'PWD': '/builds/slave/test', 05:20:22 INFO - 'SHELL': '/bin/bash', 05:20:22 INFO - 'SHLVL': '1', 05:20:22 INFO - 'TERM': 'linux', 05:20:22 INFO - 'TMOUT': '86400', 05:20:22 INFO - 'USER': 'cltbld', 05:20:22 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1473669411.660316-970082928', 05:20:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:22 INFO - '_': '/tools/buildbot/bin/python'} 05:20:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:22 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 05:20:22 INFO - Cleaning up... 05:20:22 INFO - Return code: 0 05:20:22 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:20:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:22 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 0x19c1030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b248a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bf0400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c7f570>, '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 0x1c80470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c80900>, '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': '32c5991c409366f7fec8a718000001d2-1473669411.660316-970082928', '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 05:20:22 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 05:20:22 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 05:20:22 INFO - Using env: {'DISPLAY': ':0', 05:20:22 INFO - 'HOME': '/home/cltbld', 05:20:22 INFO - 'LANG': 'en_US.UTF-8', 05:20:22 INFO - 'LANGUAGE': 'en_US:en', 05:20:22 INFO - 'LOGNAME': 'cltbld', 05:20:22 INFO - 'MAIL': '/var/mail/cltbld', 05:20:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:22 INFO - 'MOZ_NO_REMOTE': '1', 05:20:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:22 INFO - 'NO_EM_RESTART': '1', 05:20:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:22 INFO - 'PWD': '/builds/slave/test', 05:20:22 INFO - 'SHELL': '/bin/bash', 05:20:22 INFO - 'SHLVL': '1', 05:20:22 INFO - 'TERM': 'linux', 05:20:22 INFO - 'TMOUT': '86400', 05:20:22 INFO - 'USER': 'cltbld', 05:20:22 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1473669411.660316-970082928', 05:20:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:22 INFO - '_': '/tools/buildbot/bin/python'} 05:20:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:23 INFO - Downloading/unpacking psutil>=3.1.1 05:20:23 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 05:20:23 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 05:20:23 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 05:20:23 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 05:20:23 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 05:20:23 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 05:20:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:20:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:20:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:20:25 INFO - Installing collected packages: psutil 05:20:25 INFO - Running setup.py install for psutil 05:20:25 INFO - building 'psutil._psutil_linux' extension 05:20:25 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 05:20:25 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 05:20:25 INFO - building 'psutil._psutil_posix' extension 05:20:25 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 05:20:25 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 05:20:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:20:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:20:25 INFO - Successfully installed psutil 05:20:25 INFO - Cleaning up... 05:20:25 INFO - Return code: 0 05:20:25 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:20:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:25 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 0x19c1030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b248a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bf0400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c7f570>, '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 0x1c80470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c80900>, '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': '32c5991c409366f7fec8a718000001d2-1473669411.660316-970082928', '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 05:20:25 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 05:20:25 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 05:20:25 INFO - Using env: {'DISPLAY': ':0', 05:20:25 INFO - 'HOME': '/home/cltbld', 05:20:25 INFO - 'LANG': 'en_US.UTF-8', 05:20:25 INFO - 'LANGUAGE': 'en_US:en', 05:20:25 INFO - 'LOGNAME': 'cltbld', 05:20:25 INFO - 'MAIL': '/var/mail/cltbld', 05:20:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:25 INFO - 'MOZ_NO_REMOTE': '1', 05:20:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:25 INFO - 'NO_EM_RESTART': '1', 05:20:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:25 INFO - 'PWD': '/builds/slave/test', 05:20:25 INFO - 'SHELL': '/bin/bash', 05:20:25 INFO - 'SHLVL': '1', 05:20:25 INFO - 'TERM': 'linux', 05:20:25 INFO - 'TMOUT': '86400', 05:20:25 INFO - 'USER': 'cltbld', 05:20:25 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1473669411.660316-970082928', 05:20:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:25 INFO - '_': '/tools/buildbot/bin/python'} 05:20:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:25 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:20:25 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 05:20:25 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 05:20:25 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 05:20:25 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 05:20:25 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 05:20:25 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 05:20:27 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:20:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:20:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:20:27 INFO - Installing collected packages: mozsystemmonitor 05:20:27 INFO - Running setup.py install for mozsystemmonitor 05:20:27 INFO - Successfully installed mozsystemmonitor 05:20:27 INFO - Cleaning up... 05:20:28 INFO - Return code: 0 05:20:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:20:28 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')]} 05:20:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:28 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')]} 05:20:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:28 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 0x19c1030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b248a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bf0400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c7f570>, '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 0x1c80470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c80900>, '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': '32c5991c409366f7fec8a718000001d2-1473669411.660316-970082928', '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 05:20:28 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 05:20:28 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 05:20:28 INFO - Using env: {'DISPLAY': ':0', 05:20:28 INFO - 'HOME': '/home/cltbld', 05:20:28 INFO - 'LANG': 'en_US.UTF-8', 05:20:28 INFO - 'LANGUAGE': 'en_US:en', 05:20:28 INFO - 'LOGNAME': 'cltbld', 05:20:28 INFO - 'MAIL': '/var/mail/cltbld', 05:20:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:28 INFO - 'MOZ_NO_REMOTE': '1', 05:20:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:28 INFO - 'NO_EM_RESTART': '1', 05:20:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:28 INFO - 'PWD': '/builds/slave/test', 05:20:28 INFO - 'SHELL': '/bin/bash', 05:20:28 INFO - 'SHLVL': '1', 05:20:28 INFO - 'TERM': 'linux', 05:20:28 INFO - 'TMOUT': '86400', 05:20:28 INFO - 'USER': 'cltbld', 05:20:28 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1473669411.660316-970082928', 05:20:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:28 INFO - '_': '/tools/buildbot/bin/python'} 05:20:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:28 INFO - Downloading/unpacking jsonschema==2.5.1 05:20:28 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 05:20:28 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 05:20:28 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 05:20:28 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 05:20:28 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 05:20:28 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 05:20:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:20:30 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:20:30 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 05:20:30 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 05:20:30 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 05:20:30 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 05:20:30 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 05:20:30 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 05:20:30 INFO - Downloading functools32-3.2.3-2.tar.gz 05:20:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:20:30 INFO - warning: no files found matching '*.txt' 05:20:30 INFO - no previously-included directories found matching 'build' 05:20:30 INFO - no previously-included directories found matching 'dist' 05:20:30 INFO - no previously-included directories found matching '.git*' 05:20:30 INFO - Installing collected packages: jsonschema, functools32 05:20:30 INFO - Running setup.py install for functools32 05:20:30 INFO - warning: no files found matching '*.txt' 05:20:30 INFO - no previously-included directories found matching 'build' 05:20:30 INFO - no previously-included directories found matching 'dist' 05:20:30 INFO - no previously-included directories found matching '.git*' 05:20:30 INFO - Successfully installed jsonschema functools32 05:20:30 INFO - Cleaning up... 05:20:30 INFO - Return code: 0 05:20:30 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:20: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')]} 05:20:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20: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')]} 05:20:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:30 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 0x19c1030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b248a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bf0400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c7f570>, '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 0x1c80470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c80900>, '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': '32c5991c409366f7fec8a718000001d2-1473669411.660316-970082928', '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 05:20:30 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 05:20:30 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 05:20:30 INFO - Using env: {'DISPLAY': ':0', 05:20:30 INFO - 'HOME': '/home/cltbld', 05:20:30 INFO - 'LANG': 'en_US.UTF-8', 05:20:30 INFO - 'LANGUAGE': 'en_US:en', 05:20:30 INFO - 'LOGNAME': 'cltbld', 05:20:30 INFO - 'MAIL': '/var/mail/cltbld', 05:20:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:30 INFO - 'MOZ_NO_REMOTE': '1', 05:20:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:30 INFO - 'NO_EM_RESTART': '1', 05:20:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:30 INFO - 'PWD': '/builds/slave/test', 05:20:30 INFO - 'SHELL': '/bin/bash', 05:20:30 INFO - 'SHLVL': '1', 05:20:30 INFO - 'TERM': 'linux', 05:20:30 INFO - 'TMOUT': '86400', 05:20:30 INFO - 'USER': 'cltbld', 05:20:30 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1473669411.660316-970082928', 05:20:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:30 INFO - '_': '/tools/buildbot/bin/python'} 05:20:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:30 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:20:30 INFO - Cleaning up... 05:20:30 INFO - Return code: 0 05:20:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:20: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')]} 05:20:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20: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')]} 05:20:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:30 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 0x19c1030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b248a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bf0400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c7f570>, '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 0x1c80470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c80900>, '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': '32c5991c409366f7fec8a718000001d2-1473669411.660316-970082928', '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 05:20:30 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 05:20:30 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 05:20:30 INFO - Using env: {'DISPLAY': ':0', 05:20:30 INFO - 'HOME': '/home/cltbld', 05:20:30 INFO - 'LANG': 'en_US.UTF-8', 05:20:30 INFO - 'LANGUAGE': 'en_US:en', 05:20:30 INFO - 'LOGNAME': 'cltbld', 05:20:30 INFO - 'MAIL': '/var/mail/cltbld', 05:20:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:30 INFO - 'MOZ_NO_REMOTE': '1', 05:20:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:30 INFO - 'NO_EM_RESTART': '1', 05:20:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:30 INFO - 'PWD': '/builds/slave/test', 05:20:30 INFO - 'SHELL': '/bin/bash', 05:20:30 INFO - 'SHLVL': '1', 05:20:30 INFO - 'TERM': 'linux', 05:20:30 INFO - 'TMOUT': '86400', 05:20:30 INFO - 'USER': 'cltbld', 05:20:30 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1473669411.660316-970082928', 05:20:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:30 INFO - '_': '/tools/buildbot/bin/python'} 05:20:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:30 INFO - Downloading/unpacking blobuploader==1.2.4 05:20:30 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 05:20:30 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 05:20:30 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 05:20:30 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 05:20:30 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 05:20:30 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 05:20:32 INFO - Downloading blobuploader-1.2.4.tar.gz 05:20:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:20:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:20:32 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 05:20:32 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 05:20:32 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 05:20:32 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 05:20:32 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 05:20:32 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 05:20:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:20:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:20:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:33 INFO - Downloading docopt-0.6.1.tar.gz 05:20:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:20:33 INFO - Installing collected packages: blobuploader, requests, docopt 05:20:33 INFO - Running setup.py install for blobuploader 05:20:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:20:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:20:33 INFO - Running setup.py install for requests 05:20:34 INFO - Running setup.py install for docopt 05:20:34 INFO - Successfully installed blobuploader requests docopt 05:20:34 INFO - Cleaning up... 05:20:34 INFO - Return code: 0 05:20:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:20:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:34 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 0x19c1030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b248a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bf0400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c7f570>, '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 0x1c80470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c80900>, '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': '32c5991c409366f7fec8a718000001d2-1473669411.660316-970082928', '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 05:20:34 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 05:20:34 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 05:20:34 INFO - Using env: {'DISPLAY': ':0', 05:20:34 INFO - 'HOME': '/home/cltbld', 05:20:34 INFO - 'LANG': 'en_US.UTF-8', 05:20:34 INFO - 'LANGUAGE': 'en_US:en', 05:20:34 INFO - 'LOGNAME': 'cltbld', 05:20:34 INFO - 'MAIL': '/var/mail/cltbld', 05:20:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:34 INFO - 'MOZ_NO_REMOTE': '1', 05:20:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:34 INFO - 'NO_EM_RESTART': '1', 05:20:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:34 INFO - 'PWD': '/builds/slave/test', 05:20:34 INFO - 'SHELL': '/bin/bash', 05:20:34 INFO - 'SHLVL': '1', 05:20:34 INFO - 'TERM': 'linux', 05:20:34 INFO - 'TMOUT': '86400', 05:20:34 INFO - 'USER': 'cltbld', 05:20:34 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1473669411.660316-970082928', 05:20:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:34 INFO - '_': '/tools/buildbot/bin/python'} 05:20:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:20:34 INFO - Running setup.py (path:/tmp/pip-4ImSjn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:20:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:20:34 INFO - Running setup.py (path:/tmp/pip-M9Wq4K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:20:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:20:34 INFO - Running setup.py (path:/tmp/pip-yQhb0O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:20:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:20:34 INFO - Running setup.py (path:/tmp/pip-QTn7Dl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:20:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:20:34 INFO - Running setup.py (path:/tmp/pip-XPOqWu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:20:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:20:34 INFO - Running setup.py (path:/tmp/pip-0Gxen9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:20:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:20:34 INFO - Running setup.py (path:/tmp/pip-bH0Qwo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:20:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:20:34 INFO - Running setup.py (path:/tmp/pip-VPiRsc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:20:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:20:35 INFO - Running setup.py (path:/tmp/pip-NBjppn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:20:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:20:35 INFO - Running setup.py (path:/tmp/pip-xfl7uW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:20:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:20:35 INFO - Running setup.py (path:/tmp/pip-MiK_2s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:20:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:20:35 INFO - Running setup.py (path:/tmp/pip-JhyK9V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:20:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:20:35 INFO - Running setup.py (path:/tmp/pip-Gkz8Lv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:20:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:20:35 INFO - Running setup.py (path:/tmp/pip-SNvT6R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:20:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:20:35 INFO - Running setup.py (path:/tmp/pip-IQru50-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:20:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:20:35 INFO - Running setup.py (path:/tmp/pip-rkWJTL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:20:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:20:35 INFO - Running setup.py (path:/tmp/pip-upDBGn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:20:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:20:35 INFO - Running setup.py install for manifestparser 05:20:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:20:35 INFO - Running setup.py install for mozcrash 05:20:35 INFO - Running setup.py install for mozdebug 05:20:35 INFO - Running setup.py install for mozdevice 05:20:36 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:20:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:20:36 INFO - Running setup.py install for mozfile 05:20:36 INFO - Running setup.py install for mozhttpd 05:20:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:20:36 INFO - Running setup.py install for mozinfo 05:20:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:20:36 INFO - Running setup.py install for mozInstall 05:20:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:20:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:20:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:20:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:20:36 INFO - Running setup.py install for mozleak 05:20:36 INFO - Running setup.py install for mozlog 05:20:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:20:36 INFO - Running setup.py install for moznetwork 05:20:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:20:36 INFO - Running setup.py install for mozprocess 05:20:36 INFO - Running setup.py install for mozprofile 05:20:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:20:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:20:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:20:37 INFO - Running setup.py install for mozrunner 05:20:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:20:37 INFO - Running setup.py install for mozscreenshot 05:20:37 INFO - Running setup.py install for moztest 05:20:37 INFO - Running setup.py install for mozversion 05:20:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:20:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:20:37 INFO - Cleaning up... 05:20:37 INFO - Return code: 0 05:20:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:20:37 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')]} 05:20:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:37 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')]} 05:20:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:37 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 0x19c1030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b248a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bf0400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c7f570>, '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 0x1c80470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c80900>, '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': '32c5991c409366f7fec8a718000001d2-1473669411.660316-970082928', '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 05:20:37 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 05:20:37 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 05:20:37 INFO - Using env: {'DISPLAY': ':0', 05:20:37 INFO - 'HOME': '/home/cltbld', 05:20:37 INFO - 'LANG': 'en_US.UTF-8', 05:20:37 INFO - 'LANGUAGE': 'en_US:en', 05:20:37 INFO - 'LOGNAME': 'cltbld', 05:20:37 INFO - 'MAIL': '/var/mail/cltbld', 05:20:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:37 INFO - 'MOZ_NO_REMOTE': '1', 05:20:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:37 INFO - 'NO_EM_RESTART': '1', 05:20:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:37 INFO - 'PWD': '/builds/slave/test', 05:20:37 INFO - 'SHELL': '/bin/bash', 05:20:37 INFO - 'SHLVL': '1', 05:20:37 INFO - 'TERM': 'linux', 05:20:37 INFO - 'TMOUT': '86400', 05:20:37 INFO - 'USER': 'cltbld', 05:20:37 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1473669411.660316-970082928', 05:20:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:37 INFO - '_': '/tools/buildbot/bin/python'} 05:20:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:20:37 INFO - Running setup.py (path:/tmp/pip-rTMoKs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:20:37 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)) 05:20:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:20:37 INFO - Running setup.py (path:/tmp/pip-4dKitm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:20:37 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)) 05:20:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:20:37 INFO - Running setup.py (path:/tmp/pip-T6kjqV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:20:37 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)) 05:20:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:20:37 INFO - Running setup.py (path:/tmp/pip-wCZ_Db-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:20:38 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)) 05:20:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:20:38 INFO - Running setup.py (path:/tmp/pip-nRuOK2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:20:38 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)) 05:20:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:20:38 INFO - Running setup.py (path:/tmp/pip-Ma5gAO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:20:38 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)) 05:20:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:20:38 INFO - Running setup.py (path:/tmp/pip-9bsJiz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:20:38 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)) 05:20:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:20:38 INFO - Running setup.py (path:/tmp/pip-XbXRTI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:20:38 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)) 05:20:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:20:38 INFO - Running setup.py (path:/tmp/pip-3O74pN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:20:38 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)) 05:20:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:20:38 INFO - Running setup.py (path:/tmp/pip-Oan2m2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:20:38 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)) 05:20:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:20:38 INFO - Running setup.py (path:/tmp/pip-KJWIlO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:20:38 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)) 05:20:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:20:38 INFO - Running setup.py (path:/tmp/pip-JlUZMJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:20:38 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)) 05:20:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:20:38 INFO - Running setup.py (path:/tmp/pip-flvgWS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:20:38 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)) 05:20:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:20:38 INFO - Running setup.py (path:/tmp/pip-fYlkvI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:20:38 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)) 05:20:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:20:38 INFO - Running setup.py (path:/tmp/pip-wJEKEA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:20:38 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)) 05:20:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:20:38 INFO - Running setup.py (path:/tmp/pip-PKXKQy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:20:38 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)) 05:20:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:20:38 INFO - Running setup.py (path:/tmp/pip-HY6DgO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:20:38 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)) 05:20:38 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)) 05:20:38 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)) 05:20:38 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)) 05:20:38 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)) 05:20:38 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)) 05:20:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:20:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:40 INFO - Downloading blessings-1.6.tar.gz 05:20:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:20:40 INFO - Installing collected packages: blessings 05:20:40 INFO - Running setup.py install for blessings 05:20:41 INFO - Successfully installed blessings 05:20:41 INFO - Cleaning up... 05:20:41 INFO - Return code: 0 05:20:41 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:20:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:20:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:20:41 INFO - Reading from file tmpfile_stdout 05:20:41 INFO - Current package versions: 05:20:41 INFO - argparse == 1.2.1 05:20:41 INFO - blessings == 1.6 05:20:41 INFO - blobuploader == 1.2.4 05:20:41 INFO - docopt == 0.6.1 05:20:41 INFO - functools32 == 3.2.3-2 05:20:41 INFO - jsonschema == 2.5.1 05:20:41 INFO - manifestparser == 1.1 05:20:41 INFO - mozInstall == 1.12 05:20:41 INFO - mozcrash == 0.17 05:20:41 INFO - mozdebug == 0.1 05:20:41 INFO - mozdevice == 0.48 05:20:41 INFO - mozfile == 1.2 05:20:41 INFO - mozhttpd == 0.7 05:20:41 INFO - mozinfo == 0.9 05:20:41 INFO - mozleak == 0.1 05:20:41 INFO - mozlog == 3.3 05:20:41 INFO - moznetwork == 0.27 05:20:41 INFO - mozprocess == 0.23 05:20:41 INFO - mozprofile == 0.28 05:20:41 INFO - mozrunner == 6.12 05:20:41 INFO - mozscreenshot == 0.1 05:20:41 INFO - mozsystemmonitor == 0.3 05:20:41 INFO - moztest == 0.7 05:20:41 INFO - mozversion == 1.4 05:20:41 INFO - psutil == 3.1.1 05:20:41 INFO - requests == 1.2.3 05:20:41 INFO - wsgiref == 0.1.2 05:20:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:20:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:41 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 0x19c1030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b248a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bf0400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c7f570>, '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 0x1c80470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c80900>, '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': '32c5991c409366f7fec8a718000001d2-1473669411.660316-970082928', '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 05:20:41 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 05:20:41 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 05:20:41 INFO - Using env: {'DISPLAY': ':0', 05:20:41 INFO - 'HOME': '/home/cltbld', 05:20:41 INFO - 'LANG': 'en_US.UTF-8', 05:20:41 INFO - 'LANGUAGE': 'en_US:en', 05:20:41 INFO - 'LOGNAME': 'cltbld', 05:20:41 INFO - 'MAIL': '/var/mail/cltbld', 05:20:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:41 INFO - 'MOZ_NO_REMOTE': '1', 05:20:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:41 INFO - 'NO_EM_RESTART': '1', 05:20:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:41 INFO - 'PWD': '/builds/slave/test', 05:20:41 INFO - 'SHELL': '/bin/bash', 05:20:41 INFO - 'SHLVL': '1', 05:20:41 INFO - 'TERM': 'linux', 05:20:41 INFO - 'TMOUT': '86400', 05:20:41 INFO - 'USER': 'cltbld', 05:20:41 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1473669411.660316-970082928', 05:20:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:41 INFO - '_': '/tools/buildbot/bin/python'} 05:20:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:41 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)) 05:20:41 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)) 05:20:41 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)) 05:20:41 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)) 05:20:41 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)) 05:20:41 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)) 05:20:41 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)) 05:20:41 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)) 05:20:41 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)) 05:20:41 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)) 05:20:41 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)) 05:20:41 INFO - Cleaning up... 05:20:41 INFO - Return code: 0 05:20:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:20:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19c1030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b248a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bf0400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c7f570>, '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 0x1c80470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c80900>, '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': '32c5991c409366f7fec8a718000001d2-1473669411.660316-970082928', '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 05:20:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:20:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:20:41 INFO - Using env: {'DISPLAY': ':0', 05:20:41 INFO - 'HOME': '/home/cltbld', 05:20:41 INFO - 'LANG': 'en_US.UTF-8', 05:20:41 INFO - 'LANGUAGE': 'en_US:en', 05:20:41 INFO - 'LOGNAME': 'cltbld', 05:20:41 INFO - 'MAIL': '/var/mail/cltbld', 05:20:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:41 INFO - 'MOZ_NO_REMOTE': '1', 05:20:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:41 INFO - 'NO_EM_RESTART': '1', 05:20:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:41 INFO - 'PWD': '/builds/slave/test', 05:20:41 INFO - 'SHELL': '/bin/bash', 05:20:41 INFO - 'SHLVL': '1', 05:20:41 INFO - 'TERM': 'linux', 05:20:41 INFO - 'TMOUT': '86400', 05:20:41 INFO - 'USER': 'cltbld', 05:20:41 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1473669411.660316-970082928', 05:20:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:41 INFO - '_': '/tools/buildbot/bin/python'} 05:20:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:41 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:20:41 INFO - Cleaning up... 05:20:41 INFO - Return code: 0 05:20:41 INFO - Running post-action listener: _resource_record_post_action 05:20:41 INFO - Running post-action listener: _start_resource_monitoring 05:20:41 INFO - Starting resource monitoring. 05:20:41 INFO - [mozharness: 2016-09-12 12:20:41.822127Z] Finished create-virtualenv step (success) 05:20:41 INFO - [mozharness: 2016-09-12 12:20:41.822556Z] Running install step. 05:20:41 INFO - Running pre-action listener: _resource_record_pre_action 05:20:41 INFO - Running main action method: install 05:20:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:20:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:20:42 INFO - Reading from file tmpfile_stdout 05:20:42 INFO - Detecting whether we're running mozinstall >=1.0... 05:20:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:20:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:20:42 INFO - Reading from file tmpfile_stdout 05:20:42 INFO - Output received: 05:20:42 INFO - Usage: mozinstall [options] installer 05:20:42 INFO - Options: 05:20:42 INFO - -h, --help show this help message and exit 05:20:42 INFO - -d DEST, --destination=DEST 05:20:42 INFO - Directory to install application into. [default: 05:20:42 INFO - "/builds/slave/test"] 05:20:42 INFO - --app=APP Application being installed. [default: firefox] 05:20:42 INFO - mkdir: /builds/slave/test/build/application 05:20:42 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'] 05:20:42 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 05:20:58 INFO - Reading from file tmpfile_stdout 05:20:58 INFO - Output received: 05:20:58 INFO - /builds/slave/test/build/application/firefox/firefox 05:20:58 INFO - Running post-action listener: _resource_record_post_action 05:20:58 INFO - [mozharness: 2016-09-12 12:20:58.370167Z] Finished install step (success) 05:20:58 INFO - [mozharness: 2016-09-12 12:20:58.370416Z] Running run-tests step. 05:20:58 INFO - Running pre-action listener: _resource_record_pre_action 05:20:58 INFO - Running main action method: run_tests 05:20:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:20:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:20:58 INFO - Python 2.7.3 05:20:58 INFO - Return code: 0 05:20:58 INFO - grabbing minidump binary from tooltool 05:20:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:58 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 0x1c7f570>, '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 0x1c80470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c80900>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:20:58 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 05:20:58 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 05:20:58 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 05:20:58 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:20:58 INFO - Return code: 0 05:20:58 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:20:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:20:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:20:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:20:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:20:58 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/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-008', '--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 05:20:58 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/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-008 --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 05:20:58 INFO - Using env: {'DISPLAY': ':0', 05:20:58 INFO - 'HOME': '/home/cltbld', 05:20:58 INFO - 'LANG': 'en_US.UTF-8', 05:20:58 INFO - 'LANGUAGE': 'en_US:en', 05:20:58 INFO - 'LOGNAME': 'cltbld', 05:20:58 INFO - 'MAIL': '/var/mail/cltbld', 05:20:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:20:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:20:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:58 INFO - 'MOZ_NO_REMOTE': '1', 05:20:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:20:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:58 INFO - 'NO_EM_RESTART': '1', 05:20:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:58 INFO - 'PWD': '/builds/slave/test', 05:20:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:20:58 INFO - 'SHELL': '/bin/bash', 05:20:58 INFO - 'SHLVL': '1', 05:20:58 INFO - 'TERM': 'linux', 05:20:58 INFO - 'TMOUT': '86400', 05:20:58 INFO - 'USER': 'cltbld', 05:20:58 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1473669411.660316-970082928', 05:20:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:58 INFO - '_': '/tools/buildbot/bin/python'} 05:20:58 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/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-008', '--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 05:20:58 INFO - mozversion application_buildid: 20160910055116 05:20:58 INFO - mozversion application_changeset: 8663d41e015f3c9933a08a65c8349b86f2263354 05:20:58 INFO - mozversion application_display_name: FirefoxDeveloperEdition 05:20:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:20:58 INFO - mozversion application_name: Firefox 05:20:58 INFO - mozversion application_remotingname: firefox-dev 05:20:58 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:20:58 INFO - mozversion application_vendor: Mozilla 05:20:58 INFO - mozversion application_version: 50.0a2 05:20:58 INFO - mozversion platform_buildid: 20160910055116 05:20:58 INFO - mozversion platform_changeset: 8663d41e015f3c9933a08a65c8349b86f2263354 05:20:58 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:20:58 INFO - mozversion platform_version: 50.0a2 05:20:58 INFO - using testdate: 1473682858 05:20:58 INFO - actual date: 1473682858 05:20:58 INFO - starting webserver on 'localhost:56860' 05:20:58 INFO - SUITE-START | Running 2 tests 05:20:58 INFO - TEST-START | dromaeo_css 05:20:58 INFO - operating with platform_type : linux_ 05:20:58 INFO - Initialising browser for dromaeo_css test... 05:20:58 INFO - TEST-INFO | started process 5186 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpflVP9S/profile http://localhost:56860/getInfo.html) 05:21:02 INFO - PROCESS | 5186 | __metrics Screen width/height:1600/1200 05:21:02 INFO - PROCESS | 5186 | colorDepth:24 05:21:02 INFO - PROCESS | 5186 | Browser inner width/height: 1024/697 05:21:02 INFO - PROCESS | 5186 | __metrics 05:21:03 ERROR - PROCESS | 5186 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 05:21:04 INFO - TEST-INFO | 5186: exit 0 05:21:04 INFO - Browser initialized. 05:21:04 INFO - Running cycle 1/1 for dromaeo_css test... 05:21:04 INFO - TEST-INFO | started process 5313 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpflVP9S/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:21:16 INFO - PROCESS | 5313 | RSS: Main: 153776128 05:21:16 INFO - PROCESS | 5313 | 05:22:05 INFO - PROCESS | 5313 | 1473682925948 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] 05:24:14 INFO - PROCESS | 5313 | Cycle 1(1): loaded http://localhost:56860/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56860/tests/dromaeo/cssquery-ext.html) 05:24:15 INFO - PROCESS | 5313 | RSS: Main: 160133120 05:24:15 INFO - PROCESS | 5313 | 05:27:17 INFO - PROCESS | 5313 | Cycle 1(1): loaded http://localhost:56860/tests/dromaeo/cssquery-ext.html (next: http://localhost:56860/tests/dromaeo/cssquery-jquery.html) 05:27:18 INFO - PROCESS | 5313 | RSS: Main: 156045312 05:27:18 INFO - PROCESS | 5313 | 05:30:20 INFO - PROCESS | 5313 | Cycle 1(1): loaded http://localhost:56860/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56860/tests/dromaeo/cssquery-mootools.html) 05:30:20 INFO - PROCESS | 5313 | RSS: Main: 156545024 05:30:20 INFO - PROCESS | 5313 | 05:33:22 INFO - PROCESS | 5313 | Cycle 1(1): loaded http://localhost:56860/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56860/tests/dromaeo/cssquery-prototype.html) 05:33:23 INFO - PROCESS | 5313 | RSS: Main: 155127808 05:33:23 INFO - PROCESS | 5313 | 05:36:25 INFO - PROCESS | 5313 | Cycle 1(1): loaded http://localhost:56860/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56860/tests/dromaeo/cssquery-yui.html) 05:36:26 INFO - PROCESS | 5313 | RSS: Main: 157487104 05:36:26 INFO - PROCESS | 5313 | 05:39:28 INFO - PROCESS | 5313 | Cycle 1(1): loaded http://localhost:56860/tests/dromaeo/cssquery-yui.html (next: http://localhost:56860/tests/dromaeo/cssquery-dojo.html) 05:39:28 INFO - PROCESS | 5313 | RSS: Main: 157413376 05:39:28 INFO - PROCESS | 5313 | 05:39:28 INFO - PROCESS | 5313 | __start_tp_report 05:39:28 INFO - PROCESS | 5313 | _x_x_mozilla_page_load 05:39:28 INFO - PROCESS | 5313 | _x_x_mozilla_page_load_details 05:39:28 INFO - PROCESS | 5313 | |i|pagename|runs| 05:39:28 INFO - PROCESS | 5313 | |0;dojo.html;3292.3744488547172;3315.4529502632067;3318.269980604266;3322.6178989416217;3332.8500217490196;5693.28833895763;5754.510866576346;5758.3665796762425;5778.855528611782;5803.912941305878;5627.493525582697;5662.17898404731;5685.118806584976;5705;5719.857003574913;5153.4846515348445;5166.9224961625605;5199.610029247802;5261.263423120766;5268.973655131738;5136.512031357015;5138.511841375082;5194.480551944795;5251.737413129347;5305.734713264341;5187.559057480119;5203.895922081556;5211.635185537014;5212.817551385702;5265.6840589564745;5259.763955470461;5263.105272103751;5266.631335806495;5274.9472505275035;5306.787728490855;5088.211327244283;5116;5121.769520371592;5149.716765577892;5158.071547121516;5664.716764161775;5706.429357064302;5711.171880077407;5718.428157184248;5747.195392645033;6105.633661980251;6126.724297406583;6156.815295541141;6159.568830181889;6159.876802463926;6001.639901605918;6096.4817990470865;6126.050462178369;6217.937820621826;6226.097215903714;5776.913346299809;5793.217915581385;5794.565407594448;5816.069428891399;5822.679752613604;6326.525510586633;6331.778387756406;6333.493320534346;6333.651649159248;6339.587926784744;5955.136505206808;5961.552883533753;5970.582059255898;6055.2430946131735;6073.997790364629;6122.672617327906;6123.908141377883;6128.169829013797;6130.860895427079;6134.00801433197;6047.485963693048;6105;6107.969460152671;6108.480779133734;6145.846353841101;5910.408959104141;6003.21958145447;6050.576459647782;6116.908246376308;6152.815415537541;4680.602148817388;4681.110588988081;4683.274092515666;4702.858914232578;4709.058188362341;4183.226103170923;4183.53981062089;4190.958090419118;4193.916121677549;4214.262504061839;5733.971330143406;5862.70686465675;5922.111683247461;5928.762849485972;5928.911066334009;6218.160548325918;6234.532410069263;6248.250209974832;6260.937390626126;6270.090836828635;5967.045272756338;5994.310654274815;6006.249218847644;6025.15647809307;6035.36628653985;5256.579473642099;5265.157574788015;5278.234655974862;5380.435054319319;5380.973095134499;6351.333110023474;6385.393387628168;6392.105105285264;6392.7123279452535;6421.518386120966;6095.481884039907;6151;6167.259928808572;6184.783532576338;6202.4417802398;6238.968805155945;6375.139356186855;6398.648074355955;6409.198850143732;6439.549231553746;6181.721822517908;6198.81403557894;6200.875982480235;6201.3178550358525;6213.285472170758;6324.620522768649;6332.52506062038;6335.366463353628;6343.619382837044;6346.4605508532295;5893.292804863272;5992.161097446448;6025.969870150621;6066;6108.144859719554;6080.695965201634;6092.512598991982;6093.9085913712215;6098;6129.724162412613;6006.09908513709;6013.218281623361;6042.5770196087615;6054.697265136813;6075.058365953284;5888;5917.142014407973;5941.168236446987;5962.791302304398;6074.1799857020505;5863.061910094364;5863.120531920246;5875.383084776037;5881.235439392852;5888.057910734262 05:39:28 INFO - PROCESS | 5313 | |1;ext.html;583.6614763437207;631.9020551814468;665.7536711416776;670.8557660103077;675.3955210086975;41579.58420415799;46591.53408465914;46659.53340466594;46668;46755;47029.76485117578;47752;47868.28197577039;47943.520564794344;48055.75972120143;9145.496997665143;9171.266298696088;9273.768155796091;9274.536273186348;9282.118198771106;9018.954905225464;9146.817063658722;9148;9150.633974641007;9178.586963586655;9240.353175277733;9283.25733941283;9290.628374864997;9295.628174872996;9295.907040929571;9096.226820720247;9183.816323673522;9197.816043679122;9230.123138301919;9246.583903724348;9043.59303831329;9101.772455688595;9113.635454581809;9120.954395228047;9169.083091690844;35785.28429431397;36032.45951310706;36034.279314413565;36099.63900360989;36118.458223126676;3646.5259516262718;4215;4525.586008562738;4608.354830323758;4622.976885115553;35692.82153589215;35967.4604880927;36500.45249321262;36507.08732281688;37431.625683743085;4649.767511624405;4685.929711054337;4709.340692303081;4718.575328220442;4811.109944660214;414351;416354;417002;420563;420638;38453;39517.20965580672;65597;66019.66990165018;66297.66851165809;39812.20375592472;39951.80024099919;40078;40212.59787402089;40405.393919091235;16238.75641865373;16248.51254462368;16253.83746162547;16339.183040848147;16372.345106195857;13969.65075873091;14127.85872141286;14154.292285385689;14163.221022843842;14170.362333695008;8921.527947888526;9010.729678109667;9037.186653201112;9044.592993315317;9112.681056163003;3442.8278586070596;3447.2588393495016;3480.4083305838067;3490.528778614905;3552.467129930531;1100.3452945497415;1109.7669489407315;1143.9656810295705;1168.6026750904732;1202.6632542888005;32706;32864.17839553983;33174.502382464285;33186.50220246698;33244.66755332416;1178.128185143005;1185.057878986208;1186.3178672263482;1195.2768575012167;1285.9421326040351;16854.494365169063;17446.419885444357;17559.561010974572;17586.296548137932;17603.29586816513;483132;1060542;1074049;1074360;1077306;64032;65369.346306536325;65658;65719;65720.6713966427;1454.8690617844234;1460.3355473259642;1477.7931089647673;1489.6275931017246;1493.4250313629113;34413.31173376468;34482.137946551535;34733;35199.64800351964;35271.64728352786;57048.429515705975;57527.42472575221;57713;57943;57955.420445795;538917;543392;543617;543956;544780;31456.842715786275;31461.213469663442;31826.681733183297;31836.681633183372;32055;52012.21981670354;52221;52553.73723131513;53226;53635;44280.778596106815;45724.54275457203;45895.770521147184;46613.766931165126;46683.066338673714;44347.77826110849;45684.314735278334;45936.31095533637;46446.30330544977;46872.29691554561;38807.2238555233;42996.57003430051;43960.780196098814;44003.559964401225;44144.55855441533;38286.80856595699;38998.80500597479;39588.01029974274;42416.151676965674;42480.36279455749;30059.549106762977;30094.69905300919;30480.237994050327;30626;30830 05:39:28 INFO - PROCESS | 5313 | |2;jquery.html;412.5379574876137;416.74578507110635;427.5382586806249;430.27069117845264;440.92063428582844;19953.800461995375;20186.293479728214;20580.073896674647;20627.69058464124;20752;18109.366172183974;19554.31559895404;19787;20410.897945510253;20549.178032878703;5713.514351280146;5925.140854576105;5925.4370834770625;5932.228810254661;5942.346341902409;4345.261305578059;5866.76532938682;5920.052791553352;6464.579802312858;6483.578567393104;6534.444572211368;6568.1789776277965;6574.2110946686225;6584.506162037842;6584.736610535573;6456.644884531349;6472.417482426581;6501.707423165945;6512.609243445361;6519.315471875432;5613.522850557708;5738.42615738427;5747.453991870766;5778.653280803165;5822.214001109838;24077;24621.753782462303;24828.3792905177;24909.377265568324;25009.87495062513;2522.6216067590008;2644.2067379785985;2718.9728102719114;3108.9378212435627;3225.5645487859074;14902.403903843833;14926.10443373401;15210.467633632881;15813.446529371417;15956.920215398848;2009.7688765791975;2118.629239883014;2230.230570453191;2234.340869443518;2252.662100684894;79506.6024669873;81556.18443815545;90901;91943.54028229883;92810.53594731983;11301.26541774782;11585;11743.530258789724;11761.705957351134;11797.705057373634;16736.665266694843;16771.1614419281;16784.32862685479;16785.58036049084;16793;8312.584370781437;8448.408611397266;8635.186610811674;8657.350698697497;8759;8292.502449853027;8304.543250121302;8371.162883711702;8467.788305292417;8498.447600905922;4513.7066090704;4623.791929363187;4734.644901632391;4761.880952976203;5208.088584497774;1977.920883164686;2013.1041686449682;2072.305777564533;2118.364490652798;2144.5496445746257;940.2290122099812;1017.2218253036285;1020.6019652335596;1058.2327812336148;1075.0271004740723;18916.337928172725;20279.986000699904;20382.28661996823;20426.897865510873;20854.270100546408;1047.8271085270997;1049.1239814754613;1051.295631926611;1064.084886997182;1072.372661992743;10038.247131465168;11416.942915285537;11425.428728563538;11595.246308989888;11603.303801771921;535590;546517;551462;554935;569449;88487;93696.06303936873;106887;107979;112764;1060.4008735064663;1076.445630500293;1111.3276467737064;1113.4210210690396;1115.3753897817087;17959.910200448914;18442.35451759182;18755.249790008245;18880.52798680044;19092.8090719091;44934.57238736319;44994;45003.32495012512;45125.54874451345;45161.32258016067;65589.34410656024;65762.67118664375;65935.34064659291;66251.33748662451;66793.3320666787;7745.845083098419;8216.835663286582;8327.50034997902;8555.828883422173;8961.865572016295;13345.532906348231;13386.665333366744;13430.261335626634;13547;13585.116967397087;11378.146639001943;11973.940130299292;11988.64034078979;12019.519219231483;12111;11665.766684666429;11761.529538818351;11799.882001179878;11893.82159267629;12061.698457538632;11083.778324433628;11176;11365.772684546428;12197.878021219674;12259.938700306442;8185.099639039582;8199.303059239794;8239.794005149919;8318.875216871631;8392.538410387244;8766.298696104168;11110.277831940897;11192.608258710907;11256.549738010388;11279.32324060559 05:39:28 INFO - PROCESS | 5313 | |3;mootools.html;1587.6427803744155;1593.1715507935874;1796.7574377459048;1886.8018858019916;1892.5079479335368;8302.83394332113;8763.868541971875;8902.198802107809;9553.904460955388;9593.760155996095;8834.514101724402;9172.08279172084;9183.908160918389;9208.309376796733;9236.8614470783;2481.5409149307347;2529.7976161907004;2533.2147034419295;2534.1257266243115;2541.961870571943;2435.1111844176835;2548.0444833187553;2549.6048112542494;2556.4248044190094;2561.7822485088695;2463.1502131764514;2463.310273123528;2467.9753202467923;2468.0868078810777;2485.763852434016;2444.5355382477273;2446.412860913377;2446.7675570820834;2455.2020593307248;2463.7659422354845;2419.649150873131;2425.842320249196;2439.9390015249583;2442.3527765142253;2472.11004038546;9389.859152112724;9478.241740660731;9498.477583732893;9584;9587.856182157273;5377.139657654757;5486.753096110645;5488.17677348397;5556.9444305556835;5560.888782224373;3213.0975310803838;3248.6588908164777;3340.1816554943953;3366.5960084789735;3427.8628854846024;1143.6626195272415;1168.760404117154;1225.91418600698;1240.1628900492221;1241.3544956622507;12724.618261452171;12730.108892377537;12768.80846787299;12786.296753678544;12829.294388808612;4733.526647335308;4818.253170758538;4878.438979517401;4960.355153829979;5002.09962206799;10871.891281087088;10909.83635245472;10912.945435272773;10922.672319830417;10927.344359338305;5509.421510741395;5557.805476808292;5558.555315574744;5567.164925261243;5568.777248909998;4455.398521199597;4884.5603895649565;4984.3021976923255;5030.698158110525;5147.253648221062;4191.538930717618;4212.873613791591;4329.848455304049;4357.869263922087;4363.912721745547;1298.448159532195;1328.4486937920656;1342.3020029584561;1357.8099066130749;1363.863613638648;921.5255591054394;959.4962644611635;1015.0357160697367;1071.0842229893485;1084.9403282819521;6020.4280593343565;6204.937950620526;6250.343713910065;6314.842128946813;6393.136926514954;497.8008796481436;503.9014947414598;504.5156649616336;508.7583397885975;512.5438359859728;4327.956720432819;4395.296752519582;4412.448443944507;4419.889502762392;4539.02410981634;11318;11547.711307217387;11614.419279036014;11695.12286578493;11721.472533736003;11379.317240965569;11488.540458381738;11515.481803318871;11585.652430426931;11627.53489860396;8556.058833528183;8651.178138076873;8702.738917832476;8783.868241976375;8830.955845220733;6232.750689972531;6337.04944258365;6359.300476947625;6363.459105976044;6504.6747662615735;10441;10524.631637892944;10540.631077912236;10545.683629491128;10625.521851516545;10728.570857165625;10732.892671073188;10735.516901739284;10780.730481738019;10915.672529824118;7152.427805775629;8162.6734930602115;8173.713920012771;8211.055728591049;8290.46112002718;9098.772530686785;9109.18017378439;9141.222996045144;9176.632934682804;9196.58615362297;8182.67269309221;8186.549739764132;8254.463459875089;8282.917170828214;8288.50268983863;7645.5794931279315;8018.4787988780545;8035.67857285702;8075.919240807517;8265.049519305358;7271.636418179176;7280.9271907280245;7320.1215854097845;7456.254374562501;7561.054868141482;4993.675411098267;5058.215976523645;5500.917486237629;5518.944810551843;5600.971995139998;5706.3152421709665;5748.367679555162;5751.252337196138;5759.337676167294;5782.3639399666845 05:39:28 INFO - PROCESS | 5313 | |4;prototype.html;352.3798115317042;354.60992907801403;355.2628296285207;357.4459587639159;359.4249201277956;5789.826305210845;5845.210896528967;5862.794802181925;5866.73599688014;5869.941300586993;5748.655080695161;5751.741171647286;5759.918443632658;5781.797637082703;5783.855403614912;4319.913601727972;4487.596116349528;4529.297958816373;4531.252343363341;4542.613877820373;4500.662450316239;4513.142502924434;4530.640807757676;4536.64094670771;4541.682082254243;4471.9776401118115;4498.730076195422;4507.625174321847;4513.480949690795;4534.523874993128;4425.513193548707;4450.332450132473;4475.172093162775;4480.8879778005485;4495.303227999665;3936.2521120987208;3951.214393852356;3965.722399432041;3967.4247234150885;3976.363781794928;5610.4670056344585;5682.318121825366;5682.659040457585;5690.60165788395;5712.65724056558;5027.824026159067;5131.589472842164;5177.767000484987;5201.297824793643;5218.895622087575;5973.402659734035;6011.158437818709;6065.666388348639;6083.6045657032155;6094.207752992127;4277.310462367379;4309.147066761291;4365.738055716667;4367.54140815213;4368.60682538573;5959.255093113361;6148.8770224595255;6232.34560371159;6254.374562543755;6307.905381419282;4343.891402714894;4361.629261512743;4373.650107991353;4375.453068366454;4382.255016647177;4424.6902716810155;4451.933221001687;4459.866204013885;4467.396901418267;4487.41663583732;4895.363602731622;5016.44819069902;5117.360329958755;5125.077486052472;5137.434882163034;5986.6707331097205;6037.154798328237;6043.667598282049;6049.879002419927;6056.212692350055;1524.521214422031;1549.977015170005;1555.0902721907585;1560.8048993875766;1565.3973220310263;939.3189937295406;994.9751755691649;1005.1104772276442;1005.9597616095274;1010.9090181883665;5189.558887494605;5263.447228094371;5287.598786321628;5292.782659988225;5318.680879147263;5463.399026107125;5488;5516.420775818481;5538.723063846792;5557.527610153102;4966.726830024311;5252.763625636855;5258.132408152612;5273.1299335609965;5316.175992721134;2938.4563855686765;2974.9553756693667;2975.925601859971;2979.0636086543486;2998.8050776699447;5582.8325150245555;5647.548196144298;5732.3407808101765;5773.8267851963665;5775.48020678141;5924.4667979882015;6398.5521013528605;6426.260979987362;6453.612783233021;6468.902966455507;6508.576942498722;6517.837054073686;6545.836354091186;6556.803295901035;6579.374959378852;6118.571699981137;6227.688615569113;6290.716917738621;6314.89489339373;6324.873502530016;6243.594166379171;6245.250569931637;6268.6179251884;6272.874542509216;6277.96861015692;6238.906416403848;6287.145292138738;6519.445847102859;6533.836654083686;6567.868642627216;4445.799939002688;4473.5526447355005;4482.394876691605;4490.348899409502;4500.054988452462;6135.6932153393045;6221.844453888689;6265.780697675559;6323.905141422791;6327.810165695037;6094.146819445193;6098.481629061579;6101.206843110367;6109.20580324555;6126.172966649446;5938.910916336172;6115.235595550556;6133.3866613338305;6154.415145638932;6163.691815409301;6042.969785151046;6068.393160683896;6074.939250607437;6078.574499784972;6091.299500557493;6104.511639068953;6123.326434092335;6165.599236049642;6178.104174894705;6184.350643182401;5764.279465066867;5954.672493012753;5961;5982;6004 05:39:28 INFO - PROCESS | 5313 | |5;yui.html;753.1677496366515;779.9376049916008;794.5113755040649;817.5830326533467;834.8539005674005;15929.44246951357;17284.654306913853;17755.55611109723;17765.289388424448;17783.46649600511;14643.19462429566;14692.191929443876;14692.853071469282;14748.926255368735;14826.555203343916;736.4513437489052;860.1957170046012;879.1560102301765;905.3571963905634;910.4400793512009;885.8582626779717;890.3278025091047;895.1764376773351;897.4121950122681;898.5103118800237;950.3015283766426;956.1920177450044;957.3681370295607;959.3140904253457;959.6689142245914;945.9337846350758;948.0945696859483;948.9383190092622;952.4190243951219;953.208836665566;698.8043347862607;699.5977313044968;700.1633048507036;700.6706847781535;703.0157779109237;15962.122083285414;15982.881198316123;15984.04095754247;16011.119388433632;16041.117738524377;16300.266488008068;16760.83239167605;16954.406595769087;17302.577639130533;17808.10959452022;12927.418266178045;13036.934815325958;13074.346282685829;13135.605931822061;13163.53927612529;4092.9386059209137;4110.116324990112;4138.151678905817;4140.710150289481;4201.70588058836;88205;89033.55483222542;89377;89395.55302223447;89436;12740.4266807992;12921.935390322988;13016.934915325362;13049.151805132637;13327.866721332663;19029.429117126507;20150.093245803975;20196;20876.686849697267;26201.475970480864;9306.302027347841;9359.29805264608;9391.530423478798;9551.331406801457;9592.184664303611;4873.707577545288;5126.307948426989;5138.537531622172;5172.7413629319135;5192.818251361185;4032.9798351008644;4175.749455032707;4176.14389050244;4188.85339013133;4195.4336164618;662.1160750398176;720.4632548751214;739.9778006659809;753.5365750063781;758.2758465665277;776.3556248313886;778.8091917480141;783.6434422337823;787.0751866556773;795.7732046366758;10325.277230593936;10759.354438733544;10945.23383363157;11229.775404491864;11562.595309164139;642.7332656947408;733.3033618062861;779.9805004874923;803.1005274093053;807.6284908941952;5025.195968645073;5355.625106242604;5376.758045887945;5390.541803946709;5404.729763511808;74003.62998185083;92099.07900920905;118886;132789;141003;116373;116851;117811.41094294644;119899;120376.3981180106;96093;102781;102836.48581757043;104335.4783226079;104501.47749261204;9709.757256068373;9957.402555846673;10075.596976120873;10098.697039088838;10148.74628134273;58378.7081064592;59755.402445976724;60248;61844.69077654583;62041.37958620356;64902.67548662226;67280.66359668366;70426;71077.64461177868;78379;14513.201773902114;14775.261236938326;14794.77807832905;14903.552893413214;14998.175100369584;16147.596310092342;16656.250468728693;16767.1785450405;16881.493555193363;17252.309907603554;14745;15295.85281103944;15756.527304180892;15774.9211253943;15860.52418427449;15210;15292.158931258442;15455.459058932884;15680.607984800472;15709.293081811575;14731.631709206928;14860;14866.03370780939;15459.149746764037;15856.286467108777;12410.5656302029;12496.25022498653;12563.371831408576;12566.115887182026;13326.666833329244;11756.58851940178;11954.880451195724;12988.285644289655;13104.475820967054;13356.599302020955 05:39:28 INFO - PROCESS | 5313 | __end_tp_report 05:39:28 INFO - PROCESS | 5313 | __start_cc_report 05:39:28 INFO - PROCESS | 5313 | _x_x_mozilla_cycle_collect,344 05:39:28 INFO - PROCESS | 5313 | __end_cc_report 05:39:28 INFO - PROCESS | 5313 | __startTimestamp1473683968783__endTimestamp 05:39:28 INFO - PROCESS | 5313 | 05:39:28 INFO - PROCESS | 5313 | ------- Summary: start ------- 05:39:28 INFO - PROCESS | 5313 | Number of tests: 6 05:39:28 INFO - PROCESS | 5313 | 05:39:28 INFO - PROCESS | 5313 | [#0] dojo.html Cycles:165 Average:5725.84 Median:5962.79 stddev:663.16 (11.1%) stddev-sans-first:637.13 05:39:28 INFO - PROCESS | 5313 | Values: 3292.4 3315.5 3318.3 3322.6 3332.9 5693.3 5754.5 5758.4 5778.9 5803.9 5627.5 5662.2 5685.1 5705.0 5719.9 5153.5 5166.9 5199.6 5261.3 5269.0 5136.5 5138.5 5194.5 5251.7 5305.7 5187.6 5203.9 5211.6 5212.8 5265.7 5259.8 5263.1 5266.6 5274.9 5306.8 5088.2 5116.0 5121.8 5149.7 5158.1 5664.7 5706.4 5711.2 5718.4 5747.2 6105.6 6126.7 6156.8 6159.6 6159.9 6001.6 6096.5 6126.1 6217.9 6226.1 5776.9 5793.2 5794.6 5816.1 5822.7 6326.5 6331.8 6333.5 6333.7 6339.6 5955.1 5961.6 5970.6 6055.2 6074.0 6122.7 6123.9 6128.2 6130.9 6134.0 6047.5 6105.0 6108.0 6108.5 6145.8 5910.4 6003.2 6050.6 6116.9 6152.8 4680.6 4681.1 4683.3 4702.9 4709.1 4183.2 4183.5 4191.0 4193.9 4214.3 5734.0 5862.7 5922.1 5928.8 5928.9 6218.2 6234.5 6248.3 6260.9 6270.1 5967.0 5994.3 6006.2 6025.2 6035.4 5256.6 5265.2 5278.2 5380.4 5381.0 6351.3 6385.4 6392.1 6392.7 6421.5 6095.5 6151.0 6167.3 6184.8 6202.4 6239.0 6375.1 6398.6 6409.2 6439.5 6181.7 6198.8 6200.9 6201.3 6213.3 6324.6 6332.5 6335.4 6343.6 6346.5 5893.3 5992.2 6026.0 6066.0 6108.1 6080.7 6092.5 6093.9 6098.0 6129.7 6006.1 6013.2 6042.6 6054.7 6075.1 5888.0 5917.1 5941.2 5962.8 6074.2 5863.1 5863.1 5875.4 5881.2 5888.1 05:39:28 INFO - PROCESS | 5313 | 05:39:28 INFO - PROCESS | 5313 | [#1] ext.html Cycles:180 Average:77109.79 Median:43478.68 stddev:187307.69 (430.8%) stddev-sans-first:187745.01 05:39:28 INFO - PROCESS | 5313 | Values: 583.7 631.9 665.8 670.9 675.4 41579.6 46591.5 46659.5 46668.0 46755.0 47029.8 47752.0 47868.3 47943.5 48055.8 9145.5 9171.3 9273.8 9274.5 9282.1 9019.0 9146.8 9148.0 9150.6 9178.6 9240.4 9283.3 9290.6 9295.6 9295.9 9096.2 9183.8 9197.8 9230.1 9246.6 9043.6 9101.8 9113.6 9121.0 9169.1 35785.3 36032.5 36034.3 36099.6 36118.5 3646.5 4215.0 4525.6 4608.4 4623.0 35692.8 35967.5 36500.5 36507.1 37431.6 4649.8 4685.9 4709.3 4718.6 4811.1 414351.0 416354.0 417002.0 420563.0 420638.0 38453.0 39517.2 65597.0 66019.7 66297.7 39812.2 39951.8 40078.0 40212.6 40405.4 16238.8 16248.5 16253.8 16339.2 16372.3 13969.7 14127.9 14154.3 14163.2 14170.4 8921.5 9010.7 9037.2 9044.6 9112.7 3442.8 3447.3 3480.4 3490.5 3552.5 1100.3 1109.8 1144.0 1168.6 1202.7 32706.0 32864.2 33174.5 33186.5 33244.7 1178.1 1185.1 1186.3 1195.3 1285.9 16854.5 17446.4 17559.6 17586.3 17603.3 483132.0 1060542.0 1074049.0 1074360.0 1077306.0 64032.0 65369.3 65658.0 65719.0 65720.7 1454.9 1460.3 1477.8 1489.6 1493.4 34413.3 34482.1 34733.0 35199.6 35271.6 57048.4 57527.4 57713.0 57943.0 57955.4 538917.0 543392.0 543617.0 543956.0 544780.0 31456.8 31461.2 31826.7 31836.7 32055.0 52012.2 52221.0 52553.7 53226.0 53635.0 44280.8 45724.5 45895.8 46613.8 46683.1 44347.8 45684.3 45936.3 46446.3 46872.3 38807.2 42996.6 43960.8 44003.6 44144.6 38286.8 38998.8 39588.0 42416.2 42480.4 30059.5 30094.7 30480.2 30626.0 30830.0 05:39:28 INFO - PROCESS | 5313 | 05:39:28 INFO - PROCESS | 5313 | [#2] jquery.html Cycles:180 Average:31934.56 Median:13164.83 stddev:90986.66 (691.1%) stddev-sans-first:91211.11 05:39:28 INFO - PROCESS | 5313 | Values: 412.5 416.7 427.5 430.3 440.9 19953.8 20186.3 20580.1 20627.7 20752.0 18109.4 19554.3 19787.0 20410.9 20549.2 5713.5 5925.1 5925.4 5932.2 5942.3 4345.3 5866.8 5920.1 6464.6 6483.6 6534.4 6568.2 6574.2 6584.5 6584.7 6456.6 6472.4 6501.7 6512.6 6519.3 5613.5 5738.4 5747.5 5778.7 5822.2 24077.0 24621.8 24828.4 24909.4 25009.9 2522.6 2644.2 2719.0 3108.9 3225.6 14902.4 14926.1 15210.5 15813.4 15956.9 2009.8 2118.6 2230.2 2234.3 2252.7 79506.6 81556.2 90901.0 91943.5 92810.5 11301.3 11585.0 11743.5 11761.7 11797.7 16736.7 16771.2 16784.3 16785.6 16793.0 8312.6 8448.4 8635.2 8657.4 8759.0 8292.5 8304.5 8371.2 8467.8 8498.4 4513.7 4623.8 4734.6 4761.9 5208.1 1977.9 2013.1 2072.3 2118.4 2144.5 940.2 1017.2 1020.6 1058.2 1075.0 18916.3 20280.0 20382.3 20426.9 20854.3 1047.8 1049.1 1051.3 1064.1 1072.4 10038.2 11416.9 11425.4 11595.2 11603.3 535590.0 546517.0 551462.0 554935.0 569449.0 88487.0 93696.1 106887.0 107979.0 112764.0 1060.4 1076.4 1111.3 1113.4 1115.4 17959.9 18442.4 18755.2 18880.5 19092.8 44934.6 44994.0 45003.3 45125.5 45161.3 65589.3 65762.7 65935.3 66251.3 66793.3 7745.8 8216.8 8327.5 8555.8 8961.9 13345.5 13386.7 13430.3 13547.0 13585.1 11378.1 11973.9 11988.6 12019.5 12111.0 11665.8 11761.5 11799.9 11893.8 12061.7 11083.8 11176.0 11365.8 12197.9 12259.9 8185.1 8199.3 8239.8 8318.9 8392.5 8766.3 11110.3 11192.6 11256.5 11279.3 05:39:28 INFO - PROCESS | 5313 | 05:39:28 INFO - PROCESS | 5313 | [#3] mootools.html Cycles:180 Average:6110.56 Median:4848.35 stddev:3483.90 (71.9%) stddev-sans-first:3477.09 05:39:28 INFO - PROCESS | 5313 | Values: 1587.6 1593.2 1796.8 1886.8 1892.5 8302.8 8763.9 8902.2 9553.9 9593.8 8834.5 9172.1 9183.9 9208.3 9236.9 2481.5 2529.8 2533.2 2534.1 2542.0 2435.1 2548.0 2549.6 2556.4 2561.8 2463.2 2463.3 2468.0 2468.1 2485.8 2444.5 2446.4 2446.8 2455.2 2463.8 2419.6 2425.8 2439.9 2442.4 2472.1 9389.9 9478.2 9498.5 9584.0 9587.9 5377.1 5486.8 5488.2 5556.9 5560.9 3213.1 3248.7 3340.2 3366.6 3427.9 1143.7 1168.8 1225.9 1240.2 1241.4 12724.6 12730.1 12768.8 12786.3 12829.3 4733.5 4818.3 4878.4 4960.4 5002.1 10871.9 10909.8 10912.9 10922.7 10927.3 5509.4 5557.8 5558.6 5567.2 5568.8 4455.4 4884.6 4984.3 5030.7 5147.3 4191.5 4212.9 4329.8 4357.9 4363.9 1298.4 1328.4 1342.3 1357.8 1363.9 921.5 959.5 1015.0 1071.1 1084.9 6020.4 6204.9 6250.3 6314.8 6393.1 497.8 503.9 504.5 508.8 512.5 4328.0 4395.3 4412.4 4419.9 4539.0 11318.0 11547.7 11614.4 11695.1 11721.5 11379.3 11488.5 11515.5 11585.7 11627.5 8556.1 8651.2 8702.7 8783.9 8831.0 6232.8 6337.0 6359.3 6363.5 6504.7 10441.0 10524.6 10540.6 10545.7 10625.5 10728.6 10732.9 10735.5 10780.7 10915.7 7152.4 8162.7 8173.7 8211.1 8290.5 9098.8 9109.2 9141.2 9176.6 9196.6 8182.7 8186.5 8254.5 8282.9 8288.5 7645.6 8018.5 8035.7 8075.9 8265.0 7271.6 7280.9 7320.1 7456.3 7561.1 4993.7 5058.2 5500.9 5518.9 5601.0 5706.3 5748.4 5751.3 5759.3 5782.4 05:39:28 INFO - PROCESS | 5313 | 05:39:28 INFO - PROCESS | 5313 | [#4] prototype.html Cycles:180 Average:5037.44 Median:5664.93 stddev:1496.74 (26.4%) stddev-sans-first:1459.05 05:39:28 INFO - PROCESS | 5313 | Values: 352.4 354.6 355.3 357.4 359.4 5789.8 5845.2 5862.8 5866.7 5869.9 5748.7 5751.7 5759.9 5781.8 5783.9 4319.9 4487.6 4529.3 4531.3 4542.6 4500.7 4513.1 4530.6 4536.6 4541.7 4472.0 4498.7 4507.6 4513.5 4534.5 4425.5 4450.3 4475.2 4480.9 4495.3 3936.3 3951.2 3965.7 3967.4 3976.4 5610.5 5682.3 5682.7 5690.6 5712.7 5027.8 5131.6 5177.8 5201.3 5218.9 5973.4 6011.2 6065.7 6083.6 6094.2 4277.3 4309.1 4365.7 4367.5 4368.6 5959.3 6148.9 6232.3 6254.4 6307.9 4343.9 4361.6 4373.7 4375.5 4382.3 4424.7 4451.9 4459.9 4467.4 4487.4 4895.4 5016.4 5117.4 5125.1 5137.4 5986.7 6037.2 6043.7 6049.9 6056.2 1524.5 1550.0 1555.1 1560.8 1565.4 939.3 995.0 1005.1 1006.0 1010.9 5189.6 5263.4 5287.6 5292.8 5318.7 5463.4 5488.0 5516.4 5538.7 5557.5 4966.7 5252.8 5258.1 5273.1 5316.2 2938.5 2975.0 2975.9 2979.1 2998.8 5582.8 5647.5 5732.3 5773.8 5775.5 5924.5 6398.6 6426.3 6453.6 6468.9 6508.6 6517.8 6545.8 6556.8 6579.4 6118.6 6227.7 6290.7 6314.9 6324.9 6243.6 6245.3 6268.6 6272.9 6278.0 6238.9 6287.1 6519.4 6533.8 6567.9 4445.8 4473.6 4482.4 4490.3 4500.1 6135.7 6221.8 6265.8 6323.9 6327.8 6094.1 6098.5 6101.2 6109.2 6126.2 5938.9 6115.2 6133.4 6154.4 6163.7 6043.0 6068.4 6074.9 6078.6 6091.3 6104.5 6123.3 6165.6 6178.1 6184.4 5764.3 5954.7 5961.0 5982.0 6004.0 05:39:28 INFO - PROCESS | 5313 | 05:39:28 INFO - PROCESS | 5313 | [#5] yui.html Cycles:180 Average:22888.24 Median:4062.96 stddev:33029.12 (812.9%) stddev-sans-first:33079.95 05:39:28 INFO - PROCESS | 5313 | Values: 753.2 779.9 794.5 817.6 834.9 15929.4 17284.7 17755.6 17765.3 17783.5 14643.2 14692.2 14692.9 14748.9 14826.6 736.5 860.2 879.2 905.4 910.4 885.9 890.3 895.2 897.4 898.5 950.3 956.2 957.4 959.3 959.7 945.9 948.1 948.9 952.4 953.2 698.8 699.6 700.2 700.7 703.0 15962.1 15982.9 15984.0 16011.1 16041.1 16300.3 16760.8 16954.4 17302.6 17808.1 12927.4 13036.9 13074.3 13135.6 13163.5 4092.9 4110.1 4138.2 4140.7 4201.7 88205.0 89033.6 89377.0 89395.6 89436.0 12740.4 12921.9 13016.9 13049.2 13327.9 19029.4 20150.1 20196.0 20876.7 26201.5 9306.3 9359.3 9391.5 9551.3 9592.2 4873.7 5126.3 5138.5 5172.7 5192.8 4033.0 4175.7 4176.1 4188.9 4195.4 662.1 720.5 740.0 753.5 758.3 776.4 778.8 783.6 787.1 795.8 10325.3 10759.4 10945.2 11229.8 11562.6 642.7 733.3 780.0 803.1 807.6 5025.2 5355.6 5376.8 5390.5 5404.7 74003.6 92099.1 118886.0 132789.0 141003.0 116373.0 116851.0 117811.4 119899.0 120376.4 96093.0 102781.0 102836.5 104335.5 104501.5 9709.8 9957.4 10075.6 10098.7 10148.7 58378.7 59755.4 60248.0 61844.7 62041.4 64902.7 67280.7 70426.0 71077.6 78379.0 14513.2 14775.3 14794.8 14903.6 14998.2 16147.6 16656.3 16767.2 16881.5 17252.3 14745.0 15295.9 15756.5 15774.9 15860.5 15210.0 15292.2 15455.5 15680.6 15709.3 14731.6 14860.0 14866.0 15459.1 15856.3 12410.6 12496.3 12563.4 12566.1 13326.7 11756.6 11954.9 12988.3 13104.5 13356.6 05:39:28 INFO - PROCESS | 5313 | -------- Summary: end -------- 05:39:28 INFO - PROCESS | 5313 | 05:39:29 INFO - TEST-INFO | 5313: exit 0 05:39:29 INFO - TEST-OK | dromaeo_css | took 1110897ms 05:39:29 INFO - TEST-START | kraken 05:39:29 INFO - operating with platform_type : linux_ 05:39:29 INFO - Initialising browser for kraken test... 05:39:29 INFO - TEST-INFO | started process 5574 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpENnzIg/profile http://localhost:56860/getInfo.html) 05:39:33 INFO - PROCESS | 5574 | __metrics Screen width/height:1600/1200 05:39:33 INFO - PROCESS | 5574 | colorDepth:24 05:39:33 INFO - PROCESS | 5574 | Browser inner width/height: 1024/697 05:39:33 INFO - PROCESS | 5574 | __metrics 05:39:34 ERROR - PROCESS | 5574 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 05:39:35 INFO - TEST-INFO | 5574: exit 0 05:39:35 INFO - Browser initialized. 05:39:35 INFO - Running cycle 1/1 for kraken test... 05:39:35 INFO - TEST-INFO | started process 5704 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpENnzIg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:39:47 INFO - PROCESS | 5704 | RSS: Main: 153161728 05:39:47 INFO - PROCESS | 5704 | 05:39:49 INFO - PROCESS | 5704 | Cycle 1(1): loaded http://localhost:56860/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56860/tests/kraken/driver.html?testName=audio-beat-detection) 05:39:50 INFO - PROCESS | 5704 | RSS: Main: 158326784 05:39:50 INFO - PROCESS | 5704 | 05:39:53 INFO - PROCESS | 5704 | Cycle 1(1): loaded http://localhost:56860/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56860/tests/kraken/driver.html?testName=audio-dft) 05:39:53 INFO - PROCESS | 5704 | RSS: Main: 157589504 05:39:53 INFO - PROCESS | 5704 | 05:39:56 INFO - PROCESS | 5704 | Cycle 1(1): loaded http://localhost:56860/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56860/tests/kraken/driver.html?testName=audio-fft) 05:39:57 INFO - PROCESS | 5704 | RSS: Main: 157724672 05:39:57 INFO - PROCESS | 5704 | 05:39:59 INFO - PROCESS | 5704 | Cycle 1(1): loaded http://localhost:56860/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56860/tests/kraken/driver.html?testName=audio-oscillator) 05:40:00 INFO - PROCESS | 5704 | RSS: Main: 157569024 05:40:00 INFO - PROCESS | 5704 | 05:40:02 INFO - PROCESS | 5704 | Cycle 1(1): loaded http://localhost:56860/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56860/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:40:02 INFO - PROCESS | 5704 | RSS: Main: 158248960 05:40:02 INFO - PROCESS | 5704 | 05:40:05 INFO - PROCESS | 5704 | Cycle 1(1): loaded http://localhost:56860/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56860/tests/kraken/driver.html?testName=imaging-darkroom) 05:40:06 INFO - PROCESS | 5704 | RSS: Main: 157368320 05:40:06 INFO - PROCESS | 5704 | 05:40:09 INFO - PROCESS | 5704 | Cycle 1(1): loaded http://localhost:56860/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56860/tests/kraken/driver.html?testName=imaging-desaturate) 05:40:10 INFO - PROCESS | 5704 | RSS: Main: 157757440 05:40:10 INFO - PROCESS | 5704 | 05:40:13 INFO - PROCESS | 5704 | Cycle 1(1): loaded http://localhost:56860/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56860/tests/kraken/driver.html?testName=json-parse-financial) 05:40:13 INFO - PROCESS | 5704 | RSS: Main: 157855744 05:40:13 INFO - PROCESS | 5704 | 05:40:15 INFO - PROCESS | 5704 | Cycle 1(1): loaded http://localhost:56860/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56860/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:40:16 INFO - PROCESS | 5704 | RSS: Main: 157663232 05:40:16 INFO - PROCESS | 5704 | 05:40:17 INFO - PROCESS | 5704 | Cycle 1(1): loaded http://localhost:56860/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56860/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:40:18 INFO - PROCESS | 5704 | RSS: Main: 158183424 05:40:18 INFO - PROCESS | 5704 | 05:40:20 INFO - PROCESS | 5704 | Cycle 1(1): loaded http://localhost:56860/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56860/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:40:21 INFO - PROCESS | 5704 | RSS: Main: 158113792 05:40:21 INFO - PROCESS | 5704 | 05:40:23 INFO - PROCESS | 5704 | Cycle 1(1): loaded http://localhost:56860/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56860/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:40:24 INFO - PROCESS | 5704 | RSS: Main: 159084544 05:40:24 INFO - PROCESS | 5704 | 05:40:26 INFO - PROCESS | 5704 | Cycle 1(1): loaded http://localhost:56860/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56860/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:40:27 INFO - PROCESS | 5704 | RSS: Main: 158748672 05:40:27 INFO - PROCESS | 5704 | 05:40:29 INFO - PROCESS | 5704 | Cycle 1(1): loaded http://localhost:56860/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56860/tests/kraken/driver.html?testName=ai-astar) 05:40:29 INFO - PROCESS | 5704 | RSS: Main: 160595968 05:40:29 INFO - PROCESS | 5704 | 05:40:29 INFO - PROCESS | 5704 | __start_tp_report 05:40:29 INFO - PROCESS | 5704 | _x_x_mozilla_page_load 05:40:29 INFO - PROCESS | 5704 | _x_x_mozilla_page_load_details 05:40:29 INFO - PROCESS | 5704 | |i|pagename|runs| 05:40:29 INFO - PROCESS | 5704 | |0;ai-astar;102;111;111;105;111;112;111;113;112;120 05:40:29 INFO - PROCESS | 5704 | |1;audio-beat-detection;149;116;139;173;117;147;145;126;167;120 05:40:29 INFO - PROCESS | 5704 | |2;audio-dft;161;164;162;165;165;162;165;163;161;164 05:40:29 INFO - PROCESS | 5704 | |3;audio-fft;74;75;74;173;75;75;75;75;170;76 05:40:29 INFO - PROCESS | 5704 | |4;audio-oscillator;85;83;144;84;84;83;134;83;83;138 05:40:29 INFO - PROCESS | 5704 | |5;imaging-gaussian-blur;117;117;117;117;117;118;118;118;118;119 05:40:29 INFO - PROCESS | 5704 | |6;imaging-darkroom;147;148;148;148;148;148;146;148;146;147 05:40:29 INFO - PROCESS | 5704 | |7;imaging-desaturate;97;96;101;95;95;96;96;95;102;96 05:40:29 INFO - PROCESS | 5704 | |8;json-parse-financial;51;51;51;59;51;51;52;52;51;53 05:40:29 INFO - PROCESS | 5704 | |9;json-stringify-tinderbox;59;60;62;60;61;60;61;60;60;60 05:40:29 INFO - PROCESS | 5704 | |10;stanford-crypto-aes;73;80;72;79;72;72;72;77;72;72 05:40:29 INFO - PROCESS | 5704 | |11;stanford-crypto-ccm;122;117;128;116;116;127;137;120;157;126 05:40:29 INFO - PROCESS | 5704 | |12;stanford-crypto-pbkdf2;158;155;153;158;150;150;153;150;149;154 05:40:29 INFO - PROCESS | 5704 | |13;stanford-crypto-sha256-iterative;66;62;62;67;62;62;63;63;61;64 05:40:29 INFO - PROCESS | 5704 | __end_tp_report 05:40:29 INFO - PROCESS | 5704 | __start_cc_report 05:40:29 INFO - PROCESS | 5704 | _x_x_mozilla_cycle_collect,898 05:40:29 INFO - PROCESS | 5704 | __end_cc_report 05:40:29 INFO - PROCESS | 5704 | __startTimestamp1473684029489__endTimestamp 05:40:29 INFO - PROCESS | 5704 | 05:40:29 INFO - PROCESS | 5704 | ------- Summary: start ------- 05:40:29 INFO - PROCESS | 5704 | Number of tests: 14 05:40:29 INFO - PROCESS | 5704 | 05:40:29 INFO - PROCESS | 5704 | [#0] ai-astar Cycles:10 Average:110.80 Median:111.50 stddev:4.76 (4.3%) stddev-sans-first:3.83 05:40:29 INFO - PROCESS | 5704 | Values: 102.0 111.0 111.0 105.0 111.0 112.0 111.0 113.0 112.0 120.0 05:40:29 INFO - PROCESS | 5704 | 05:40:29 INFO - PROCESS | 5704 | [#1] audio-beat-detection Cycles:10 Average:139.90 Median:146.00 stddev:20.21 (13.8%) stddev-sans-first:21.16 05:40:29 INFO - PROCESS | 5704 | Values: 149.0 116.0 139.0 173.0 117.0 147.0 145.0 126.0 167.0 120.0 05:40:29 INFO - PROCESS | 5704 | 05:40:29 INFO - PROCESS | 5704 | [#2] audio-dft Cycles:10 Average:163.20 Median:164.00 stddev:1.62 (1.0%) stddev-sans-first:1.51 05:40:29 INFO - PROCESS | 5704 | Values: 161.0 164.0 162.0 165.0 165.0 162.0 165.0 163.0 161.0 164.0 05:40:29 INFO - PROCESS | 5704 | 05:40:29 INFO - PROCESS | 5704 | [#3] audio-fft Cycles:10 Average:94.20 Median:75.00 stddev:40.75 (54.3%) stddev-sans-first:42.56 05:40:29 INFO - PROCESS | 5704 | Values: 74.0 75.0 74.0 173.0 75.0 75.0 75.0 75.0 170.0 76.0 05:40:29 INFO - PROCESS | 5704 | 05:40:29 INFO - PROCESS | 5704 | [#4] audio-oscillator Cycles:10 Average:100.10 Median:83.00 stddev:26.73 (32.2%) stddev-sans-first:27.78 05:40:29 INFO - PROCESS | 5704 | Values: 85.0 83.0 144.0 84.0 84.0 83.0 134.0 83.0 83.0 138.0 05:40:29 INFO - PROCESS | 5704 | 05:40:29 INFO - PROCESS | 5704 | [#5] imaging-gaussian-blur Cycles:10 Average:117.60 Median:118.00 stddev:0.70 (0.6%) stddev-sans-first:0.71 05:40:29 INFO - PROCESS | 5704 | Values: 117.0 117.0 117.0 117.0 117.0 118.0 118.0 118.0 118.0 119.0 05:40:29 INFO - PROCESS | 5704 | 05:40:29 INFO - PROCESS | 5704 | [#6] imaging-darkroom Cycles:10 Average:147.40 Median:148.00 stddev:0.84 (0.6%) stddev-sans-first:0.88 05:40:29 INFO - PROCESS | 5704 | Values: 147.0 148.0 148.0 148.0 148.0 148.0 146.0 148.0 146.0 147.0 05:40:29 INFO - PROCESS | 5704 | 05:40:29 INFO - PROCESS | 5704 | [#7] imaging-desaturate Cycles:10 Average:96.90 Median:96.00 stddev:2.51 (2.6%) stddev-sans-first:2.67 05:40:29 INFO - PROCESS | 5704 | Values: 97.0 96.0 101.0 95.0 95.0 96.0 96.0 95.0 102.0 96.0 05:40:29 INFO - PROCESS | 5704 | 05:40:29 INFO - PROCESS | 5704 | [#8] json-parse-financial Cycles:10 Average:52.20 Median:51.50 stddev:2.49 (4.8%) stddev-sans-first:2.60 05:40:29 INFO - PROCESS | 5704 | Values: 51.0 51.0 51.0 59.0 51.0 51.0 52.0 52.0 51.0 53.0 05:40:29 INFO - PROCESS | 5704 | 05:40:29 INFO - PROCESS | 5704 | [#9] json-stringify-tinderbox Cycles:10 Average:60.30 Median:60.00 stddev:0.82 (1.4%) stddev-sans-first:0.73 05:40:29 INFO - PROCESS | 5704 | Values: 59.0 60.0 62.0 60.0 61.0 60.0 61.0 60.0 60.0 60.0 05:40:29 INFO - PROCESS | 5704 | 05:40:29 INFO - PROCESS | 5704 | [#10] stanford-crypto-aes Cycles:10 Average:74.10 Median:72.50 stddev:3.25 (4.5%) stddev-sans-first:3.42 05:40:29 INFO - PROCESS | 5704 | Values: 73.0 80.0 72.0 79.0 72.0 72.0 72.0 77.0 72.0 72.0 05:40:29 INFO - PROCESS | 5704 | 05:40:29 INFO - PROCESS | 5704 | [#11] stanford-crypto-ccm Cycles:10 Average:126.60 Median:126.50 stddev:12.55 (9.9%) stddev-sans-first:13.20 05:40:29 INFO - PROCESS | 5704 | Values: 122.0 117.0 128.0 116.0 116.0 127.0 137.0 120.0 157.0 126.0 05:40:29 INFO - PROCESS | 5704 | 05:40:29 INFO - PROCESS | 5704 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.00 Median:153.50 stddev:3.30 (2.1%) stddev-sans-first:2.96 05:40:29 INFO - PROCESS | 5704 | Values: 158.0 155.0 153.0 158.0 150.0 150.0 153.0 150.0 149.0 154.0 05:40:29 INFO - PROCESS | 5704 | 05:40:29 INFO - PROCESS | 5704 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.20 Median:63.00 stddev:1.93 (3.1%) stddev-sans-first:1.76 05:40:29 INFO - PROCESS | 5704 | Values: 66.0 62.0 62.0 67.0 62.0 62.0 63.0 63.0 61.0 64.0 05:40:29 INFO - PROCESS | 5704 | -------- Summary: end -------- 05:40:29 INFO - PROCESS | 5704 | 05:40:30 INFO - TEST-INFO | 5704: exit 0 05:40:30 INFO - TEST-OK | kraken | took 60911ms 05:40:30 INFO - SUITE-END | took 1171s 05:40:30 INFO - Completed test suite (00:19:32) 05:40:30 INFO - javascript benchmark 05:40:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7723.777559379744, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3292.3744488547172, 3315.4529502632067, 3318.269980604266, 3322.6178989416217, 3332.8500217490196, 5693.28833895763, 5754.510866576346, 5758.3665796762425, 5778.855528611782, 5803.912941305878, 5627.493525582697, 5662.17898404731, 5685.118806584976, 5705.0, 5719.857003574913, 5153.4846515348445, 5166.9224961625605, 5199.610029247802, 5261.263423120766, 5268.973655131738, 5136.512031357015, 5138.511841375082, 5194.480551944795, 5251.737413129347, 5305.734713264341, 5187.559057480119, 5203.895922081556, 5211.635185537014, 5212.817551385702, 5265.6840589564745, 5259.763955470461, 5263.105272103751, 5266.631335806495, 5274.9472505275035, 5306.787728490855, 5088.211327244283, 5116.0, 5121.769520371592, 5149.716765577892, 5158.071547121516, 5664.716764161775, 5706.429357064302, 5711.171880077407, 5718.428157184248, 5747.195392645033, 6105.633661980251, 6126.724297406583, 6156.815295541141, 6159.568830181889, 6159.876802463926, 6001.639901605918, 6096.4817990470865, 6126.050462178369, 6217.937820621826, 6226.097215903714, 5776.913346299809, 5793.217915581385, 5794.565407594448, 5816.069428891399, 5822.679752613604, 6326.525510586633, 6331.778387756406, 6333.493320534346, 6333.651649159248, 6339.587926784744, 5955.136505206808, 5961.552883533753, 5970.582059255898, 6055.2430946131735, 6073.997790364629, 6122.672617327906, 6123.908141377883, 6128.169829013797, 6130.860895427079, 6134.00801433197, 6047.485963693048, 6105.0, 6107.969460152671, 6108.480779133734, 6145.846353841101, 5910.408959104141, 6003.21958145447, 6050.576459647782, 6116.908246376308, 6152.815415537541, 4680.602148817388, 4681.110588988081, 4683.274092515666, 4702.858914232578, 4709.058188362341, 4183.226103170923, 4183.53981062089, 4190.958090419118, 4193.916121677549, 4214.262504061839, 5733.971330143406, 5862.70686465675, 5922.111683247461, 5928.762849485972, 5928.911066334009, 6218.160548325918, 6234.532410069263, 6248.250209974832, 6260.937390626126, 6270.090836828635, 5967.045272756338, 5994.310654274815, 6006.249218847644, 6025.15647809307, 6035.36628653985, 5256.579473642099, 5265.157574788015, 5278.234655974862, 5380.435054319319, 5380.973095134499, 6351.333110023474, 6385.393387628168, 6392.105105285264, 6392.7123279452535, 6421.518386120966, 6095.481884039907, 6151.0, 6167.259928808572, 6184.783532576338, 6202.4417802398, 6238.968805155945, 6375.139356186855, 6398.648074355955, 6409.198850143732, 6439.549231553746, 6181.721822517908, 6198.81403557894, 6200.875982480235, 6201.3178550358525, 6213.285472170758, 6324.620522768649, 6332.52506062038, 6335.366463353628, 6343.619382837044, 6346.4605508532295, 5893.292804863272, 5992.161097446448, 6025.969870150621, 6066.0, 6108.144859719554, 6080.695965201634, 6092.512598991982, 6093.9085913712215, 6098.0, 6129.724162412613, 6006.09908513709, 6013.218281623361, 6042.5770196087615, 6054.697265136813, 6075.058365953284, 5888.0, 5917.142014407973, 5941.168236446987, 5962.791302304398, 6074.1799857020505, 5863.061910094364, 5863.120531920246, 5875.383084776037, 5881.235439392852, 5888.057910734262], "value": 5680.100909023007, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [583.6614763437207, 631.9020551814468, 665.7536711416776, 670.8557660103077, 675.3955210086975, 41579.58420415799, 46591.53408465914, 46659.53340466594, 46668.0, 46755.0, 47029.76485117578, 47752.0, 47868.28197577039, 47943.520564794344, 48055.75972120143, 9145.496997665143, 9171.266298696088, 9273.768155796091, 9274.536273186348, 9282.118198771106, 9018.954905225464, 9146.817063658722, 9148.0, 9150.633974641007, 9178.586963586655, 9240.353175277733, 9283.25733941283, 9290.628374864997, 9295.628174872996, 9295.907040929571, 9096.226820720247, 9183.816323673522, 9197.816043679122, 9230.123138301919, 9246.583903724348, 9043.59303831329, 9101.772455688595, 9113.635454581809, 9120.954395228047, 9169.083091690844, 35785.28429431397, 36032.45951310706, 36034.279314413565, 36099.63900360989, 36118.458223126676, 3646.5259516262718, 4215.0, 4525.586008562738, 4608.354830323758, 4622.976885115553, 35692.82153589215, 35967.4604880927, 36500.45249321262, 36507.08732281688, 37431.625683743085, 4649.767511624405, 4685.929711054337, 4709.340692303081, 4718.575328220442, 4811.109944660214, 414351.0, 416354.0, 417002.0, 420563.0, 420638.0, 38453.0, 39517.20965580672, 65597.0, 66019.66990165018, 66297.66851165809, 39812.20375592472, 39951.80024099919, 40078.0, 40212.59787402089, 40405.393919091235, 16238.75641865373, 16248.51254462368, 16253.83746162547, 16339.183040848147, 16372.345106195857, 13969.65075873091, 14127.85872141286, 14154.292285385689, 14163.221022843842, 14170.362333695008, 8921.527947888526, 9010.729678109667, 9037.186653201112, 9044.592993315317, 9112.681056163003, 3442.8278586070596, 3447.2588393495016, 3480.4083305838067, 3490.528778614905, 3552.467129930531, 1100.3452945497415, 1109.7669489407315, 1143.9656810295705, 1168.6026750904732, 1202.6632542888005, 32706.0, 32864.17839553983, 33174.502382464285, 33186.50220246698, 33244.66755332416, 1178.128185143005, 1185.057878986208, 1186.3178672263482, 1195.2768575012167, 1285.9421326040351, 16854.494365169063, 17446.419885444357, 17559.561010974572, 17586.296548137932, 17603.29586816513, 483132.0, 1060542.0, 1074049.0, 1074360.0, 1077306.0, 64032.0, 65369.346306536325, 65658.0, 65719.0, 65720.6713966427, 1454.8690617844234, 1460.3355473259642, 1477.7931089647673, 1489.6275931017246, 1493.4250313629113, 34413.31173376468, 34482.137946551535, 34733.0, 35199.64800351964, 35271.64728352786, 57048.429515705975, 57527.42472575221, 57713.0, 57943.0, 57955.420445795, 538917.0, 543392.0, 543617.0, 543956.0, 544780.0, 31456.842715786275, 31461.213469663442, 31826.681733183297, 31836.681633183372, 32055.0, 52012.21981670354, 52221.0, 52553.73723131513, 53226.0, 53635.0, 44280.778596106815, 45724.54275457203, 45895.770521147184, 46613.766931165126, 46683.066338673714, 44347.77826110849, 45684.314735278334, 45936.31095533637, 46446.30330544977, 46872.29691554561, 38807.2238555233, 42996.57003430051, 43960.780196098814, 44003.559964401225, 44144.55855441533, 38286.80856595699, 38998.80500597479, 39588.01029974274, 42416.151676965674, 42480.36279455749, 30059.549106762977, 30094.69905300919, 30480.237994050327, 30626.0, 30830.0], "value": 20713.252487139594, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [412.5379574876137, 416.74578507110635, 427.5382586806249, 430.27069117845264, 440.92063428582844, 19953.800461995375, 20186.293479728214, 20580.073896674647, 20627.69058464124, 20752.0, 18109.366172183974, 19554.31559895404, 19787.0, 20410.897945510253, 20549.178032878703, 5713.514351280146, 5925.140854576105, 5925.4370834770625, 5932.228810254661, 5942.346341902409, 4345.261305578059, 5866.76532938682, 5920.052791553352, 6464.579802312858, 6483.578567393104, 6534.444572211368, 6568.1789776277965, 6574.2110946686225, 6584.506162037842, 6584.736610535573, 6456.644884531349, 6472.417482426581, 6501.707423165945, 6512.609243445361, 6519.315471875432, 5613.522850557708, 5738.42615738427, 5747.453991870766, 5778.653280803165, 5822.214001109838, 24077.0, 24621.753782462303, 24828.3792905177, 24909.377265568324, 25009.87495062513, 2522.6216067590008, 2644.2067379785985, 2718.9728102719114, 3108.9378212435627, 3225.5645487859074, 14902.403903843833, 14926.10443373401, 15210.467633632881, 15813.446529371417, 15956.920215398848, 2009.7688765791975, 2118.629239883014, 2230.230570453191, 2234.340869443518, 2252.662100684894, 79506.6024669873, 81556.18443815545, 90901.0, 91943.54028229883, 92810.53594731983, 11301.26541774782, 11585.0, 11743.530258789724, 11761.705957351134, 11797.705057373634, 16736.665266694843, 16771.1614419281, 16784.32862685479, 16785.58036049084, 16793.0, 8312.584370781437, 8448.408611397266, 8635.186610811674, 8657.350698697497, 8759.0, 8292.502449853027, 8304.543250121302, 8371.162883711702, 8467.788305292417, 8498.447600905922, 4513.7066090704, 4623.791929363187, 4734.644901632391, 4761.880952976203, 5208.088584497774, 1977.920883164686, 2013.1041686449682, 2072.305777564533, 2118.364490652798, 2144.5496445746257, 940.2290122099812, 1017.2218253036285, 1020.6019652335596, 1058.2327812336148, 1075.0271004740723, 18916.337928172725, 20279.986000699904, 20382.28661996823, 20426.897865510873, 20854.270100546408, 1047.8271085270997, 1049.1239814754613, 1051.295631926611, 1064.084886997182, 1072.372661992743, 10038.247131465168, 11416.942915285537, 11425.428728563538, 11595.246308989888, 11603.303801771921, 535590.0, 546517.0, 551462.0, 554935.0, 569449.0, 88487.0, 93696.06303936873, 106887.0, 107979.0, 112764.0, 1060.4008735064663, 1076.445630500293, 1111.3276467737064, 1113.4210210690396, 1115.3753897817087, 17959.910200448914, 18442.35451759182, 18755.249790008245, 18880.52798680044, 19092.8090719091, 44934.57238736319, 44994.0, 45003.32495012512, 45125.54874451345, 45161.32258016067, 65589.34410656024, 65762.67118664375, 65935.34064659291, 66251.33748662451, 66793.3320666787, 7745.845083098419, 8216.835663286582, 8327.50034997902, 8555.828883422173, 8961.865572016295, 13345.532906348231, 13386.665333366744, 13430.261335626634, 13547.0, 13585.116967397087, 11378.146639001943, 11973.940130299292, 11988.64034078979, 12019.519219231483, 12111.0, 11665.766684666429, 11761.529538818351, 11799.882001179878, 11893.82159267629, 12061.698457538632, 11083.778324433628, 11176.0, 11365.772684546428, 12197.878021219674, 12259.938700306442, 8185.099639039582, 8199.303059239794, 8239.794005149919, 8318.875216871631, 8392.538410387244, 8766.298696104168, 11110.277831940897, 11192.608258710907, 11256.549738010388, 11279.32324060559], "value": 9837.240246839954, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1587.6427803744155, 1593.1715507935874, 1796.7574377459048, 1886.8018858019916, 1892.5079479335368, 8302.83394332113, 8763.868541971875, 8902.198802107809, 9553.904460955388, 9593.760155996095, 8834.514101724402, 9172.08279172084, 9183.908160918389, 9208.309376796733, 9236.8614470783, 2481.5409149307347, 2529.7976161907004, 2533.2147034419295, 2534.1257266243115, 2541.961870571943, 2435.1111844176835, 2548.0444833187553, 2549.6048112542494, 2556.4248044190094, 2561.7822485088695, 2463.1502131764514, 2463.310273123528, 2467.9753202467923, 2468.0868078810777, 2485.763852434016, 2444.5355382477273, 2446.412860913377, 2446.7675570820834, 2455.2020593307248, 2463.7659422354845, 2419.649150873131, 2425.842320249196, 2439.9390015249583, 2442.3527765142253, 2472.11004038546, 9389.859152112724, 9478.241740660731, 9498.477583732893, 9584.0, 9587.856182157273, 5377.139657654757, 5486.753096110645, 5488.17677348397, 5556.9444305556835, 5560.888782224373, 3213.0975310803838, 3248.6588908164777, 3340.1816554943953, 3366.5960084789735, 3427.8628854846024, 1143.6626195272415, 1168.760404117154, 1225.91418600698, 1240.1628900492221, 1241.3544956622507, 12724.618261452171, 12730.108892377537, 12768.80846787299, 12786.296753678544, 12829.294388808612, 4733.526647335308, 4818.253170758538, 4878.438979517401, 4960.355153829979, 5002.09962206799, 10871.891281087088, 10909.83635245472, 10912.945435272773, 10922.672319830417, 10927.344359338305, 5509.421510741395, 5557.805476808292, 5558.555315574744, 5567.164925261243, 5568.777248909998, 4455.398521199597, 4884.5603895649565, 4984.3021976923255, 5030.698158110525, 5147.253648221062, 4191.538930717618, 4212.873613791591, 4329.848455304049, 4357.869263922087, 4363.912721745547, 1298.448159532195, 1328.4486937920656, 1342.3020029584561, 1357.8099066130749, 1363.863613638648, 921.5255591054394, 959.4962644611635, 1015.0357160697367, 1071.0842229893485, 1084.9403282819521, 6020.4280593343565, 6204.937950620526, 6250.343713910065, 6314.842128946813, 6393.136926514954, 497.8008796481436, 503.9014947414598, 504.5156649616336, 508.7583397885975, 512.5438359859728, 4327.956720432819, 4395.296752519582, 4412.448443944507, 4419.889502762392, 4539.02410981634, 11318.0, 11547.711307217387, 11614.419279036014, 11695.12286578493, 11721.472533736003, 11379.317240965569, 11488.540458381738, 11515.481803318871, 11585.652430426931, 11627.53489860396, 8556.058833528183, 8651.178138076873, 8702.738917832476, 8783.868241976375, 8830.955845220733, 6232.750689972531, 6337.04944258365, 6359.300476947625, 6363.459105976044, 6504.6747662615735, 10441.0, 10524.631637892944, 10540.631077912236, 10545.683629491128, 10625.521851516545, 10728.570857165625, 10732.892671073188, 10735.516901739284, 10780.730481738019, 10915.672529824118, 7152.427805775629, 8162.6734930602115, 8173.713920012771, 8211.055728591049, 8290.46112002718, 9098.772530686785, 9109.18017378439, 9141.222996045144, 9176.632934682804, 9196.58615362297, 8182.67269309221, 8186.549739764132, 8254.463459875089, 8282.917170828214, 8288.50268983863, 7645.5794931279315, 8018.4787988780545, 8035.67857285702, 8075.919240807517, 8265.049519305358, 7271.636418179176, 7280.9271907280245, 7320.1215854097845, 7456.254374562501, 7561.054868141482, 4993.675411098267, 5058.215976523645, 5500.917486237629, 5518.944810551843, 5600.971995139998, 5706.3152421709665, 5748.367679555162, 5751.252337196138, 5759.337676167294, 5782.3639399666845], "value": 4820.44055533097, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.3798115317042, 354.60992907801403, 355.2628296285207, 357.4459587639159, 359.4249201277956, 5789.826305210845, 5845.210896528967, 5862.794802181925, 5866.73599688014, 5869.941300586993, 5748.655080695161, 5751.741171647286, 5759.918443632658, 5781.797637082703, 5783.855403614912, 4319.913601727972, 4487.596116349528, 4529.297958816373, 4531.252343363341, 4542.613877820373, 4500.662450316239, 4513.142502924434, 4530.640807757676, 4536.64094670771, 4541.682082254243, 4471.9776401118115, 4498.730076195422, 4507.625174321847, 4513.480949690795, 4534.523874993128, 4425.513193548707, 4450.332450132473, 4475.172093162775, 4480.8879778005485, 4495.303227999665, 3936.2521120987208, 3951.214393852356, 3965.722399432041, 3967.4247234150885, 3976.363781794928, 5610.4670056344585, 5682.318121825366, 5682.659040457585, 5690.60165788395, 5712.65724056558, 5027.824026159067, 5131.589472842164, 5177.767000484987, 5201.297824793643, 5218.895622087575, 5973.402659734035, 6011.158437818709, 6065.666388348639, 6083.6045657032155, 6094.207752992127, 4277.310462367379, 4309.147066761291, 4365.738055716667, 4367.54140815213, 4368.60682538573, 5959.255093113361, 6148.8770224595255, 6232.34560371159, 6254.374562543755, 6307.905381419282, 4343.891402714894, 4361.629261512743, 4373.650107991353, 4375.453068366454, 4382.255016647177, 4424.6902716810155, 4451.933221001687, 4459.866204013885, 4467.396901418267, 4487.41663583732, 4895.363602731622, 5016.44819069902, 5117.360329958755, 5125.077486052472, 5137.434882163034, 5986.6707331097205, 6037.154798328237, 6043.667598282049, 6049.879002419927, 6056.212692350055, 1524.521214422031, 1549.977015170005, 1555.0902721907585, 1560.8048993875766, 1565.3973220310263, 939.3189937295406, 994.9751755691649, 1005.1104772276442, 1005.9597616095274, 1010.9090181883665, 5189.558887494605, 5263.447228094371, 5287.598786321628, 5292.782659988225, 5318.680879147263, 5463.399026107125, 5488.0, 5516.420775818481, 5538.723063846792, 5557.527610153102, 4966.726830024311, 5252.763625636855, 5258.132408152612, 5273.1299335609965, 5316.175992721134, 2938.4563855686765, 2974.9553756693667, 2975.925601859971, 2979.0636086543486, 2998.8050776699447, 5582.8325150245555, 5647.548196144298, 5732.3407808101765, 5773.8267851963665, 5775.48020678141, 5924.4667979882015, 6398.5521013528605, 6426.260979987362, 6453.612783233021, 6468.902966455507, 6508.576942498722, 6517.837054073686, 6545.836354091186, 6556.803295901035, 6579.374959378852, 6118.571699981137, 6227.688615569113, 6290.716917738621, 6314.89489339373, 6324.873502530016, 6243.594166379171, 6245.250569931637, 6268.6179251884, 6272.874542509216, 6277.96861015692, 6238.906416403848, 6287.145292138738, 6519.445847102859, 6533.836654083686, 6567.868642627216, 4445.799939002688, 4473.5526447355005, 4482.394876691605, 4490.348899409502, 4500.054988452462, 6135.6932153393045, 6221.844453888689, 6265.780697675559, 6323.905141422791, 6327.810165695037, 6094.146819445193, 6098.481629061579, 6101.206843110367, 6109.20580324555, 6126.172966649446, 5938.910916336172, 6115.235595550556, 6133.3866613338305, 6154.415145638932, 6163.691815409301, 6042.969785151046, 6068.393160683896, 6074.939250607437, 6078.574499784972, 6091.299500557493, 6104.511639068953, 6123.326434092335, 6165.599236049642, 6178.104174894705, 6184.350643182401, 5764.279465066867, 5954.672493012753, 5961.0, 5982.0, 6004.0], "value": 4558.402930549074, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [753.1677496366515, 779.9376049916008, 794.5113755040649, 817.5830326533467, 834.8539005674005, 15929.44246951357, 17284.654306913853, 17755.55611109723, 17765.289388424448, 17783.46649600511, 14643.19462429566, 14692.191929443876, 14692.853071469282, 14748.926255368735, 14826.555203343916, 736.4513437489052, 860.1957170046012, 879.1560102301765, 905.3571963905634, 910.4400793512009, 885.8582626779717, 890.3278025091047, 895.1764376773351, 897.4121950122681, 898.5103118800237, 950.3015283766426, 956.1920177450044, 957.3681370295607, 959.3140904253457, 959.6689142245914, 945.9337846350758, 948.0945696859483, 948.9383190092622, 952.4190243951219, 953.208836665566, 698.8043347862607, 699.5977313044968, 700.1633048507036, 700.6706847781535, 703.0157779109237, 15962.122083285414, 15982.881198316123, 15984.04095754247, 16011.119388433632, 16041.117738524377, 16300.266488008068, 16760.83239167605, 16954.406595769087, 17302.577639130533, 17808.10959452022, 12927.418266178045, 13036.934815325958, 13074.346282685829, 13135.605931822061, 13163.53927612529, 4092.9386059209137, 4110.116324990112, 4138.151678905817, 4140.710150289481, 4201.70588058836, 88205.0, 89033.55483222542, 89377.0, 89395.55302223447, 89436.0, 12740.4266807992, 12921.935390322988, 13016.934915325362, 13049.151805132637, 13327.866721332663, 19029.429117126507, 20150.093245803975, 20196.0, 20876.686849697267, 26201.475970480864, 9306.302027347841, 9359.29805264608, 9391.530423478798, 9551.331406801457, 9592.184664303611, 4873.707577545288, 5126.307948426989, 5138.537531622172, 5172.7413629319135, 5192.818251361185, 4032.9798351008644, 4175.749455032707, 4176.14389050244, 4188.85339013133, 4195.4336164618, 662.1160750398176, 720.4632548751214, 739.9778006659809, 753.5365750063781, 758.2758465665277, 776.3556248313886, 778.8091917480141, 783.6434422337823, 787.0751866556773, 795.7732046366758, 10325.277230593936, 10759.354438733544, 10945.23383363157, 11229.775404491864, 11562.595309164139, 642.7332656947408, 733.3033618062861, 779.9805004874923, 803.1005274093053, 807.6284908941952, 5025.195968645073, 5355.625106242604, 5376.758045887945, 5390.541803946709, 5404.729763511808, 74003.62998185083, 92099.07900920905, 118886.0, 132789.0, 141003.0, 116373.0, 116851.0, 117811.41094294644, 119899.0, 120376.3981180106, 96093.0, 102781.0, 102836.48581757043, 104335.4783226079, 104501.47749261204, 9709.757256068373, 9957.402555846673, 10075.596976120873, 10098.697039088838, 10148.74628134273, 58378.7081064592, 59755.402445976724, 60248.0, 61844.69077654583, 62041.37958620356, 64902.67548662226, 67280.66359668366, 70426.0, 71077.64461177868, 78379.0, 14513.201773902114, 14775.261236938326, 14794.77807832905, 14903.552893413214, 14998.175100369584, 16147.596310092342, 16656.250468728693, 16767.1785450405, 16881.493555193363, 17252.309907603554, 14745.0, 15295.85281103944, 15756.527304180892, 15774.9211253943, 15860.52418427449, 15210.0, 15292.158931258442, 15455.459058932884, 15680.607984800472, 15709.293081811575, 14731.631709206928, 14860.0, 14866.03370780939, 15459.149746764037, 15856.286467108777, 12410.5656302029, 12496.25022498653, 12563.371831408576, 12566.115887182026, 13326.666833329244, 11756.58851940178, 11954.880451195724, 12988.285644289655, 13104.475820967054, 13356.599302020955], "value": 8347.566510163766, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1499.4999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 111.0, 111.0, 105.0, 111.0, 112.0, 111.0, 113.0, 112.0, 120.0], "value": 110.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 116.0, 139.0, 173.0, 117.0, 147.0, 145.0, 126.0, 167.0, 120.0], "value": 139.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 164.0, 162.0, 165.0, 165.0, 162.0, 165.0, 163.0, 161.0, 164.0], "value": 163.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 74.0, 173.0, 75.0, 75.0, 75.0, 75.0, 170.0, 76.0], "value": 94.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 144.0, 84.0, 84.0, 83.0, 134.0, 83.0, 83.0, 138.0], "value": 100.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 118.0, 118.0, 118.0, 118.0, 119.0], "value": 117.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 148.0, 148.0, 148.0, 148.0, 146.0, 148.0, 146.0, 147.0], "value": 147.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 96.0, 101.0, 95.0, 95.0, 96.0, 96.0, 95.0, 102.0, 96.0], "value": 96.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 51.0, 51.0, 59.0, 51.0, 51.0, 52.0, 52.0, 51.0, 53.0], "value": 52.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 62.0, 60.0, 61.0, 60.0, 61.0, 60.0, 60.0, 60.0], "value": 60.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 80.0, 72.0, 79.0, 72.0, 72.0, 72.0, 77.0, 72.0, 72.0], "value": 74.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 117.0, 128.0, 116.0, 116.0, 127.0, 137.0, 120.0, 157.0, 126.0], "value": 126.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 155.0, 153.0, 158.0, 150.0, 150.0, 153.0, 150.0, 149.0, 154.0], "value": 153.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 62.0, 62.0, 67.0, 62.0, 62.0, 63.0, 63.0, 61.0, 64.0], "value": 63.2, "unit": "score"}]}]} 05:40:30 INFO - Return code: 0 05:40:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:40:30 ERROR - # TBPL SUCCESS # 05:40:30 INFO - Running post-action listener: _resource_record_post_action 05:40:30 INFO - [mozharness: 2016-09-12 12:40:30.794669Z] Finished run-tests step (success) 05:40:30 INFO - Running post-run listener: _resource_record_post_run 05:40:30 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 266240; Write bytes: 327942144; Read time: 28; Write time: 375108 05:40:30 INFO - TinderboxPrint: CPU usage
13.3% 05:40:30 INFO - TinderboxPrint: I/O read bytes / time
266,240 / 28 05:40:30 INFO - TinderboxPrint: I/O write bytes / time
327,942,144 / 375,108 05:40:30 INFO - TinderboxPrint: CPU idle
8,221.2 (86.7%) 05:40:30 INFO - TinderboxPrint: CPU user
1,200.6 (12.7%) 05:40:30 INFO - TinderboxPrint: Swap in / out
0 / 0 05:40:30 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 78843904; Read time: 0; Write time: 177704 05:40:30 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 262144; Write bytes: 248508416; Read time: 16; Write time: 197404 05:40:31 INFO - Running post-run listener: _upload_blobber_files 05:40:31 INFO - Blob upload gear active. 05:40:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:40:31 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 05:40:31 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'] 05:40:31 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 05:40:31 INFO - (blobuploader) - INFO - Open directory for files ... 05:40:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:40:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:40:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:40:36 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:40:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:40:36 INFO - (blobuploader) - INFO - Done attempting. 05:40:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:40:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:40:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:40:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:40:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:40:37 INFO - (blobuploader) - INFO - Done attempting. 05:40:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:40:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:40:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:40:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:40:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:40:38 INFO - (blobuploader) - INFO - Done attempting. 05:40:38 INFO - (blobuploader) - INFO - Iteration through files over. 05:40:38 INFO - Return code: 0 05:40:38 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:40:38 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:40:38 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7f8fd70863720065d4a4eb3028343399a16518ef985af6830b7ec8aee8f29f6635f306eed719458eb870bbb0833255f6c883a26279edab4424d773ae5831d421", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/95efc54a41838cb2ed0c2982d7b82a17dfd755b15b323312942dc15a5acd128e437d566aaed274792f8453e507e601c5f58891f8607c64855e2698bc325b2e8c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c220b853116e645723ad7ff9d0d95db2130154928856e51aee1a0ba056acd37dd1b5f62af084719db7a3e15c4690155e1edb58c54b739ba871a176fd374bedc5"} 05:40:38 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:40:38 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:40:38 INFO - Contents: 05:40:38 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7f8fd70863720065d4a4eb3028343399a16518ef985af6830b7ec8aee8f29f6635f306eed719458eb870bbb0833255f6c883a26279edab4424d773ae5831d421", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/95efc54a41838cb2ed0c2982d7b82a17dfd755b15b323312942dc15a5acd128e437d566aaed274792f8453e507e601c5f58891f8607c64855e2698bc325b2e8c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c220b853116e645723ad7ff9d0d95db2130154928856e51aee1a0ba056acd37dd1b5f62af084719db7a3e15c4690155e1edb58c54b739ba871a176fd374bedc5"} 05:40:38 INFO - Running post-run listener: copy_logs_to_upload_dir 05:40:38 INFO - Copying logs to upload dir... 05:40:38 INFO - mkdir: /builds/slave/test/build/upload/logs 05:40:38 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1678.949055 ========= master_lag: 0.12 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 59 secs) (at 2016-09-12 05:40:37.622682) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-12 05:40:37.630430) ========= 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=32c5991c409366f7fec8a718000001d2-1473669411.660316-970082928 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/7f8fd70863720065d4a4eb3028343399a16518ef985af6830b7ec8aee8f29f6635f306eed719458eb870bbb0833255f6c883a26279edab4424d773ae5831d421", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/95efc54a41838cb2ed0c2982d7b82a17dfd755b15b323312942dc15a5acd128e437d566aaed274792f8453e507e601c5f58891f8607c64855e2698bc325b2e8c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c220b853116e645723ad7ff9d0d95db2130154928856e51aee1a0ba056acd37dd1b5f62af084719db7a3e15c4690155e1edb58c54b739ba871a176fd374bedc5"} build_url:https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011295 build_url: 'https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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/7f8fd70863720065d4a4eb3028343399a16518ef985af6830b7ec8aee8f29f6635f306eed719458eb870bbb0833255f6c883a26279edab4424d773ae5831d421", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/95efc54a41838cb2ed0c2982d7b82a17dfd755b15b323312942dc15a5acd128e437d566aaed274792f8453e507e601c5f58891f8607c64855e2698bc325b2e8c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c220b853116e645723ad7ff9d0d95db2130154928856e51aee1a0ba056acd37dd1b5f62af084719db7a3e15c4690155e1edb58c54b739ba871a176fd374bedc5"}' ========= master_lag: 0.06 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-12 05:40:37.706061) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 05:40:37.706488) ========= 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=32c5991c409366f7fec8a718000001d2-1473669411.660316-970082928 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004429 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 05:40:37.730304) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-12 05:40:37.730640) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-12 05:40:37.734002) ========= ========= Total master_lag: 0.28 =========