builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-040 starttime: 1473682356.73 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.730599) ========= 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.731087) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-12 05:12:36.731393) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-12 05:12:37.132496) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 05:12:37.132816) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1473669467.785587-1039223074 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-12 05:12:37-- 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% 216M=0s 2016-09-12 05:12:37 (216 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.110553 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 05:12:37.308317) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 05:12:37.308649) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1473669467.785587-1039223074 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014972 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 05:12:37.371188) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-12 05:12:37.371541) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1473669467.785587-1039223074 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-12 05:12:38,148 truncating revision to first 12 chars 2016-09-12 05:12:38,148 Setting DEBUG logging. 2016-09-12 05:12:38,148 attempt 1/10 2016-09-12 05:12:38,148 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,359 unpacking tar archive at: mozilla-aurora-8663d41e015f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.576570 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-12 05:12:38.965168) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-12 05:12:38.965454) ========= script_repo_revision: 8663d41e015f3c9933a08a65c8349b86f2263354 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-12 05:12:38.965819) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-12 05:12:38.966095) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-12 05:12:38.976682) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 2 secs) (at 2016-09-12 05:12:38.977010) ========= /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=9e0dfe3369c33a1556e63f89000001d8-1473669467.785587-1039223074 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-040', 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.837425Z] 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.296008Z] Finished clobber step (success) 05:12:40 INFO - [mozharness: 2016-09-12 12:12:40.296110Z] 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": 495, 05:12:40 INFO - "slavename": "talos-linux64-ix-040", 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.298567Z] Finished read-buildbot-config step (success) 05:12:40 INFO - [mozharness: 2016-09-12 12:12:40.298656Z] 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:10 INFO - Downloaded 1361 bytes. 05:14:10 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 05:14:10 INFO - Using the following test package requirements: 05:14:10 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 05:14:10 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 05:14:10 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 05:14:10 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 05:14:10 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 05:14:10 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 05:14:10 INFO - u'jsshell-linux-x86_64.zip'], 05:14:10 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 05:14:10 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 05:14:10 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 05:14:10 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 05:14:10 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 05:14:10 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 05:14:10 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 05:14:10 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 05:14:10 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 05:14:10 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 05:14:10 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 05:14:10 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:14:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:10 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:10 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:10 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:10 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:10 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: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.common.tests.zip 05:14:10 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 05:14:10 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:41 INFO - Downloaded 21770904 bytes. 05:15:41 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:41 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:15 INFO - Return code: 0 05:17:15 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:15 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:15 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:15 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:15 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:15 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:15 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:15 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 05:17:15 INFO - retry: Failed, sleeping 30 seconds before retrying 05:17:45 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:45 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:45 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 05:17:45 INFO - retry: Failed, sleeping 60 seconds before retrying 05:18:45 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:45 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:45 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 05:18:45 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:45 INFO - Caught exception: HTTP Error 404: Not Found 05:18:45 INFO - Caught exception: HTTP Error 404: Not Found 05:18:45 INFO - Caught exception: HTTP Error 404: Not Found 05:18:45 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:45 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:45 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:46 INFO - Return code: 0 05:18:46 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:46 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:46 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:46 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:46 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:46 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: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.tar.bz2 05:18:46 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 05:18:46 INFO - retry: Failed, sleeping 30 seconds before retrying 05:19:16 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: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.tar.bz2 05:19:16 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 05:19:16 INFO - retry: Failed, sleeping 60 seconds before retrying 05:20:16 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: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.tar.bz2 05:20:16 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 05:20:16 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:16 INFO - Caught exception: HTTP Error 404: Not Found 05:20:16 INFO - Caught exception: HTTP Error 404: Not Found 05:20:16 INFO - Caught exception: HTTP Error 404: Not Found 05:20:16 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:16 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:16 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:18 INFO - Downloaded 63895992 bytes. 05:20:18 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:18 INFO - mkdir: /builds/slave/test/properties 05:20:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:20:18 INFO - Writing to file /builds/slave/test/properties/build_url 05:20:18 INFO - Contents: 05:20:18 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:19 INFO - Running post-action listener: _resource_record_post_action 05:20:19 INFO - Running post-action listener: set_extra_try_arguments 05:20:19 INFO - [mozharness: 2016-09-12 12:20:19.102122Z] Finished download-and-extract step (success) 05:20:19 INFO - [mozharness: 2016-09-12 12:20:19.102356Z] Running populate-webroot step. 05:20:19 INFO - Running pre-action listener: _resource_record_pre_action 05:20:19 INFO - Running main action method: populate_webroot 05:20:19 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:20:19 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:20:19 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:20:19 INFO - '--tppagecycles', 05:20:19 INFO - '7'], 05:20:19 INFO - 'tests': ['tsvgm']}}, 05:20:19 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:20:19 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:20:19 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:20:19 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:20:19 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:20:19 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:20:19 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:19 INFO - 'talos_options': ['--disable-e10s'], 05:20:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:20:19 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:20:19 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:19 INFO - 'talos_options': ['--disable-e10s'], 05:20:19 INFO - 'tests': ['damp', 'tps']}, 05:20:19 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:19 INFO - 'tests': ['damp', 'tps']}, 05:20:19 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:20:19 INFO - 'tests': ['dromaeo_dom']}, 05:20:19 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:20:19 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:20:19 INFO - 'tests': ['basic_compositor_video']}, 05:20:19 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:20:19 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:20:19 INFO - 'tests': ['a11yr', 05:20:19 INFO - 'ts_paint', 05:20:19 INFO - 'tpaint', 05:20:19 INFO - 'sessionrestore', 05:20:19 INFO - 'sessionrestore_no_auto_restore', 05:20:19 INFO - 'tabpaint']}, 05:20:19 INFO - 'other-e10s': {'tests': ['a11yr', 05:20:19 INFO - 'ts_paint', 05:20:19 INFO - 'tpaint', 05:20:19 INFO - 'sessionrestore', 05:20:19 INFO - 'sessionrestore_no_auto_restore', 05:20:19 INFO - 'tabpaint']}, 05:20:19 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:20:19 INFO - 'ts_paint', 05:20:19 INFO - 'tpaint', 05:20:19 INFO - 'sessionrestore', 05:20:19 INFO - 'sessionrestore_no_auto_restore', 05:20:19 INFO - 'tabpaint']}, 05:20:19 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:20:19 INFO - 'ts_paint', 05:20:19 INFO - 'tpaint', 05:20:19 INFO - 'sessionrestore', 05:20:19 INFO - 'sessionrestore_no_auto_restore', 05:20:19 INFO - 'tabpaint']}, 05:20:19 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:20:19 INFO - 'tests': ['a11yr', 05:20:19 INFO - 'ts_paint', 05:20:19 INFO - 'tpaint', 05:20:19 INFO - 'sessionrestore', 05:20:19 INFO - 'sessionrestore_no_auto_restore', 05:20:19 INFO - 'tabpaint']}, 05:20:19 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:20:19 INFO - 'tests': ['a11yr', 05:20:19 INFO - 'ts_paint', 05:20:19 INFO - 'tpaint', 05:20:19 INFO - 'sessionrestore', 05:20:19 INFO - 'sessionrestore_no_auto_restore', 05:20:19 INFO - 'tabpaint']}, 05:20:19 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:20:19 INFO - 'tests': ['tsvgx', 05:20:19 INFO - 'tsvgr_opacity', 05:20:19 INFO - 'tart', 05:20:19 INFO - 'tscrollx', 05:20:19 INFO - 'cart']}, 05:20:19 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:20:19 INFO - 'tsvgr_opacity', 05:20:19 INFO - 'tart', 05:20:19 INFO - 'tscrollx', 05:20:19 INFO - 'cart']}, 05:20:19 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:19 INFO - 'talos_options': ['--disable-e10s'], 05:20:19 INFO - 'tests': ['tp5o']}, 05:20:19 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:19 INFO - 'tests': ['tp5o']}, 05:20:19 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:19 INFO - 'talos_options': ['--disable-e10s', 05:20:19 INFO - '--xperf_path', 05:20:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:20:19 INFO - 'tests': ['tp5n']}, 05:20:19 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:19 INFO - 'talos_options': ['--xperf_path', 05:20:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:20:19 INFO - 'tests': ['tp5n']}}, 05:20:19 INFO - 'talos.zip': {'path': '', 05:20:19 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:20:19 INFO - Running post-action listener: _resource_record_post_action 05:20:19 INFO - [mozharness: 2016-09-12 12:20:19.117958Z] Finished populate-webroot step (success) 05:20:19 INFO - [mozharness: 2016-09-12 12:20:19.118047Z] Running create-virtualenv step. 05:20:19 INFO - Running pre-action listener: _resource_record_pre_action 05:20:19 INFO - Running main action method: create_virtualenv 05:20:19 INFO - Creating virtualenv /builds/slave/test/build/venv 05:20:19 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:19 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:20:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:20:19 INFO - Using real prefix '/usr' 05:20:19 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:20:19 INFO - Installing distribute.............................................................................................................................................................................................done. 05:20:21 INFO - Installing pip.................done. 05:20:21 INFO - Return code: 0 05:20:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:20:21 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:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:21 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:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:21 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 0x1852030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19b58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a81400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b10570>, '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 0x1b11470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b11900>, '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': '9e0dfe3369c33a1556e63f89000001d8-1473669467.785587-1039223074', '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:21 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:21 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:21 INFO - Using env: {'DISPLAY': ':0', 05:20:21 INFO - 'HOME': '/home/cltbld', 05:20:21 INFO - 'LANG': 'en_US.UTF-8', 05:20:21 INFO - 'LANGUAGE': 'en_US:en', 05:20:21 INFO - 'LOGNAME': 'cltbld', 05:20:21 INFO - 'MAIL': '/var/mail/cltbld', 05:20:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:21 INFO - 'MOZ_NO_REMOTE': '1', 05:20:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:21 INFO - 'NO_EM_RESTART': '1', 05:20:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:21 INFO - 'PWD': '/builds/slave/test', 05:20:21 INFO - 'SHELL': '/bin/bash', 05:20:21 INFO - 'SHLVL': '1', 05:20:21 INFO - 'TERM': 'linux', 05:20:21 INFO - 'TMOUT': '86400', 05:20:21 INFO - 'USER': 'cltbld', 05:20:21 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1473669467.785587-1039223074', 05:20:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:21 INFO - '_': '/tools/buildbot/bin/python'} 05:20:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:21 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:21 INFO - Cleaning up... 05:20:21 INFO - Return code: 0 05:20:21 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:20:21 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:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:21 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:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:21 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 0x1852030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19b58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a81400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b10570>, '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 0x1b11470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b11900>, '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': '9e0dfe3369c33a1556e63f89000001d8-1473669467.785587-1039223074', '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:21 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:21 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:21 INFO - Using env: {'DISPLAY': ':0', 05:20:21 INFO - 'HOME': '/home/cltbld', 05:20:21 INFO - 'LANG': 'en_US.UTF-8', 05:20:21 INFO - 'LANGUAGE': 'en_US:en', 05:20:21 INFO - 'LOGNAME': 'cltbld', 05:20:21 INFO - 'MAIL': '/var/mail/cltbld', 05:20:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:21 INFO - 'MOZ_NO_REMOTE': '1', 05:20:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:21 INFO - 'NO_EM_RESTART': '1', 05:20:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:21 INFO - 'PWD': '/builds/slave/test', 05:20:21 INFO - 'SHELL': '/bin/bash', 05:20:21 INFO - 'SHLVL': '1', 05:20:21 INFO - 'TERM': 'linux', 05:20:21 INFO - 'TMOUT': '86400', 05:20:21 INFO - 'USER': 'cltbld', 05:20:21 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1473669467.785587-1039223074', 05:20:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:21 INFO - '_': '/tools/buildbot/bin/python'} 05:20:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:22 INFO - Downloading/unpacking psutil>=3.1.1 05:20:22 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:22 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:22 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:22 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:22 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:22 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:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:20:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:20:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:20:24 INFO - Installing collected packages: psutil 05:20:24 INFO - Running setup.py install for psutil 05:20:24 INFO - building 'psutil._psutil_linux' extension 05:20:24 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:24 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:24 INFO - building 'psutil._psutil_posix' extension 05:20:24 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:24 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:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:20:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:20:24 INFO - Successfully installed psutil 05:20:24 INFO - Cleaning up... 05:20:24 INFO - Return code: 0 05:20:24 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:20:24 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:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:24 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:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:24 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 0x1852030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19b58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a81400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b10570>, '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 0x1b11470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b11900>, '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': '9e0dfe3369c33a1556e63f89000001d8-1473669467.785587-1039223074', '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:24 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:24 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:24 INFO - Using env: {'DISPLAY': ':0', 05:20:24 INFO - 'HOME': '/home/cltbld', 05:20:24 INFO - 'LANG': 'en_US.UTF-8', 05:20:24 INFO - 'LANGUAGE': 'en_US:en', 05:20:24 INFO - 'LOGNAME': 'cltbld', 05:20:24 INFO - 'MAIL': '/var/mail/cltbld', 05:20:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:24 INFO - 'MOZ_NO_REMOTE': '1', 05:20:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:24 INFO - 'NO_EM_RESTART': '1', 05:20:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:24 INFO - 'PWD': '/builds/slave/test', 05:20:24 INFO - 'SHELL': '/bin/bash', 05:20:24 INFO - 'SHLVL': '1', 05:20:24 INFO - 'TERM': 'linux', 05:20:24 INFO - 'TMOUT': '86400', 05:20:24 INFO - 'USER': 'cltbld', 05:20:24 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1473669467.785587-1039223074', 05:20:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:24 INFO - '_': '/tools/buildbot/bin/python'} 05:20:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:24 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:20:24 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:24 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:24 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:24 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:24 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:24 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:26 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:20:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:20:26 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:26 INFO - Installing collected packages: mozsystemmonitor 05:20:26 INFO - Running setup.py install for mozsystemmonitor 05:20:26 INFO - Successfully installed mozsystemmonitor 05:20:26 INFO - Cleaning up... 05:20:26 INFO - Return code: 0 05:20:26 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:20:26 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:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:26 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:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:26 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 0x1852030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19b58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a81400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b10570>, '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 0x1b11470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b11900>, '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': '9e0dfe3369c33a1556e63f89000001d8-1473669467.785587-1039223074', '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:26 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:26 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:26 INFO - Using env: {'DISPLAY': ':0', 05:20:26 INFO - 'HOME': '/home/cltbld', 05:20:26 INFO - 'LANG': 'en_US.UTF-8', 05:20:26 INFO - 'LANGUAGE': 'en_US:en', 05:20:26 INFO - 'LOGNAME': 'cltbld', 05:20:26 INFO - 'MAIL': '/var/mail/cltbld', 05:20:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:26 INFO - 'MOZ_NO_REMOTE': '1', 05:20:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:26 INFO - 'NO_EM_RESTART': '1', 05:20:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:26 INFO - 'PWD': '/builds/slave/test', 05:20:26 INFO - 'SHELL': '/bin/bash', 05:20:26 INFO - 'SHLVL': '1', 05:20:26 INFO - 'TERM': 'linux', 05:20:26 INFO - 'TMOUT': '86400', 05:20:26 INFO - 'USER': 'cltbld', 05:20:26 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1473669467.785587-1039223074', 05:20:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:26 INFO - '_': '/tools/buildbot/bin/python'} 05:20:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:27 INFO - Downloading/unpacking jsonschema==2.5.1 05:20:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:20:29 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:20:29 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:29 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:29 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:29 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:29 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:29 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:29 INFO - Downloading functools32-3.2.3-2.tar.gz 05:20:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:20:29 INFO - warning: no files found matching '*.txt' 05:20:29 INFO - no previously-included directories found matching 'build' 05:20:29 INFO - no previously-included directories found matching 'dist' 05:20:29 INFO - no previously-included directories found matching '.git*' 05:20:29 INFO - Installing collected packages: jsonschema, functools32 05:20:29 INFO - Running setup.py install for functools32 05:20:29 INFO - warning: no files found matching '*.txt' 05:20:29 INFO - no previously-included directories found matching 'build' 05:20:29 INFO - no previously-included directories found matching 'dist' 05:20:29 INFO - no previously-included directories found matching '.git*' 05:20:29 INFO - Successfully installed jsonschema functools32 05:20:29 INFO - Cleaning up... 05:20:29 INFO - Return code: 0 05:20:29 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:20:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1852030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19b58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a81400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b10570>, '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 0x1b11470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b11900>, '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': '9e0dfe3369c33a1556e63f89000001d8-1473669467.785587-1039223074', '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:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:20:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:20:29 INFO - Using env: {'DISPLAY': ':0', 05:20:29 INFO - 'HOME': '/home/cltbld', 05:20:29 INFO - 'LANG': 'en_US.UTF-8', 05:20:29 INFO - 'LANGUAGE': 'en_US:en', 05:20:29 INFO - 'LOGNAME': 'cltbld', 05:20:29 INFO - 'MAIL': '/var/mail/cltbld', 05:20:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:29 INFO - 'MOZ_NO_REMOTE': '1', 05:20:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:29 INFO - 'NO_EM_RESTART': '1', 05:20:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:29 INFO - 'PWD': '/builds/slave/test', 05:20:29 INFO - 'SHELL': '/bin/bash', 05:20:29 INFO - 'SHLVL': '1', 05:20:29 INFO - 'TERM': 'linux', 05:20:29 INFO - 'TMOUT': '86400', 05:20:29 INFO - 'USER': 'cltbld', 05:20:29 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1473669467.785587-1039223074', 05:20:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:29 INFO - '_': '/tools/buildbot/bin/python'} 05:20:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:29 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:20:29 INFO - Cleaning up... 05:20:29 INFO - Return code: 0 05:20:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:20:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1852030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19b58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a81400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b10570>, '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 0x1b11470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b11900>, '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': '9e0dfe3369c33a1556e63f89000001d8-1473669467.785587-1039223074', '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:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:20:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:20:29 INFO - Using env: {'DISPLAY': ':0', 05:20:29 INFO - 'HOME': '/home/cltbld', 05:20:29 INFO - 'LANG': 'en_US.UTF-8', 05:20:29 INFO - 'LANGUAGE': 'en_US:en', 05:20:29 INFO - 'LOGNAME': 'cltbld', 05:20:29 INFO - 'MAIL': '/var/mail/cltbld', 05:20:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:29 INFO - 'MOZ_NO_REMOTE': '1', 05:20:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:29 INFO - 'NO_EM_RESTART': '1', 05:20:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:29 INFO - 'PWD': '/builds/slave/test', 05:20:29 INFO - 'SHELL': '/bin/bash', 05:20:29 INFO - 'SHLVL': '1', 05:20:29 INFO - 'TERM': 'linux', 05:20:29 INFO - 'TMOUT': '86400', 05:20:29 INFO - 'USER': 'cltbld', 05:20:29 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1473669467.785587-1039223074', 05:20:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:29 INFO - '_': '/tools/buildbot/bin/python'} 05:20:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:29 INFO - Downloading/unpacking blobuploader==1.2.4 05:20:29 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:29 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:29 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:29 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:29 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:29 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:31 INFO - Downloading blobuploader-1.2.4.tar.gz 05:20:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:20:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:20:31 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:31 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:31 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:31 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:31 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:31 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 - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:20:32 INFO - Downloading/unpacking docopt==0.6.1 (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:32 INFO - Downloading docopt-0.6.1.tar.gz 05:20:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:20:32 INFO - Installing collected packages: blobuploader, requests, docopt 05:20:32 INFO - Running setup.py install for blobuploader 05:20:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:20:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:20:32 INFO - Running setup.py install for requests 05:20:33 INFO - Running setup.py install for docopt 05:20:33 INFO - Successfully installed blobuploader requests docopt 05:20:33 INFO - Cleaning up... 05:20:33 INFO - Return code: 0 05:20:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:20:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:33 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 0x1852030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19b58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a81400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b10570>, '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 0x1b11470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b11900>, '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': '9e0dfe3369c33a1556e63f89000001d8-1473669467.785587-1039223074', '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:33 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:33 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:33 INFO - Using env: {'DISPLAY': ':0', 05:20:33 INFO - 'HOME': '/home/cltbld', 05:20:33 INFO - 'LANG': 'en_US.UTF-8', 05:20:33 INFO - 'LANGUAGE': 'en_US:en', 05:20:33 INFO - 'LOGNAME': 'cltbld', 05:20:33 INFO - 'MAIL': '/var/mail/cltbld', 05:20:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:33 INFO - 'MOZ_NO_REMOTE': '1', 05:20:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:33 INFO - 'NO_EM_RESTART': '1', 05:20:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:33 INFO - 'PWD': '/builds/slave/test', 05:20:33 INFO - 'SHELL': '/bin/bash', 05:20:33 INFO - 'SHLVL': '1', 05:20:33 INFO - 'TERM': 'linux', 05:20:33 INFO - 'TMOUT': '86400', 05:20:33 INFO - 'USER': 'cltbld', 05:20:33 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1473669467.785587-1039223074', 05:20:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:33 INFO - '_': '/tools/buildbot/bin/python'} 05:20:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:20:33 INFO - Running setup.py (path:/tmp/pip-lV0p4K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:20:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:20:33 INFO - Running setup.py (path:/tmp/pip-eQZNuP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:20:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:20:33 INFO - Running setup.py (path:/tmp/pip-WhBvrk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:20:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:20:33 INFO - Running setup.py (path:/tmp/pip-XsP9N6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:20:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:20:33 INFO - Running setup.py (path:/tmp/pip-008XuV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:20:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:20:33 INFO - Running setup.py (path:/tmp/pip-Q6a7wx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:20:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:20:33 INFO - Running setup.py (path:/tmp/pip-BlT2nf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:20:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:20:33 INFO - Running setup.py (path:/tmp/pip-tdj4zy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:20:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:20:33 INFO - Running setup.py (path:/tmp/pip-8urdM_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:20:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:20:34 INFO - Running setup.py (path:/tmp/pip-7VEN1a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:20:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:20:34 INFO - Running setup.py (path:/tmp/pip-pRoytB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:20:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:20:34 INFO - Running setup.py (path:/tmp/pip-Pe44CH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:20:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:20:34 INFO - Running setup.py (path:/tmp/pip-UZQ5S7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:20:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:20:34 INFO - Running setup.py (path:/tmp/pip-k037bb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:20:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:20:34 INFO - Running setup.py (path:/tmp/pip-_fF1bT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:20:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:20:34 INFO - Running setup.py (path:/tmp/pip-NdZaqR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:20:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:20:34 INFO - Running setup.py (path:/tmp/pip-0r_8LB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:20:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:20:34 INFO - Running setup.py install for manifestparser 05:20:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:20:34 INFO - Running setup.py install for mozcrash 05:20:34 INFO - Running setup.py install for mozdebug 05:20:34 INFO - Running setup.py install for mozdevice 05:20:35 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:20:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:20:35 INFO - Running setup.py install for mozfile 05:20:35 INFO - Running setup.py install for mozhttpd 05:20:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:20:35 INFO - Running setup.py install for mozinfo 05:20:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:20:35 INFO - Running setup.py install for mozInstall 05:20:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:20:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:20:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:20:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:20:35 INFO - Running setup.py install for mozleak 05:20:35 INFO - Running setup.py install for mozlog 05:20:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:20:35 INFO - Running setup.py install for moznetwork 05:20:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:20:35 INFO - Running setup.py install for mozprocess 05:20:35 INFO - Running setup.py install for mozprofile 05:20:35 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:20:35 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:20:35 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:20:35 INFO - Running setup.py install for mozrunner 05:20:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:20:36 INFO - Running setup.py install for mozscreenshot 05:20:36 INFO - Running setup.py install for moztest 05:20:36 INFO - Running setup.py install for mozversion 05:20:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:20:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:20:36 INFO - Cleaning up... 05:20:36 INFO - Return code: 0 05:20:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:20:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:36 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 0x1852030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19b58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a81400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b10570>, '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 0x1b11470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b11900>, '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': '9e0dfe3369c33a1556e63f89000001d8-1473669467.785587-1039223074', '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:36 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:36 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:36 INFO - Using env: {'DISPLAY': ':0', 05:20:36 INFO - 'HOME': '/home/cltbld', 05:20:36 INFO - 'LANG': 'en_US.UTF-8', 05:20:36 INFO - 'LANGUAGE': 'en_US:en', 05:20:36 INFO - 'LOGNAME': 'cltbld', 05:20:36 INFO - 'MAIL': '/var/mail/cltbld', 05:20:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:36 INFO - 'MOZ_NO_REMOTE': '1', 05:20:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:36 INFO - 'NO_EM_RESTART': '1', 05:20:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:36 INFO - 'PWD': '/builds/slave/test', 05:20:36 INFO - 'SHELL': '/bin/bash', 05:20:36 INFO - 'SHLVL': '1', 05:20:36 INFO - 'TERM': 'linux', 05:20:36 INFO - 'TMOUT': '86400', 05:20:36 INFO - 'USER': 'cltbld', 05:20:36 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1473669467.785587-1039223074', 05:20:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:36 INFO - '_': '/tools/buildbot/bin/python'} 05:20:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:20:36 INFO - Running setup.py (path:/tmp/pip-b6nlw8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:20:36 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:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:20:36 INFO - Running setup.py (path:/tmp/pip-iEo0Ye-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:20:36 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:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:20:36 INFO - Running setup.py (path:/tmp/pip-uQB74i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:20:36 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:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:20:36 INFO - Running setup.py (path:/tmp/pip-lO0dcb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:20:36 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:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:20:36 INFO - Running setup.py (path:/tmp/pip-FWsgbD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:20:36 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:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:20:36 INFO - Running setup.py (path:/tmp/pip-RAIH2z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:20:37 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:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:20:37 INFO - Running setup.py (path:/tmp/pip-FG9cei-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:20:37 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:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:20:37 INFO - Running setup.py (path:/tmp/pip-Lf0DEo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:20:37 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:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:20:37 INFO - Running setup.py (path:/tmp/pip-xMcBD6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:20:37 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:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:20:37 INFO - Running setup.py (path:/tmp/pip-LE4UAC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:20:37 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:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:20:37 INFO - Running setup.py (path:/tmp/pip-nFDxuN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:20:37 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:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:20:37 INFO - Running setup.py (path:/tmp/pip-AyklGR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:20:37 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:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:20:37 INFO - Running setup.py (path:/tmp/pip-5LUnNI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:20:37 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:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:20:37 INFO - Running setup.py (path:/tmp/pip-1YvnhL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:20:37 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:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:20:37 INFO - Running setup.py (path:/tmp/pip-PrIipL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:20:37 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:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:20:37 INFO - Running setup.py (path:/tmp/pip-lOxQtd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:20:37 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:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:20:37 INFO - Running setup.py (path:/tmp/pip-LjJaDO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:20:37 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:37 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:37 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:37 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:37 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:37 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:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:20:37 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:37 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:37 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:37 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:37 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:37 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:39 INFO - Downloading blessings-1.6.tar.gz 05:20:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:20:39 INFO - Installing collected packages: blessings 05:20:39 INFO - Running setup.py install for blessings 05:20:39 INFO - Successfully installed blessings 05:20:39 INFO - Cleaning up... 05:20:40 INFO - Return code: 0 05:20:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:20:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:20:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:20:40 INFO - Reading from file tmpfile_stdout 05:20:40 INFO - Current package versions: 05:20:40 INFO - argparse == 1.2.1 05:20:40 INFO - blessings == 1.6 05:20:40 INFO - blobuploader == 1.2.4 05:20:40 INFO - docopt == 0.6.1 05:20:40 INFO - functools32 == 3.2.3-2 05:20:40 INFO - jsonschema == 2.5.1 05:20:40 INFO - manifestparser == 1.1 05:20:40 INFO - mozInstall == 1.12 05:20:40 INFO - mozcrash == 0.17 05:20:40 INFO - mozdebug == 0.1 05:20:40 INFO - mozdevice == 0.48 05:20:40 INFO - mozfile == 1.2 05:20:40 INFO - mozhttpd == 0.7 05:20:40 INFO - mozinfo == 0.9 05:20:40 INFO - mozleak == 0.1 05:20:40 INFO - mozlog == 3.3 05:20:40 INFO - moznetwork == 0.27 05:20:40 INFO - mozprocess == 0.23 05:20:40 INFO - mozprofile == 0.28 05:20:40 INFO - mozrunner == 6.12 05:20:40 INFO - mozscreenshot == 0.1 05:20:40 INFO - mozsystemmonitor == 0.3 05:20:40 INFO - moztest == 0.7 05:20:40 INFO - mozversion == 1.4 05:20:40 INFO - psutil == 3.1.1 05:20:40 INFO - requests == 1.2.3 05:20:40 INFO - wsgiref == 0.1.2 05:20:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:20: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:20:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20: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:20:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:40 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 0x1852030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19b58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a81400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b10570>, '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 0x1b11470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b11900>, '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': '9e0dfe3369c33a1556e63f89000001d8-1473669467.785587-1039223074', '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:40 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:40 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:40 INFO - Using env: {'DISPLAY': ':0', 05:20:40 INFO - 'HOME': '/home/cltbld', 05:20:40 INFO - 'LANG': 'en_US.UTF-8', 05:20:40 INFO - 'LANGUAGE': 'en_US:en', 05:20:40 INFO - 'LOGNAME': 'cltbld', 05:20:40 INFO - 'MAIL': '/var/mail/cltbld', 05:20:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:40 INFO - 'MOZ_NO_REMOTE': '1', 05:20:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:40 INFO - 'NO_EM_RESTART': '1', 05:20:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:40 INFO - 'PWD': '/builds/slave/test', 05:20:40 INFO - 'SHELL': '/bin/bash', 05:20:40 INFO - 'SHLVL': '1', 05:20:40 INFO - 'TERM': 'linux', 05:20:40 INFO - 'TMOUT': '86400', 05:20:40 INFO - 'USER': 'cltbld', 05:20:40 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1473669467.785587-1039223074', 05:20:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:40 INFO - '_': '/tools/buildbot/bin/python'} 05:20:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:40 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:40 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:40 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:40 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:40 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:40 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:40 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:40 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:40 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:40 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:40 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:40 INFO - Cleaning up... 05:20:40 INFO - Return code: 0 05:20:40 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:20: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:20:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20: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:20:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:40 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 0x1852030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19b58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a81400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b10570>, '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 0x1b11470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b11900>, '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': '9e0dfe3369c33a1556e63f89000001d8-1473669467.785587-1039223074', '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:40 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:40 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:40 INFO - Using env: {'DISPLAY': ':0', 05:20:40 INFO - 'HOME': '/home/cltbld', 05:20:40 INFO - 'LANG': 'en_US.UTF-8', 05:20:40 INFO - 'LANGUAGE': 'en_US:en', 05:20:40 INFO - 'LOGNAME': 'cltbld', 05:20:40 INFO - 'MAIL': '/var/mail/cltbld', 05:20:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:40 INFO - 'MOZ_NO_REMOTE': '1', 05:20:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:40 INFO - 'NO_EM_RESTART': '1', 05:20:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:40 INFO - 'PWD': '/builds/slave/test', 05:20:40 INFO - 'SHELL': '/bin/bash', 05:20:40 INFO - 'SHLVL': '1', 05:20:40 INFO - 'TERM': 'linux', 05:20:40 INFO - 'TMOUT': '86400', 05:20:40 INFO - 'USER': 'cltbld', 05:20:40 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1473669467.785587-1039223074', 05:20:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:40 INFO - '_': '/tools/buildbot/bin/python'} 05:20:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:40 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:20:40 INFO - Cleaning up... 05:20:40 INFO - Return code: 0 05:20:40 INFO - Running post-action listener: _resource_record_post_action 05:20:40 INFO - Running post-action listener: _start_resource_monitoring 05:20:40 INFO - Starting resource monitoring. 05:20:40 INFO - [mozharness: 2016-09-12 12:20:40.699253Z] Finished create-virtualenv step (success) 05:20:40 INFO - [mozharness: 2016-09-12 12:20:40.699736Z] Running install step. 05:20:40 INFO - Running pre-action listener: _resource_record_pre_action 05:20:40 INFO - Running main action method: install 05:20:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:20:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:20:40 INFO - Reading from file tmpfile_stdout 05:20:40 INFO - Detecting whether we're running mozinstall >=1.0... 05:20:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:20:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:20:40 INFO - Reading from file tmpfile_stdout 05:20:40 INFO - Output received: 05:20:40 INFO - Usage: mozinstall [options] installer 05:20:40 INFO - Options: 05:20:40 INFO - -h, --help show this help message and exit 05:20:40 INFO - -d DEST, --destination=DEST 05:20:40 INFO - Directory to install application into. [default: 05:20:40 INFO - "/builds/slave/test"] 05:20:40 INFO - --app=APP Application being installed. [default: firefox] 05:20:40 INFO - mkdir: /builds/slave/test/build/application 05:20:40 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:40 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:57 INFO - Reading from file tmpfile_stdout 05:20:57 INFO - Output received: 05:20:57 INFO - /builds/slave/test/build/application/firefox/firefox 05:20:57 INFO - Running post-action listener: _resource_record_post_action 05:20:57 INFO - [mozharness: 2016-09-12 12:20:57.550653Z] Finished install step (success) 05:20:57 INFO - [mozharness: 2016-09-12 12:20:57.550926Z] Running run-tests step. 05:20:57 INFO - Running pre-action listener: _resource_record_pre_action 05:20:57 INFO - Running main action method: run_tests 05:20:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:20:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:20:57 INFO - Python 2.7.3 05:20:57 INFO - Return code: 0 05:20:57 INFO - grabbing minidump binary from tooltool 05:20:57 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:57 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 0x1b10570>, '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 0x1b11470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b11900>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:20:57 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:57 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:57 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:57 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:20:57 INFO - Return code: 0 05:20:57 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:20:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:20:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:20:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:20:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:20:57 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-040', '--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:57 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-040 --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:57 INFO - Using env: {'DISPLAY': ':0', 05:20:57 INFO - 'HOME': '/home/cltbld', 05:20:57 INFO - 'LANG': 'en_US.UTF-8', 05:20:57 INFO - 'LANGUAGE': 'en_US:en', 05:20:57 INFO - 'LOGNAME': 'cltbld', 05:20:57 INFO - 'MAIL': '/var/mail/cltbld', 05:20:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:20:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:20:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:57 INFO - 'MOZ_NO_REMOTE': '1', 05:20:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:20:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:57 INFO - 'NO_EM_RESTART': '1', 05:20:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:57 INFO - 'PWD': '/builds/slave/test', 05:20:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:20:57 INFO - 'SHELL': '/bin/bash', 05:20:57 INFO - 'SHLVL': '1', 05:20:57 INFO - 'TERM': 'linux', 05:20:57 INFO - 'TMOUT': '86400', 05:20:57 INFO - 'USER': 'cltbld', 05:20:57 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1473669467.785587-1039223074', 05:20:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:57 INFO - '_': '/tools/buildbot/bin/python'} 05:20:57 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-040', '--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:57 INFO - mozversion application_buildid: 20160910055116 05:20:57 INFO - mozversion application_changeset: 8663d41e015f3c9933a08a65c8349b86f2263354 05:20:57 INFO - mozversion application_display_name: FirefoxDeveloperEdition 05:20:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:20:57 INFO - mozversion application_name: Firefox 05:20:57 INFO - mozversion application_remotingname: firefox-dev 05:20:57 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:20:57 INFO - mozversion application_vendor: Mozilla 05:20:57 INFO - mozversion application_version: 50.0a2 05:20:57 INFO - mozversion platform_buildid: 20160910055116 05:20:57 INFO - mozversion platform_changeset: 8663d41e015f3c9933a08a65c8349b86f2263354 05:20:57 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:20:57 INFO - mozversion platform_version: 50.0a2 05:20:57 INFO - using testdate: 1473682857 05:20:57 INFO - actual date: 1473682857 05:20:57 INFO - starting webserver on 'localhost:47680' 05:20:57 INFO - SUITE-START | Running 2 tests 05:20:57 INFO - TEST-START | dromaeo_css 05:20:57 INFO - operating with platform_type : linux_ 05:20:57 INFO - Initialising browser for dromaeo_css test... 05:20:57 INFO - TEST-INFO | started process 26874 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpewBaFq/profile http://localhost:47680/getInfo.html) 05:21:02 INFO - PROCESS | 26874 | __metrics Screen width/height:1600/1200 05:21:02 INFO - PROCESS | 26874 | colorDepth:24 05:21:02 INFO - PROCESS | 26874 | Browser inner width/height: 1024/697 05:21:02 INFO - PROCESS | 26874 | __metrics 05:21:03 ERROR - PROCESS | 26874 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 05:21:03 INFO - TEST-INFO | 26874: exit 0 05:21:03 INFO - Browser initialized. 05:21:03 INFO - Running cycle 1/1 for dromaeo_css test... 05:21:03 INFO - TEST-INFO | started process 26999 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpewBaFq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:21:15 INFO - PROCESS | 26999 | RSS: Main: 153120768 05:21:15 INFO - PROCESS | 26999 | 05:22:05 INFO - PROCESS | 26999 | 1473682925337 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 | 26999 | Cycle 1(1): loaded http://localhost:47680/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47680/tests/dromaeo/cssquery-ext.html) 05:24:15 INFO - PROCESS | 26999 | RSS: Main: 159748096 05:24:15 INFO - PROCESS | 26999 | 05:27:17 INFO - PROCESS | 26999 | Cycle 1(1): loaded http://localhost:47680/tests/dromaeo/cssquery-ext.html (next: http://localhost:47680/tests/dromaeo/cssquery-jquery.html) 05:27:17 INFO - PROCESS | 26999 | RSS: Main: 155709440 05:27:17 INFO - PROCESS | 26999 | 05:30:19 INFO - PROCESS | 26999 | Cycle 1(1): loaded http://localhost:47680/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47680/tests/dromaeo/cssquery-mootools.html) 05:30:20 INFO - PROCESS | 26999 | RSS: Main: 156008448 05:30:20 INFO - PROCESS | 26999 | 05:33:22 INFO - PROCESS | 26999 | Cycle 1(1): loaded http://localhost:47680/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47680/tests/dromaeo/cssquery-prototype.html) 05:33:22 INFO - PROCESS | 26999 | RSS: Main: 154746880 05:33:22 INFO - PROCESS | 26999 | 05:36:25 INFO - PROCESS | 26999 | Cycle 1(1): loaded http://localhost:47680/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47680/tests/dromaeo/cssquery-yui.html) 05:36:25 INFO - PROCESS | 26999 | RSS: Main: 157265920 05:36:25 INFO - PROCESS | 26999 | 05:39:28 INFO - PROCESS | 26999 | Cycle 1(1): loaded http://localhost:47680/tests/dromaeo/cssquery-yui.html (next: http://localhost:47680/tests/dromaeo/cssquery-dojo.html) 05:39:28 INFO - PROCESS | 26999 | RSS: Main: 157429760 05:39:28 INFO - PROCESS | 26999 | 05:39:28 INFO - PROCESS | 26999 | __start_tp_report 05:39:28 INFO - PROCESS | 26999 | _x_x_mozilla_page_load 05:39:28 INFO - PROCESS | 26999 | _x_x_mozilla_page_load_details 05:39:28 INFO - PROCESS | 26999 | |i|pagename|runs| 05:39:28 INFO - PROCESS | 26999 | |0;dojo.html;3272.656371081037;3362.9327413451724;3364.6467120952298;3371.8482668279926;3392.440247359187;5785.247917770689;5859.1797148399255;5879.647221166728;5889.087191485316;5923.229980102586;5709.115087161496;5783.884322313551;5803.477687008169;5829.271341082364;5835.679037652938;5244.344456942892;5282.947170528303;5344.545713614348;5347.893042139174;5380.300560927074;5129.487051294878;5133.2300154976865;5185.662931909433;5195.922061169085;5233.633645644806;5262.894742105155;5271.262023316739;5272.31459910213;5279.894402111955;5297.125974214269;5318.946810531903;5324.467553244684;5330.173823057432;5333.54664853486;5347.25138480613;5127.9743601282125;5145.716985565792;5161.845144645667;5187.118189907725;5199.2461093141665;5721.342045664718;5756.856078598027;5777.740001699934;5780.132980052984;5806.1000544915605;6221.066839973995;6231.034189700604;6239;6274.780382686629;6288.9056664150075;6156.045812899008;6175.042868355412;6217.844553886144;6296.6536840473755;6321.367863213642;5854.2096816930025;5898.41015898411;5899.410058994152;5916.26046744157;5934.495567876736;6411.102445657519;6436.678166091677;6449.7097630605795;6452.4515416189215;6452.741890324334;5873.471387575139;6012.669303188279;6021.608595441282;6165.876682466325;6176.536759743038;6195.31851496335;6229.875402491924;6244.063390491463;6244.594101383395;6254.280757712831;6124.571280010446;6162.22972128484;6175.783847565313;6182.412670796267;6198.163247961557;6033.637981721111;6050.122732203806;6056.757729690851;6186.886360455162;6228.408301211378;4704.506026867198;4705.858824235278;4709.576138147583;4730.479647238801;4735.171345014609;4172.83308667656;4196.055887425305;4197.9370309445385;4202.285611446062;4221.5567365426805;5844.47399734026;5899.46904778572;5963.5825492216;5963.940360596339;6026.096085587197;6324.557280990379;6332.366763323723;6339.5562310637815;6341.270753863272;6387.297397286295;6067.180930574405;6073.453389194905;6077.6353418795015;6083.39166083397;6111.54163437744;5189.911275047399;5198.194279886624;5251.10731175701;5439.401665816757;5466.808661696821;6226.377362263737;6263.96868015657;6314.147590075281;6400.487960963112;6401.9039714404325;6183.96908015457;6201.162843016135;6201.81394558164;6209.410106039919;6210.906836397548;6110.77140286259;6330.746770129143;6382.680865956777;6393.456556192589;6401.071844582602;5971.731272092853;5976.760929562943;5983.820485385445;6039.516838652985;6082.939170608237;6103.7253323601435;6116.694165291807;6124;6133.141360209485;6136.447719705248;5868.061110222344;5945.048792193228;5989.970050149721;6008.849778755566;6022.247219097613;5953.7023148843255;5981;6009;6075.544334174866;6085.878282434238;5980.9401905980385;5996.700164991646;6009.489193418608;6018.548608854266;6050.364711705208;5931.673757943355;6052.6973651316375;6105.419985101408;6133.815985520441;6139;5891.882162356815;5912.763489460373;5960.165576819162;6006.549508786946;6053.122297266959 05:39:28 INFO - PROCESS | 26999 | |1;ext.html;600.339626410948;652.9216494020717;668.0613737698534;670.9832254193644;722.7000794670212;42053.57946420535;46364.76817615907;46372.76813615936;46394.304085438744;46411.303830442484;46597.30104048442;46748.298775518306;47144;47160.76419617906;47368.52631473684;8843.734687959355;9128.224100951395;9149.130832570896;9167.083291670813;9169.128932751379;9177.449353038839;9210.953945230265;9224.723258302229;9227.677031303905;9233.445993240393;9325.813483730355;9373.765655858624;9399.10708482693;9419.952900235488;9424.104710052568;9262.6294948202;9299.349045566812;9300.441973481613;9330.720078397659;9344.532773361372;9192.448453092837;9200.033996430413;9276.675316363895;9280.164785169367;9291.163795258459;42401.78799105985;42625;42815.78592107019;42939;42997.140057198994;3570.8750193743094;3621.8732344368086;3792.260509200707;3862.995621138496;4461.420015397977;34369.31261374783;34541;34665.48001779975;34815.4777678335;35239.29521409583;4516.1419330327135;4619.907601847978;4625.213713668684;4700.177468942921;4711.081339138869;378647;556047;564329;567622;567815;66821.99767003402;67167;67225.6638716813;67348;67389;39294.60705392966;70183.64908175427;70952.64523677349;71285.2871471289;71368.64315678389;18090.366837160636;18804.153813078443;18824.058797060094;18884.43346699601;18897.905510472636;14112.223827689575;14118.64703382403;14174.14955102697;14207.71584568303;14247.287635618177;20541.89729051345;20629.484262893548;20673.17307307691;20749.377518674464;20897.164113435294;7524.548527088392;7561.168271490132;7603.353714934182;7647.923520764833;7759.495632783851;1027.6557353286582;1036.4662198967537;1125.5610311978335;1145.6505765741529;1151.2574389518713;32986.3402731944;33035.834820825745;33452;33531.83234083814;33659.831700841336;1164.3072371938683;1191.9821202681967;1209.4738788626976;1246.6945598328286;1250.0437165568349;17429.564260893327;17510.387136450165;17637.206325715375;17648.470545883643;17698.29206831712;657860;665279;666523;666892;667948;62390;63388.366116339166;63548.364516354246;64002;64158;1475.7269905067596;1503.0455660655562;1521.2241756703938;1560.4694403902502;1664.192866459766;32049;32477.188070297485;32741.345173095928;32780.67219327872;33446.66553334533;55037.72481137569;55625;55665;55676.44323556712;55712.72143639256;386871;389422;389621;390528;390695;30204.395912082076;30485;30882;30908.227294316923;30964.0710778677;51482.742586286826;51969.22046169235;52684;53931.73034134961;53986.73006634942;43928.78035609802;44619.33071004003;45014.324785127596;45289.09421811479;46032;41817;43722.34416483819;43862.56137438585;44700.32949505825;48716.26925596048;43394.349084764384;43789.78105109454;44085.33871991987;44798.88002799956;46530.30204546867;39205;39469;39778.403323949584;40376.99057523585;40565.59434405618;28815;29953.70046299509;30511.54232686467;32071.839640801645;32250.35499290048 05:39:28 INFO - PROCESS | 26999 | |2;jquery.html;414.2026598797315;416.39414651681795;416.76036279139504;417.6596074199527;431.3486635180876;19780;20335.288264910712;20629.587408251828;21057.789422105776;21298.680519792197;20500.384988450332;20935.47661308468;21086.261980830674;21655.783442165575;21784;6020.4280593343565;6233.096201050845;6247;6250.874982500347;6258.06129080637;6096.573239873189;6152.0464328029;6156.353582873801;6164.506839452855;6169.568130230886;5909;6088.634681919077;6128.11142384354;6135.9079613805825;6136.50907927367;5911.940880591182;5970.582059255832;6016.368281330485;6028.427299406571;6050.757969681207;5335.1197052486295;5475.397706252309;5481.095619222823;5486.890262194774;5492.670439773627;24811.379715507075;24880.87559562209;25072.122475713444;25310.746892531024;25381;2596.5066637338846;2631.8552479613613;2638.300850274679;2711.539038363483;2862.5992361069466;15802.841971580252;15961.521154365271;16129;16319.18404079803;16778.580535486588;2050.8256798172174;2100.9684854727193;2110.820580250665;2168.902399392031;2169;81119.18880811175;81644;82064.58967705123;83176;86853;11341.829872551752;11401.657950261506;11941.402929853646;12294.631161065183;12452.75094498105;16237.756433653505;16281.755773663404;16351.427700030441;16356.34574617002;16394;8784.736457906272;8977.192052715287;9068.22920051803;9226.58480368385;9250.95374523123;8711.520866352412;8816.677498375295;8849.159329863782;8923.866142007875;9020.278377729766;7036.753713619999;7086.397656199178;7090.255523170096;7177.389921856597;7299.635018249066;2041.2447394464143;2052.9486762831048;2116.386247988097;2118;2130.158587358016;907.4464576608264;1057.513543769859;1072.383379556758;1085.9235794892131;1109.9278546894427;21092;21096.894515527325;21112.366629000848;21178.576428471344;21260.149594016068;1042.3224903812581;1096.8957948994832;1105.8838821923744;1121.7924683933336;1131.6944425005202;11310.264832785842;11842.467088981017;12130;12299.569515066929;12326.260424374566;369183;371984;372783;527892;538246;95797;98418.50790746143;99138.504307478;99176.50411747895;100999.49500252452;1070.4273213830563;1080.573173596425;1097.6542389147232;1110.5335758981248;1119.9888001120048;18689.90655046716;19174.233030678617;19225.32711355096;19352.32266870653;19394.612107758046;43849.56150438455;44107.77946110249;44709.55290447054;44827.327590086825;46501.53498465107;62607.37392626015;64151.679241603495;64692.6765366189;66121.66939165273;67605;9042.321825863219;9193.080691930754;9212.723618291462;9269.304802139732;9648.276379271443;10402.791944160923;13680.93159534196;13753.38109785082;14007.509737159151;14198.361073751912;11575.479103440197;11956.880431195577;12031.977281930784;12173.634790956285;12229.082818788449;11453.255538389978;11881.702957426134;12115.636530904087;12172;12371.195872268274;11634.301941883514;11717.707057323634;11901.10741694359;12191.085668574715;12566.554846192805;8641.956790216009;8700.60847261862;8778.692745753868;9045.547722613712;9220.538973051187;8949.731508054769;11777.764444710887;11808.232464889754;11905.107116966088;11981.400929953641 05:39:28 INFO - PROCESS | 26999 | |3;mootools.html;1555.8210805757335;1575.708493928623;1758.8329108734674;1858.9814101858985;1860.8604354673403;8128.9187108129045;9014.098590140991;9033.412828166165;9688.515574221286;9699.854502182472;9098.363114581982;9466.574004169812;9470.100340467661;9472.66845660402;9487.667931622394;2503.8372505787156;2531.3798119460757;2531.835430697008;2534.024400605771;2536.2137737301405;2493.6259561065895;2543.504016716741;2567.7047139579;2568.8458692478416;2576.8711564421797;2483.689538807649;2484.8633325167107;2553.157458038852;2556.3481312265344;2569.9100531481404;2437.9390515237174;2466.839655422401;2486.627005949104;2501.262127672323;2514.421683012899;2408.1932552594903;2426.46617744096;2432.3067925641285;2448.791852692523;2467.5188338274043;9458.57436415363;9546.61813527458;9567.952160239223;9571.904280957171;9573.234141268682;5523.337199536041;5542.473465020817;5576.804811831607;5584.804531841407;5596.972015139898;3445.5693038370205;3535.1338921964025;3548;3553.9111522211892;3604.098975256186;1187.2935603316014;1242.714175739576;1249.425264378387;1250.2998320940303;1275.7576060548465;13171.934140329333;13182.86817131826;13212.735745285137;13266.336683165802;13305.86694133056;4814.181589129857;4943.9864827710235;4951.430585482643;5011.523905228997;5024.723640199824;11170.553177872956;11171.553137874556;11225.66323010311;11235;11272.66182014541;5575.804846830341;5585.134304182858;5588.21764952907;5601.075822489325;5616.185653080281;4437.068215674744;4992.301077849104;5070.391553013662;5118.97440512795;5126.615503837227;4212.410262563244;4242.278812601865;4284.400183974246;4301.65586753059;4323.832565207376;1306.8562458129597;1326.2241588670543;1337.8929685625126;1342.8455727591256;1347.8113064171023;1038.2213339995003;1050.8844027157006;1061.4586560853866;1065.6217042949797;1067.685032915292;6252.468540174045;6329.8734025320155;6407.263164736022;6410.423061924449;6535.313792051861;494.8713334533047;511.6878703990563;519.0864079220523;520.8177138001745;522.5532169994676;4492.966617677952;4508.887277818015;4522.932156017662;4585.839495617637;4596.88507787308;10836.891631083747;11332.490037948312;11335.716607084723;11365.374904380338;11366.318020918607;10943.343399396063;11321.660350189508;11377.601783937522;11629.59296424621;11673.124515661359;8617.439866408664;8831.514266715392;8879.91120088791;8890.244329232088;8894.421862578782;6414.61512309263;6658.3674550917585;6667.197785235483;6687.665616719048;6801.5238933274195;10819.459027048773;10898.291611045257;10903.672889813319;10914.563417463212;11008.39453830047;10715.142788577052;10800.783984320427;11027.779444410906;11064.944675276573;11094.778104438028;7688.654010569426;8241;8256.504609723435;8398.28614567744;8439.156084391758;9199.218066464158;9233.169014788702;9244.306676999118;9254.4910029949;9269.629214831331;8342.499450033018;8373.288270497076;8430.15698430152;8498.74503764888;8552.273056790242;7847.372210223054;7857.921420785948;7869.567173805495;7947.364210863001;8001.439899207;7523.661435235537;7600.961995189989;7614.6954121836725;7629.54222746637;7738.458307918391;5767.567432442667;5793.246877905845;5821.359650438364;5851.063829787214;5867.295924489089;5545;5689;5703.287089113861;5708.315002199763;5804.506616937439 05:39:28 INFO - PROCESS | 26999 | |4;prototype.html;335.8941933291013;346.7087646377043;350.2836698950646;358.7417059717003;365.4518222666001;5860.677662728548;5892.587518873681;5893.381194974525;5912.467877890984;5946.662001049762;5772.942270577303;5776.277965254343;5778.133280007991;5797.855053623651;5809.622374545652;4353.216421044219;4486.214912390335;4555.840545580905;4588.380568623244;4589.24277494213;4564.041551274237;4580.656450766189;4586.495485496609;4603.539646035387;4611.400517932664;4571.679982401233;4579.771011449431;4585.72485650862;4588.816447342102;4602.2636378179495;4406.294992801152;4456.9331460028125;4502.077074199781;4517.367568540407;4542.295944128666;3929.25344184604;3952.616584195537;3958.1094253792953;3970.7816070116132;3975.860844870416;5618.831435056954;5626.324841019063;5629.80295689653;5646.435356464362;5697.604086998712;5117.130087885069;5157.355330583677;5174.042802081627;5179.249008893689;5200.92198617021;6031.155638210654;6063.878722425527;6088.786892458743;6107.175531303306;6127.387261273837;4305.117450922585;4405.471343438808;4432.84485043022;4435.135148646015;4435.445569303837;6142.447179753799;6265.248170219603;6319.399657032574;6331.905021424683;6369.299377068519;4387.188370151468;4444.28891377378;4450.488193857683;4453.732776033448;4463.129689710475;4520.95479045212;4534.977325113353;4541.205289074399;4548.977255113769;4570.702904311209;5144.30551875492;5157.252198431206;5204.8178313758835;5206.375234971828;5248.6588371755715;6062.666553339609;6089.817305480843;6104.26748790148;6106.877862442726;6147.262328520606;1539.698954383544;1549.124744519343;1556.758702401107;1562.9218539073001;1563.6169138560897;955.2405837359316;988.9961688885787;998.2513115163628;1002.9348092373972;1023.1865666794915;5234.450382709837;5251.553617942518;5259.632495551108;5307.0545847055255;5314.4951229633125;5453.291072160594;5475.1110866750905;5553.861153471195;5568.359638641608;5575;5033.3205017322925;5328.413874473805;5342.652727572696;5367.194920761917;5422.728863556807;2956.9704302956693;2975.0925967580097;2980.4039192161654;2983.537551679493;2992.5660779186896;5512.173174023928;5627.071533196977;5713.22871412354;5713.942860571341;5727.6849773262875;5566.972165139148;5608.214849921014;5618.634788738719;5629.6903670298525;5706.828795136153;5670.801521946794;5679.517241034476;5686.549929767965;5703.119924438215;5729.799457018984;5311.681299122065;5469.42571030052;5555.9721451531595;5562.499375056264;5574.888502229852;5429.402765695691;5458.717201457734;5467.234587157722;5542.612017158922;5544.889102218014;6208.72060757258;6309;6500.869982600227;6547.70535325921;6561.573497722824;4358.520562738159;4414.911701766011;4419.337099434982;4420.447444069491;4424.650481603142;6117.235345581802;6275.8117256480555;6335.90496142549;6345.73085382916;6367.777127800505;6075.756969721162;6088.391160883876;6104.306894328867;6151.661658608819;6183.783567575113;5858.882822343444;6117.388261173847;6121.571489995657;6150.630962142286;6175.84560385994;6079.574429789872;6091.786787462417;6112.388761123852;6121.112438696453;6160.322364539987;6174.475169610633;6218.906716399167;6240.783047305913;6246.219222597176;6274.1843560336865;5729.742161602821;6025.78909738157;6032.336442991355;6055.576109672281;6055.697215139137 05:39:28 INFO - PROCESS | 26999 | |5;yui.html;838.0237023867192;859.9527026013567;893.5710858787781;912.2610685344869;924.0528458330215;16248.675026499477;17541.91229043853;17650.293988240486;17806.554836129104;17881.374151904685;15725;15765.448209312677;15949.840501594981;16057.678846423065;16355.83644163558;884.884964954555;892.1168043636818;913.6619450803212;915.9587818548148;917.3257655623112;917.3532659475087;918.7794929217007;919.0901008002063;920.0385597051064;921.870938068671;980.4509474694158;981.9950900245489;982.4056445850265;982.4252812104927;984.4929861121528;956.8325543030012;965.3821554205314;967.690339091491;967.762898089969;968.7965527239253;713.6752777486283;714.2714431280065;714.8856183010745;714.9428045756379;715.677944924786;17262.395816146374;17387.91345038591;17486.562835929075;17533.736993945102;17550;14255;14572.854271457256;15152.242387880671;15152.924235378752;15601.609959751097;14757.335919883632;14822.555323340317;14907.627309317246;14971.625709357244;15029.323680434407;4854.320395144682;4867.440244371907;4877.780499877514;4882.584980276681;4900.705957642553;89995.55002224947;90083.54958225232;90795;91111.5444422787;91926;12375;12464.688382790322;12608.054395920342;12610.243385396905;12747.61757147287;22886.42783930415;23032.88483557605;23103.653445198335;23184.65223021656;23427.297181084596;8936.910630893737;9059.547022648841;9106.499142547224;9202.079792020879;9293.30300227472;5156.355455568054;5229.947700523022;5261.0793111205385;5276.34045744282;5312.840614781563;3629.2197177606413;3683.5211422514903;3693.649103335179;3723.2925744108534;3738.0467980664757;649.831043928582;653.3825534869598;683.8700646877078;716.2228981554968;719.4783781758183;679.7756740275697;747.7158679701679;791.7569416016913;819.4919150126959;825.4551995682821;9086.727398178065;10524.789504209873;10555.977457132634;10650.680479585624;10680.946595266974;642.2517766801717;685.533836990851;771.1941021632408;789.5460110436522;803.3894240377356;4934.259861020875;5105.923411148836;5142.794288228504;5166.483351664879;5178.559822415062;74048;74836;76385;115451;125420;108132.45933770438;109871.45064274628;110027;110586;111674.99999999837;94498;94987;97993.51003244938;99075.50462247642;102245;9775.706728798148;10090.671246829666;10092.596296148366;10134.594616215563;10212.846807297748;57507.712461437426;57617.13574296305;58247.70876145592;58776.41223587709;59357.40642593519;81939.5903020481;85236.57381713051;86089;86873;89792.5510372444;13250.271235082164;13272.734545309233;13586.048976571545;13636.181829090287;13639.249841258827;14694.412223510939;14823.555293341216;14826.40694372213;14915.328810203784;16403.589910251863;15348.23258837076;15663.216839158224;15855.603609909844;16104.919475402548;16375;15221.847781522043;15373.615659608151;15455.84544154544;15742;16297.266623001757;15020;15212.011219271155;15964.920175399513;16235.269412876685;16425.342986280415;12545.686357841127;12669.272651123743;12748.298843563693;12913.418896149507;13597.79603305971;13074.215547067206;13210.80183797263;13320.733585328433;13371.732565348833;13581.524646637321 05:39:28 INFO - PROCESS | 26999 | __end_tp_report 05:39:28 INFO - PROCESS | 26999 | __start_cc_report 05:39:28 INFO - PROCESS | 26999 | _x_x_mozilla_cycle_collect,328 05:39:28 INFO - PROCESS | 26999 | __end_cc_report 05:39:28 INFO - PROCESS | 26999 | __startTimestamp1473683968366__endTimestamp 05:39:28 INFO - PROCESS | 26999 | 05:39:28 INFO - PROCESS | 26999 | ------- Summary: start ------- 05:39:28 INFO - PROCESS | 26999 | Number of tests: 6 05:39:28 INFO - PROCESS | 26999 | 05:39:28 INFO - PROCESS | 26999 | [#0] dojo.html Cycles:165 Average:5758.96 Median:5989.97 stddev:660.23 (11.0%) stddev-sans-first:632.79 05:39:28 INFO - PROCESS | 26999 | Values: 3272.7 3362.9 3364.6 3371.8 3392.4 5785.2 5859.2 5879.6 5889.1 5923.2 5709.1 5783.9 5803.5 5829.3 5835.7 5244.3 5282.9 5344.5 5347.9 5380.3 5129.5 5133.2 5185.7 5195.9 5233.6 5262.9 5271.3 5272.3 5279.9 5297.1 5318.9 5324.5 5330.2 5333.5 5347.3 5128.0 5145.7 5161.8 5187.1 5199.2 5721.3 5756.9 5777.7 5780.1 5806.1 6221.1 6231.0 6239.0 6274.8 6288.9 6156.0 6175.0 6217.8 6296.7 6321.4 5854.2 5898.4 5899.4 5916.3 5934.5 6411.1 6436.7 6449.7 6452.5 6452.7 5873.5 6012.7 6021.6 6165.9 6176.5 6195.3 6229.9 6244.1 6244.6 6254.3 6124.6 6162.2 6175.8 6182.4 6198.2 6033.6 6050.1 6056.8 6186.9 6228.4 4704.5 4705.9 4709.6 4730.5 4735.2 4172.8 4196.1 4197.9 4202.3 4221.6 5844.5 5899.5 5963.6 5963.9 6026.1 6324.6 6332.4 6339.6 6341.3 6387.3 6067.2 6073.5 6077.6 6083.4 6111.5 5189.9 5198.2 5251.1 5439.4 5466.8 6226.4 6264.0 6314.1 6400.5 6401.9 6184.0 6201.2 6201.8 6209.4 6210.9 6110.8 6330.7 6382.7 6393.5 6401.1 5971.7 5976.8 5983.8 6039.5 6082.9 6103.7 6116.7 6124.0 6133.1 6136.4 5868.1 5945.0 5990.0 6008.8 6022.2 5953.7 5981.0 6009.0 6075.5 6085.9 5980.9 5996.7 6009.5 6018.5 6050.4 5931.7 6052.7 6105.4 6133.8 6139.0 5891.9 5912.8 5960.2 6006.5 6053.1 05:39:28 INFO - PROCESS | 26999 | 05:39:28 INFO - PROCESS | 26999 | [#1] ext.html Cycles:180 Average:69286.20 Median:44659.83 stddev:144122.94 (322.7%) stddev-sans-first:144434.98 05:39:28 INFO - PROCESS | 26999 | Values: 600.3 652.9 668.1 671.0 722.7 42053.6 46364.8 46372.8 46394.3 46411.3 46597.3 46748.3 47144.0 47160.8 47368.5 8843.7 9128.2 9149.1 9167.1 9169.1 9177.4 9211.0 9224.7 9227.7 9233.4 9325.8 9373.8 9399.1 9420.0 9424.1 9262.6 9299.3 9300.4 9330.7 9344.5 9192.4 9200.0 9276.7 9280.2 9291.2 42401.8 42625.0 42815.8 42939.0 42997.1 3570.9 3621.9 3792.3 3863.0 4461.4 34369.3 34541.0 34665.5 34815.5 35239.3 4516.1 4619.9 4625.2 4700.2 4711.1 378647.0 556047.0 564329.0 567622.0 567815.0 66822.0 67167.0 67225.7 67348.0 67389.0 39294.6 70183.6 70952.6 71285.3 71368.6 18090.4 18804.2 18824.1 18884.4 18897.9 14112.2 14118.6 14174.1 14207.7 14247.3 20541.9 20629.5 20673.2 20749.4 20897.2 7524.5 7561.2 7603.4 7647.9 7759.5 1027.7 1036.5 1125.6 1145.7 1151.3 32986.3 33035.8 33452.0 33531.8 33659.8 1164.3 1192.0 1209.5 1246.7 1250.0 17429.6 17510.4 17637.2 17648.5 17698.3 657860.0 665279.0 666523.0 666892.0 667948.0 62390.0 63388.4 63548.4 64002.0 64158.0 1475.7 1503.0 1521.2 1560.5 1664.2 32049.0 32477.2 32741.3 32780.7 33446.7 55037.7 55625.0 55665.0 55676.4 55712.7 386871.0 389422.0 389621.0 390528.0 390695.0 30204.4 30485.0 30882.0 30908.2 30964.1 51482.7 51969.2 52684.0 53931.7 53986.7 43928.8 44619.3 45014.3 45289.1 46032.0 41817.0 43722.3 43862.6 44700.3 48716.3 43394.3 43789.8 44085.3 44798.9 46530.3 39205.0 39469.0 39778.4 40377.0 40565.6 28815.0 29953.7 30511.5 32071.8 32250.4 05:39:28 INFO - PROCESS | 26999 | 05:39:28 INFO - PROCESS | 26999 | [#2] jquery.html Cycles:180 Average:28757.84 Median:24976.50 stddev:73604.49 (294.7%) stddev-sans-first:73780.21 05:39:28 INFO - PROCESS | 26999 | Values: 414.2 416.4 416.8 417.7 431.3 19780.0 20335.3 20629.6 21057.8 21298.7 20500.4 20935.5 21086.3 21655.8 21784.0 6020.4 6233.1 6247.0 6250.9 6258.1 6096.6 6152.0 6156.4 6164.5 6169.6 5909.0 6088.6 6128.1 6135.9 6136.5 5911.9 5970.6 6016.4 6028.4 6050.8 5335.1 5475.4 5481.1 5486.9 5492.7 24811.4 24880.9 25072.1 25310.7 25381.0 2596.5 2631.9 2638.3 2711.5 2862.6 15802.8 15961.5 16129.0 16319.2 16778.6 2050.8 2101.0 2110.8 2168.9 2169.0 81119.2 81644.0 82064.6 83176.0 86853.0 11341.8 11401.7 11941.4 12294.6 12452.8 16237.8 16281.8 16351.4 16356.3 16394.0 8784.7 8977.2 9068.2 9226.6 9251.0 8711.5 8816.7 8849.2 8923.9 9020.3 7036.8 7086.4 7090.3 7177.4 7299.6 2041.2 2052.9 2116.4 2118.0 2130.2 907.4 1057.5 1072.4 1085.9 1109.9 21092.0 21096.9 21112.4 21178.6 21260.1 1042.3 1096.9 1105.9 1121.8 1131.7 11310.3 11842.5 12130.0 12299.6 12326.3 369183.0 371984.0 372783.0 527892.0 538246.0 95797.0 98418.5 99138.5 99176.5 100999.5 1070.4 1080.6 1097.7 1110.5 1120.0 18689.9 19174.2 19225.3 19352.3 19394.6 43849.6 44107.8 44709.6 44827.3 46501.5 62607.4 64151.7 64692.7 66121.7 67605.0 9042.3 9193.1 9212.7 9269.3 9648.3 10402.8 13680.9 13753.4 14007.5 14198.4 11575.5 11956.9 12032.0 12173.6 12229.1 11453.3 11881.7 12115.6 12172.0 12371.2 11634.3 11717.7 11901.1 12191.1 12566.6 8642.0 8700.6 8778.7 9045.5 9220.5 8949.7 11777.8 11808.2 11905.1 11981.4 05:39:28 INFO - PROCESS | 26999 | 05:39:28 INFO - PROCESS | 26999 | [#3] mootools.html Cycles:180 Average:6210.01 Median:4705.53 stddev:3528.78 (75.0%) stddev-sans-first:3521.34 05:39:28 INFO - PROCESS | 26999 | Values: 1555.8 1575.7 1758.8 1859.0 1860.9 8128.9 9014.1 9033.4 9688.5 9699.9 9098.4 9466.6 9470.1 9472.7 9487.7 2503.8 2531.4 2531.8 2534.0 2536.2 2493.6 2543.5 2567.7 2568.8 2576.9 2483.7 2484.9 2553.2 2556.3 2569.9 2437.9 2466.8 2486.6 2501.3 2514.4 2408.2 2426.5 2432.3 2448.8 2467.5 9458.6 9546.6 9568.0 9571.9 9573.2 5523.3 5542.5 5576.8 5584.8 5597.0 3445.6 3535.1 3548.0 3553.9 3604.1 1187.3 1242.7 1249.4 1250.3 1275.8 13171.9 13182.9 13212.7 13266.3 13305.9 4814.2 4944.0 4951.4 5011.5 5024.7 11170.6 11171.6 11225.7 11235.0 11272.7 5575.8 5585.1 5588.2 5601.1 5616.2 4437.1 4992.3 5070.4 5119.0 5126.6 4212.4 4242.3 4284.4 4301.7 4323.8 1306.9 1326.2 1337.9 1342.8 1347.8 1038.2 1050.9 1061.5 1065.6 1067.7 6252.5 6329.9 6407.3 6410.4 6535.3 494.9 511.7 519.1 520.8 522.6 4493.0 4508.9 4522.9 4585.8 4596.9 10836.9 11332.5 11335.7 11365.4 11366.3 10943.3 11321.7 11377.6 11629.6 11673.1 8617.4 8831.5 8879.9 8890.2 8894.4 6414.6 6658.4 6667.2 6687.7 6801.5 10819.5 10898.3 10903.7 10914.6 11008.4 10715.1 10800.8 11027.8 11064.9 11094.8 7688.7 8241.0 8256.5 8398.3 8439.2 9199.2 9233.2 9244.3 9254.5 9269.6 8342.5 8373.3 8430.2 8498.7 8552.3 7847.4 7857.9 7869.6 7947.4 8001.4 7523.7 7601.0 7614.7 7629.5 7738.5 5767.6 5793.2 5821.4 5851.1 5867.3 5545.0 5689.0 5703.3 5708.3 5804.5 05:39:28 INFO - PROCESS | 26999 | 05:39:28 INFO - PROCESS | 26999 | [#4] prototype.html Cycles:180 Average:4979.65 Median:5543.75 stddev:1446.63 (26.1%) stddev-sans-first:1408.07 05:39:28 INFO - PROCESS | 26999 | Values: 335.9 346.7 350.3 358.7 365.5 5860.7 5892.6 5893.4 5912.5 5946.7 5772.9 5776.3 5778.1 5797.9 5809.6 4353.2 4486.2 4555.8 4588.4 4589.2 4564.0 4580.7 4586.5 4603.5 4611.4 4571.7 4579.8 4585.7 4588.8 4602.3 4406.3 4456.9 4502.1 4517.4 4542.3 3929.3 3952.6 3958.1 3970.8 3975.9 5618.8 5626.3 5629.8 5646.4 5697.6 5117.1 5157.4 5174.0 5179.2 5200.9 6031.2 6063.9 6088.8 6107.2 6127.4 4305.1 4405.5 4432.8 4435.1 4435.4 6142.4 6265.2 6319.4 6331.9 6369.3 4387.2 4444.3 4450.5 4453.7 4463.1 4521.0 4535.0 4541.2 4549.0 4570.7 5144.3 5157.3 5204.8 5206.4 5248.7 6062.7 6089.8 6104.3 6106.9 6147.3 1539.7 1549.1 1556.8 1562.9 1563.6 955.2 989.0 998.3 1002.9 1023.2 5234.5 5251.6 5259.6 5307.1 5314.5 5453.3 5475.1 5553.9 5568.4 5575.0 5033.3 5328.4 5342.7 5367.2 5422.7 2957.0 2975.1 2980.4 2983.5 2992.6 5512.2 5627.1 5713.2 5713.9 5727.7 5567.0 5608.2 5618.6 5629.7 5706.8 5670.8 5679.5 5686.5 5703.1 5729.8 5311.7 5469.4 5556.0 5562.5 5574.9 5429.4 5458.7 5467.2 5542.6 5544.9 6208.7 6309.0 6500.9 6547.7 6561.6 4358.5 4414.9 4419.3 4420.4 4424.7 6117.2 6275.8 6335.9 6345.7 6367.8 6075.8 6088.4 6104.3 6151.7 6183.8 5858.9 6117.4 6121.6 6150.6 6175.8 6079.6 6091.8 6112.4 6121.1 6160.3 6174.5 6218.9 6240.8 6246.2 6274.2 5729.7 6025.8 6032.3 6055.6 6055.7 05:39:28 INFO - PROCESS | 26999 | 05:39:28 INFO - PROCESS | 26999 | [#5] yui.html Cycles:180 Average:22514.90 Median:4296.18 stddev:31515.26 (733.6%) stddev-sans-first:31561.63 05:39:28 INFO - PROCESS | 26999 | Values: 838.0 860.0 893.6 912.3 924.1 16248.7 17541.9 17650.3 17806.6 17881.4 15725.0 15765.4 15949.8 16057.7 16355.8 884.9 892.1 913.7 916.0 917.3 917.4 918.8 919.1 920.0 921.9 980.5 982.0 982.4 982.4 984.5 956.8 965.4 967.7 967.8 968.8 713.7 714.3 714.9 714.9 715.7 17262.4 17387.9 17486.6 17533.7 17550.0 14255.0 14572.9 15152.2 15152.9 15601.6 14757.3 14822.6 14907.6 14971.6 15029.3 4854.3 4867.4 4877.8 4882.6 4900.7 89995.6 90083.5 90795.0 91111.5 91926.0 12375.0 12464.7 12608.1 12610.2 12747.6 22886.4 23032.9 23103.7 23184.7 23427.3 8936.9 9059.5 9106.5 9202.1 9293.3 5156.4 5229.9 5261.1 5276.3 5312.8 3629.2 3683.5 3693.6 3723.3 3738.0 649.8 653.4 683.9 716.2 719.5 679.8 747.7 791.8 819.5 825.5 9086.7 10524.8 10556.0 10650.7 10680.9 642.3 685.5 771.2 789.5 803.4 4934.3 5105.9 5142.8 5166.5 5178.6 74048.0 74836.0 76385.0 115451.0 125420.0 108132.5 109871.5 110027.0 110586.0 111675.0 94498.0 94987.0 97993.5 99075.5 102245.0 9775.7 10090.7 10092.6 10134.6 10212.8 57507.7 57617.1 58247.7 58776.4 59357.4 81939.6 85236.6 86089.0 86873.0 89792.6 13250.3 13272.7 13586.0 13636.2 13639.2 14694.4 14823.6 14826.4 14915.3 16403.6 15348.2 15663.2 15855.6 16104.9 16375.0 15221.8 15373.6 15455.8 15742.0 16297.3 15020.0 15212.0 15964.9 16235.3 16425.3 12545.7 12669.3 12748.3 12913.4 13597.8 13074.2 13210.8 13320.7 13371.7 13581.5 05:39:28 INFO - PROCESS | 26999 | -------- Summary: end -------- 05:39:28 INFO - PROCESS | 26999 | 05:39:29 INFO - TEST-INFO | 26999: exit 0 05:39:29 INFO - TEST-OK | dromaeo_css | took 1111232ms 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 27265 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkCD9Nj/profile http://localhost:47680/getInfo.html) 05:39:33 INFO - PROCESS | 27265 | __metrics Screen width/height:1600/1200 05:39:33 INFO - PROCESS | 27265 | colorDepth:24 05:39:33 INFO - PROCESS | 27265 | Browser inner width/height: 1024/697 05:39:33 INFO - PROCESS | 27265 | __metrics 05:39:34 ERROR - PROCESS | 27265 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 05:39:34 INFO - TEST-INFO | 27265: exit 0 05:39:34 INFO - Browser initialized. 05:39:34 INFO - Running cycle 1/1 for kraken test... 05:39:34 INFO - TEST-INFO | started process 27391 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkCD9Nj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:39:46 INFO - PROCESS | 27391 | RSS: Main: 153497600 05:39:46 INFO - PROCESS | 27391 | 05:39:49 INFO - PROCESS | 27391 | Cycle 1(1): loaded http://localhost:47680/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47680/tests/kraken/driver.html?testName=audio-beat-detection) 05:39:50 INFO - PROCESS | 27391 | RSS: Main: 158752768 05:39:50 INFO - PROCESS | 27391 | 05:39:52 INFO - PROCESS | 27391 | Cycle 1(1): loaded http://localhost:47680/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47680/tests/kraken/driver.html?testName=audio-dft) 05:39:53 INFO - PROCESS | 27391 | RSS: Main: 157061120 05:39:53 INFO - PROCESS | 27391 | 05:39:56 INFO - PROCESS | 27391 | Cycle 1(1): loaded http://localhost:47680/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47680/tests/kraken/driver.html?testName=audio-fft) 05:39:56 INFO - PROCESS | 27391 | RSS: Main: 157757440 05:39:56 INFO - PROCESS | 27391 | 05:39:58 INFO - PROCESS | 27391 | Cycle 1(1): loaded http://localhost:47680/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47680/tests/kraken/driver.html?testName=audio-oscillator) 05:39:59 INFO - PROCESS | 27391 | RSS: Main: 157761536 05:39:59 INFO - PROCESS | 27391 | 05:40:01 INFO - PROCESS | 27391 | Cycle 1(1): loaded http://localhost:47680/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47680/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:40:02 INFO - PROCESS | 27391 | RSS: Main: 158392320 05:40:02 INFO - PROCESS | 27391 | 05:40:05 INFO - PROCESS | 27391 | Cycle 1(1): loaded http://localhost:47680/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47680/tests/kraken/driver.html?testName=imaging-darkroom) 05:40:06 INFO - PROCESS | 27391 | RSS: Main: 158011392 05:40:06 INFO - PROCESS | 27391 | 05:40:09 INFO - PROCESS | 27391 | Cycle 1(1): loaded http://localhost:47680/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47680/tests/kraken/driver.html?testName=imaging-desaturate) 05:40:10 INFO - PROCESS | 27391 | RSS: Main: 158179328 05:40:10 INFO - PROCESS | 27391 | 05:40:12 INFO - PROCESS | 27391 | Cycle 1(1): loaded http://localhost:47680/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47680/tests/kraken/driver.html?testName=json-parse-financial) 05:40:13 INFO - PROCESS | 27391 | RSS: Main: 158339072 05:40:13 INFO - PROCESS | 27391 | 05:40:15 INFO - PROCESS | 27391 | Cycle 1(1): loaded http://localhost:47680/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47680/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:40:15 INFO - PROCESS | 27391 | RSS: Main: 156622848 05:40:15 INFO - PROCESS | 27391 | 05:40:17 INFO - PROCESS | 27391 | Cycle 1(1): loaded http://localhost:47680/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47680/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:40:18 INFO - PROCESS | 27391 | RSS: Main: 158339072 05:40:18 INFO - PROCESS | 27391 | 05:40:20 INFO - PROCESS | 27391 | Cycle 1(1): loaded http://localhost:47680/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47680/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:40:20 INFO - PROCESS | 27391 | RSS: Main: 157552640 05:40:20 INFO - PROCESS | 27391 | 05:40:23 INFO - PROCESS | 27391 | Cycle 1(1): loaded http://localhost:47680/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47680/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:40:23 INFO - PROCESS | 27391 | RSS: Main: 157487104 05:40:23 INFO - PROCESS | 27391 | 05:40:26 INFO - PROCESS | 27391 | Cycle 1(1): loaded http://localhost:47680/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47680/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:40:27 INFO - PROCESS | 27391 | RSS: Main: 157634560 05:40:27 INFO - PROCESS | 27391 | 05:40:28 INFO - PROCESS | 27391 | Cycle 1(1): loaded http://localhost:47680/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47680/tests/kraken/driver.html?testName=ai-astar) 05:40:29 INFO - PROCESS | 27391 | RSS: Main: 160256000 05:40:29 INFO - PROCESS | 27391 | 05:40:29 INFO - PROCESS | 27391 | __start_tp_report 05:40:29 INFO - PROCESS | 27391 | _x_x_mozilla_page_load 05:40:29 INFO - PROCESS | 27391 | _x_x_mozilla_page_load_details 05:40:29 INFO - PROCESS | 27391 | |i|pagename|runs| 05:40:29 INFO - PROCESS | 27391 | |0;ai-astar;103;111;111;107;112;112;111;111;110;122 05:40:29 INFO - PROCESS | 27391 | |1;audio-beat-detection;142;119;140;172;122;148;145;117;171;116 05:40:29 INFO - PROCESS | 27391 | |2;audio-dft;161;161;161;163;161;164;161;162;162;164 05:40:29 INFO - PROCESS | 27391 | |3;audio-fft;75;77;74;173;75;75;75;75;181;76 05:40:29 INFO - PROCESS | 27391 | |4;audio-oscillator;83;84;150;85;85;84;133;84;84;151 05:40:29 INFO - PROCESS | 27391 | |5;imaging-gaussian-blur;118;118;117;117;118;117;118;117;117;117 05:40:29 INFO - PROCESS | 27391 | |6;imaging-darkroom;148;148;148;147;147;146;148;148;147;147 05:40:29 INFO - PROCESS | 27391 | |7;imaging-desaturate;95;95;97;95;96;96;95;95;95;96 05:40:29 INFO - PROCESS | 27391 | |8;json-parse-financial;51;51;52;51;52;51;52;56;50;51 05:40:29 INFO - PROCESS | 27391 | |9;json-stringify-tinderbox;60;61;61;61;64;60;61;61;61;61 05:40:29 INFO - PROCESS | 27391 | |10;stanford-crypto-aes;77;73;83;75;72;81;73;73;73;81 05:40:29 INFO - PROCESS | 27391 | |11;stanford-crypto-ccm;122;130;126;118;120;125;138;118;168;123 05:40:29 INFO - PROCESS | 27391 | |12;stanford-crypto-pbkdf2;153;159;152;155;151;151;152;152;151;152 05:40:29 INFO - PROCESS | 27391 | |13;stanford-crypto-sha256-iterative;64;64;63;61;62;62;68;65;66;62 05:40:29 INFO - PROCESS | 27391 | __end_tp_report 05:40:29 INFO - PROCESS | 27391 | __start_cc_report 05:40:29 INFO - PROCESS | 27391 | _x_x_mozilla_cycle_collect,884 05:40:29 INFO - PROCESS | 27391 | __end_cc_report 05:40:29 INFO - PROCESS | 27391 | __startTimestamp1473684029125__endTimestamp 05:40:29 INFO - PROCESS | 27391 | 05:40:29 INFO - PROCESS | 27391 | ------- Summary: start ------- 05:40:29 INFO - PROCESS | 27391 | Number of tests: 14 05:40:29 INFO - PROCESS | 27391 | 05:40:29 INFO - PROCESS | 27391 | [#0] ai-astar Cycles:10 Average:111.00 Median:111.00 stddev:4.76 (4.3%) stddev-sans-first:4.08 05:40:29 INFO - PROCESS | 27391 | Values: 103.0 111.0 111.0 107.0 112.0 112.0 111.0 111.0 110.0 122.0 05:40:29 INFO - PROCESS | 27391 | 05:40:29 INFO - PROCESS | 27391 | [#1] audio-beat-detection Cycles:10 Average:139.20 Median:143.50 stddev:20.93 (14.6%) stddev-sans-first:22.17 05:40:29 INFO - PROCESS | 27391 | Values: 142.0 119.0 140.0 172.0 122.0 148.0 145.0 117.0 171.0 116.0 05:40:29 INFO - PROCESS | 27391 | 05:40:29 INFO - PROCESS | 27391 | [#2] audio-dft Cycles:10 Average:162.00 Median:162.00 stddev:1.25 (0.8%) stddev-sans-first:1.27 05:40:29 INFO - PROCESS | 27391 | Values: 161.0 161.0 161.0 163.0 161.0 164.0 161.0 162.0 162.0 164.0 05:40:29 INFO - PROCESS | 27391 | 05:40:29 INFO - PROCESS | 27391 | [#3] audio-fft Cycles:10 Average:95.60 Median:75.00 stddev:42.95 (57.3%) stddev-sans-first:44.90 05:40:29 INFO - PROCESS | 27391 | Values: 75.0 77.0 74.0 173.0 75.0 75.0 75.0 75.0 181.0 76.0 05:40:29 INFO - PROCESS | 27391 | 05:40:29 INFO - PROCESS | 27391 | [#4] audio-oscillator Cycles:10 Average:102.30 Median:84.00 stddev:29.63 (35.3%) stddev-sans-first:30.59 05:40:29 INFO - PROCESS | 27391 | Values: 83.0 84.0 150.0 85.0 85.0 84.0 133.0 84.0 84.0 151.0 05:40:29 INFO - PROCESS | 27391 | 05:40:29 INFO - PROCESS | 27391 | [#5] imaging-gaussian-blur Cycles:10 Average:117.40 Median:117.50 stddev:0.52 (0.4%) stddev-sans-first:0.50 05:40:29 INFO - PROCESS | 27391 | Values: 118.0 118.0 117.0 117.0 118.0 117.0 118.0 117.0 117.0 117.0 05:40:29 INFO - PROCESS | 27391 | 05:40:29 INFO - PROCESS | 27391 | [#6] imaging-darkroom Cycles:10 Average:147.40 Median:148.00 stddev:0.70 (0.5%) stddev-sans-first:0.71 05:40:29 INFO - PROCESS | 27391 | Values: 148.0 148.0 148.0 147.0 147.0 146.0 148.0 148.0 147.0 147.0 05:40:29 INFO - PROCESS | 27391 | 05:40:29 INFO - PROCESS | 27391 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:95.50 stddev:0.71 (0.7%) stddev-sans-first:0.73 05:40:29 INFO - PROCESS | 27391 | Values: 95.0 95.0 97.0 95.0 96.0 96.0 95.0 95.0 95.0 96.0 05:40:29 INFO - PROCESS | 27391 | 05:40:29 INFO - PROCESS | 27391 | [#8] json-parse-financial Cycles:10 Average:51.70 Median:51.50 stddev:1.64 (3.2%) stddev-sans-first:1.72 05:40:29 INFO - PROCESS | 27391 | Values: 51.0 51.0 52.0 51.0 52.0 51.0 52.0 56.0 50.0 51.0 05:40:29 INFO - PROCESS | 27391 | 05:40:29 INFO - PROCESS | 27391 | [#9] json-stringify-tinderbox Cycles:10 Average:61.10 Median:61.00 stddev:1.10 (1.8%) stddev-sans-first:1.09 05:40:29 INFO - PROCESS | 27391 | Values: 60.0 61.0 61.0 61.0 64.0 60.0 61.0 61.0 61.0 61.0 05:40:29 INFO - PROCESS | 27391 | 05:40:29 INFO - PROCESS | 27391 | [#10] stanford-crypto-aes Cycles:10 Average:76.10 Median:76.00 stddev:4.12 (5.4%) stddev-sans-first:4.36 05:40:29 INFO - PROCESS | 27391 | Values: 77.0 73.0 83.0 75.0 72.0 81.0 73.0 73.0 73.0 81.0 05:40:29 INFO - PROCESS | 27391 | 05:40:29 INFO - PROCESS | 27391 | [#11] stanford-crypto-ccm Cycles:10 Average:128.80 Median:125.50 stddev:15.04 (12.0%) stddev-sans-first:15.75 05:40:29 INFO - PROCESS | 27391 | Values: 122.0 130.0 126.0 118.0 120.0 125.0 138.0 118.0 168.0 123.0 05:40:29 INFO - PROCESS | 27391 | 05:40:29 INFO - PROCESS | 27391 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.80 Median:152.00 stddev:2.49 (1.6%) stddev-sans-first:2.64 05:40:29 INFO - PROCESS | 27391 | Values: 153.0 159.0 152.0 155.0 151.0 151.0 152.0 152.0 151.0 152.0 05:40:29 INFO - PROCESS | 27391 | 05:40:29 INFO - PROCESS | 27391 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.70 Median:64.00 stddev:2.16 (3.4%) stddev-sans-first:2.29 05:40:29 INFO - PROCESS | 27391 | Values: 64.0 64.0 63.0 61.0 62.0 62.0 68.0 65.0 66.0 62.0 05:40:29 INFO - PROCESS | 27391 | -------- Summary: end -------- 05:40:29 INFO - PROCESS | 27391 | 05:40:30 INFO - TEST-INFO | 27391: exit 0 05:40:30 INFO - TEST-OK | kraken | took 61026ms 05:40:30 INFO - SUITE-END | took 1172s 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": 7835.668954009154, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3272.656371081037, 3362.9327413451724, 3364.6467120952298, 3371.8482668279926, 3392.440247359187, 5785.247917770689, 5859.1797148399255, 5879.647221166728, 5889.087191485316, 5923.229980102586, 5709.115087161496, 5783.884322313551, 5803.477687008169, 5829.271341082364, 5835.679037652938, 5244.344456942892, 5282.947170528303, 5344.545713614348, 5347.893042139174, 5380.300560927074, 5129.487051294878, 5133.2300154976865, 5185.662931909433, 5195.922061169085, 5233.633645644806, 5262.894742105155, 5271.262023316739, 5272.31459910213, 5279.894402111955, 5297.125974214269, 5318.946810531903, 5324.467553244684, 5330.173823057432, 5333.54664853486, 5347.25138480613, 5127.9743601282125, 5145.716985565792, 5161.845144645667, 5187.118189907725, 5199.2461093141665, 5721.342045664718, 5756.856078598027, 5777.740001699934, 5780.132980052984, 5806.1000544915605, 6221.066839973995, 6231.034189700604, 6239.0, 6274.780382686629, 6288.9056664150075, 6156.045812899008, 6175.042868355412, 6217.844553886144, 6296.6536840473755, 6321.367863213642, 5854.2096816930025, 5898.41015898411, 5899.410058994152, 5916.26046744157, 5934.495567876736, 6411.102445657519, 6436.678166091677, 6449.7097630605795, 6452.4515416189215, 6452.741890324334, 5873.471387575139, 6012.669303188279, 6021.608595441282, 6165.876682466325, 6176.536759743038, 6195.31851496335, 6229.875402491924, 6244.063390491463, 6244.594101383395, 6254.280757712831, 6124.571280010446, 6162.22972128484, 6175.783847565313, 6182.412670796267, 6198.163247961557, 6033.637981721111, 6050.122732203806, 6056.757729690851, 6186.886360455162, 6228.408301211378, 4704.506026867198, 4705.858824235278, 4709.576138147583, 4730.479647238801, 4735.171345014609, 4172.83308667656, 4196.055887425305, 4197.9370309445385, 4202.285611446062, 4221.5567365426805, 5844.47399734026, 5899.46904778572, 5963.5825492216, 5963.940360596339, 6026.096085587197, 6324.557280990379, 6332.366763323723, 6339.5562310637815, 6341.270753863272, 6387.297397286295, 6067.180930574405, 6073.453389194905, 6077.6353418795015, 6083.39166083397, 6111.54163437744, 5189.911275047399, 5198.194279886624, 5251.10731175701, 5439.401665816757, 5466.808661696821, 6226.377362263737, 6263.96868015657, 6314.147590075281, 6400.487960963112, 6401.9039714404325, 6183.96908015457, 6201.162843016135, 6201.81394558164, 6209.410106039919, 6210.906836397548, 6110.77140286259, 6330.746770129143, 6382.680865956777, 6393.456556192589, 6401.071844582602, 5971.731272092853, 5976.760929562943, 5983.820485385445, 6039.516838652985, 6082.939170608237, 6103.7253323601435, 6116.694165291807, 6124.0, 6133.141360209485, 6136.447719705248, 5868.061110222344, 5945.048792193228, 5989.970050149721, 6008.849778755566, 6022.247219097613, 5953.7023148843255, 5981.0, 6009.0, 6075.544334174866, 6085.878282434238, 5980.9401905980385, 5996.700164991646, 6009.489193418608, 6018.548608854266, 6050.364711705208, 5931.673757943355, 6052.6973651316375, 6105.419985101408, 6133.815985520441, 6139.0, 5891.882162356815, 5912.763489460373, 5960.165576819162, 6006.549508786946, 6053.122297266959], "value": 5713.86439584929, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [600.339626410948, 652.9216494020717, 668.0613737698534, 670.9832254193644, 722.7000794670212, 42053.57946420535, 46364.76817615907, 46372.76813615936, 46394.304085438744, 46411.303830442484, 46597.30104048442, 46748.298775518306, 47144.0, 47160.76419617906, 47368.52631473684, 8843.734687959355, 9128.224100951395, 9149.130832570896, 9167.083291670813, 9169.128932751379, 9177.449353038839, 9210.953945230265, 9224.723258302229, 9227.677031303905, 9233.445993240393, 9325.813483730355, 9373.765655858624, 9399.10708482693, 9419.952900235488, 9424.104710052568, 9262.6294948202, 9299.349045566812, 9300.441973481613, 9330.720078397659, 9344.532773361372, 9192.448453092837, 9200.033996430413, 9276.675316363895, 9280.164785169367, 9291.163795258459, 42401.78799105985, 42625.0, 42815.78592107019, 42939.0, 42997.140057198994, 3570.8750193743094, 3621.8732344368086, 3792.260509200707, 3862.995621138496, 4461.420015397977, 34369.31261374783, 34541.0, 34665.48001779975, 34815.4777678335, 35239.29521409583, 4516.1419330327135, 4619.907601847978, 4625.213713668684, 4700.177468942921, 4711.081339138869, 378647.0, 556047.0, 564329.0, 567622.0, 567815.0, 66821.99767003402, 67167.0, 67225.6638716813, 67348.0, 67389.0, 39294.60705392966, 70183.64908175427, 70952.64523677349, 71285.2871471289, 71368.64315678389, 18090.366837160636, 18804.153813078443, 18824.058797060094, 18884.43346699601, 18897.905510472636, 14112.223827689575, 14118.64703382403, 14174.14955102697, 14207.71584568303, 14247.287635618177, 20541.89729051345, 20629.484262893548, 20673.17307307691, 20749.377518674464, 20897.164113435294, 7524.548527088392, 7561.168271490132, 7603.353714934182, 7647.923520764833, 7759.495632783851, 1027.6557353286582, 1036.4662198967537, 1125.5610311978335, 1145.6505765741529, 1151.2574389518713, 32986.3402731944, 33035.834820825745, 33452.0, 33531.83234083814, 33659.831700841336, 1164.3072371938683, 1191.9821202681967, 1209.4738788626976, 1246.6945598328286, 1250.0437165568349, 17429.564260893327, 17510.387136450165, 17637.206325715375, 17648.470545883643, 17698.29206831712, 657860.0, 665279.0, 666523.0, 666892.0, 667948.0, 62390.0, 63388.366116339166, 63548.364516354246, 64002.0, 64158.0, 1475.7269905067596, 1503.0455660655562, 1521.2241756703938, 1560.4694403902502, 1664.192866459766, 32049.0, 32477.188070297485, 32741.345173095928, 32780.67219327872, 33446.66553334533, 55037.72481137569, 55625.0, 55665.0, 55676.44323556712, 55712.72143639256, 386871.0, 389422.0, 389621.0, 390528.0, 390695.0, 30204.395912082076, 30485.0, 30882.0, 30908.227294316923, 30964.0710778677, 51482.742586286826, 51969.22046169235, 52684.0, 53931.73034134961, 53986.73006634942, 43928.78035609802, 44619.33071004003, 45014.324785127596, 45289.09421811479, 46032.0, 41817.0, 43722.34416483819, 43862.56137438585, 44700.32949505825, 48716.26925596048, 43394.349084764384, 43789.78105109454, 44085.33871991987, 44798.88002799956, 46530.30204546867, 39205.0, 39469.0, 39778.403323949584, 40376.99057523585, 40565.59434405618, 28815.0, 29953.70046299509, 30511.54232686467, 32071.839640801645, 32250.35499290048], "value": 21812.766739382736, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [414.2026598797315, 416.39414651681795, 416.76036279139504, 417.6596074199527, 431.3486635180876, 19780.0, 20335.288264910712, 20629.587408251828, 21057.789422105776, 21298.680519792197, 20500.384988450332, 20935.47661308468, 21086.261980830674, 21655.783442165575, 21784.0, 6020.4280593343565, 6233.096201050845, 6247.0, 6250.874982500347, 6258.06129080637, 6096.573239873189, 6152.0464328029, 6156.353582873801, 6164.506839452855, 6169.568130230886, 5909.0, 6088.634681919077, 6128.11142384354, 6135.9079613805825, 6136.50907927367, 5911.940880591182, 5970.582059255832, 6016.368281330485, 6028.427299406571, 6050.757969681207, 5335.1197052486295, 5475.397706252309, 5481.095619222823, 5486.890262194774, 5492.670439773627, 24811.379715507075, 24880.87559562209, 25072.122475713444, 25310.746892531024, 25381.0, 2596.5066637338846, 2631.8552479613613, 2638.300850274679, 2711.539038363483, 2862.5992361069466, 15802.841971580252, 15961.521154365271, 16129.0, 16319.18404079803, 16778.580535486588, 2050.8256798172174, 2100.9684854727193, 2110.820580250665, 2168.902399392031, 2169.0, 81119.18880811175, 81644.0, 82064.58967705123, 83176.0, 86853.0, 11341.829872551752, 11401.657950261506, 11941.402929853646, 12294.631161065183, 12452.75094498105, 16237.756433653505, 16281.755773663404, 16351.427700030441, 16356.34574617002, 16394.0, 8784.736457906272, 8977.192052715287, 9068.22920051803, 9226.58480368385, 9250.95374523123, 8711.520866352412, 8816.677498375295, 8849.159329863782, 8923.866142007875, 9020.278377729766, 7036.753713619999, 7086.397656199178, 7090.255523170096, 7177.389921856597, 7299.635018249066, 2041.2447394464143, 2052.9486762831048, 2116.386247988097, 2118.0, 2130.158587358016, 907.4464576608264, 1057.513543769859, 1072.383379556758, 1085.9235794892131, 1109.9278546894427, 21092.0, 21096.894515527325, 21112.366629000848, 21178.576428471344, 21260.149594016068, 1042.3224903812581, 1096.8957948994832, 1105.8838821923744, 1121.7924683933336, 1131.6944425005202, 11310.264832785842, 11842.467088981017, 12130.0, 12299.569515066929, 12326.260424374566, 369183.0, 371984.0, 372783.0, 527892.0, 538246.0, 95797.0, 98418.50790746143, 99138.504307478, 99176.50411747895, 100999.49500252452, 1070.4273213830563, 1080.573173596425, 1097.6542389147232, 1110.5335758981248, 1119.9888001120048, 18689.90655046716, 19174.233030678617, 19225.32711355096, 19352.32266870653, 19394.612107758046, 43849.56150438455, 44107.77946110249, 44709.55290447054, 44827.327590086825, 46501.53498465107, 62607.37392626015, 64151.679241603495, 64692.6765366189, 66121.66939165273, 67605.0, 9042.321825863219, 9193.080691930754, 9212.723618291462, 9269.304802139732, 9648.276379271443, 10402.791944160923, 13680.93159534196, 13753.38109785082, 14007.509737159151, 14198.361073751912, 11575.479103440197, 11956.880431195577, 12031.977281930784, 12173.634790956285, 12229.082818788449, 11453.255538389978, 11881.702957426134, 12115.636530904087, 12172.0, 12371.195872268274, 11634.301941883514, 11717.707057323634, 11901.10741694359, 12191.085668574715, 12566.554846192805, 8641.956790216009, 8700.60847261862, 8778.692745753868, 9045.547722613712, 9220.538973051187, 8949.731508054769, 11777.764444710887, 11808.232464889754, 11905.107116966088, 11981.400929953641], "value": 10004.574319996913, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1555.8210805757335, 1575.708493928623, 1758.8329108734674, 1858.9814101858985, 1860.8604354673403, 8128.9187108129045, 9014.098590140991, 9033.412828166165, 9688.515574221286, 9699.854502182472, 9098.363114581982, 9466.574004169812, 9470.100340467661, 9472.66845660402, 9487.667931622394, 2503.8372505787156, 2531.3798119460757, 2531.835430697008, 2534.024400605771, 2536.2137737301405, 2493.6259561065895, 2543.504016716741, 2567.7047139579, 2568.8458692478416, 2576.8711564421797, 2483.689538807649, 2484.8633325167107, 2553.157458038852, 2556.3481312265344, 2569.9100531481404, 2437.9390515237174, 2466.839655422401, 2486.627005949104, 2501.262127672323, 2514.421683012899, 2408.1932552594903, 2426.46617744096, 2432.3067925641285, 2448.791852692523, 2467.5188338274043, 9458.57436415363, 9546.61813527458, 9567.952160239223, 9571.904280957171, 9573.234141268682, 5523.337199536041, 5542.473465020817, 5576.804811831607, 5584.804531841407, 5596.972015139898, 3445.5693038370205, 3535.1338921964025, 3548.0, 3553.9111522211892, 3604.098975256186, 1187.2935603316014, 1242.714175739576, 1249.425264378387, 1250.2998320940303, 1275.7576060548465, 13171.934140329333, 13182.86817131826, 13212.735745285137, 13266.336683165802, 13305.86694133056, 4814.181589129857, 4943.9864827710235, 4951.430585482643, 5011.523905228997, 5024.723640199824, 11170.553177872956, 11171.553137874556, 11225.66323010311, 11235.0, 11272.66182014541, 5575.804846830341, 5585.134304182858, 5588.21764952907, 5601.075822489325, 5616.185653080281, 4437.068215674744, 4992.301077849104, 5070.391553013662, 5118.97440512795, 5126.615503837227, 4212.410262563244, 4242.278812601865, 4284.400183974246, 4301.65586753059, 4323.832565207376, 1306.8562458129597, 1326.2241588670543, 1337.8929685625126, 1342.8455727591256, 1347.8113064171023, 1038.2213339995003, 1050.8844027157006, 1061.4586560853866, 1065.6217042949797, 1067.685032915292, 6252.468540174045, 6329.8734025320155, 6407.263164736022, 6410.423061924449, 6535.313792051861, 494.8713334533047, 511.6878703990563, 519.0864079220523, 520.8177138001745, 522.5532169994676, 4492.966617677952, 4508.887277818015, 4522.932156017662, 4585.839495617637, 4596.88507787308, 10836.891631083747, 11332.490037948312, 11335.716607084723, 11365.374904380338, 11366.318020918607, 10943.343399396063, 11321.660350189508, 11377.601783937522, 11629.59296424621, 11673.124515661359, 8617.439866408664, 8831.514266715392, 8879.91120088791, 8890.244329232088, 8894.421862578782, 6414.61512309263, 6658.3674550917585, 6667.197785235483, 6687.665616719048, 6801.5238933274195, 10819.459027048773, 10898.291611045257, 10903.672889813319, 10914.563417463212, 11008.39453830047, 10715.142788577052, 10800.783984320427, 11027.779444410906, 11064.944675276573, 11094.778104438028, 7688.654010569426, 8241.0, 8256.504609723435, 8398.28614567744, 8439.156084391758, 9199.218066464158, 9233.169014788702, 9244.306676999118, 9254.4910029949, 9269.629214831331, 8342.499450033018, 8373.288270497076, 8430.15698430152, 8498.74503764888, 8552.273056790242, 7847.372210223054, 7857.921420785948, 7869.567173805495, 7947.364210863001, 8001.439899207, 7523.661435235537, 7600.961995189989, 7614.6954121836725, 7629.54222746637, 7738.458307918391, 5767.567432442667, 5793.246877905845, 5821.359650438364, 5851.063829787214, 5867.295924489089, 5545.0, 5689.0, 5703.287089113861, 5708.315002199763, 5804.506616937439], "value": 4901.197664937074, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [335.8941933291013, 346.7087646377043, 350.2836698950646, 358.7417059717003, 365.4518222666001, 5860.677662728548, 5892.587518873681, 5893.381194974525, 5912.467877890984, 5946.662001049762, 5772.942270577303, 5776.277965254343, 5778.133280007991, 5797.855053623651, 5809.622374545652, 4353.216421044219, 4486.214912390335, 4555.840545580905, 4588.380568623244, 4589.24277494213, 4564.041551274237, 4580.656450766189, 4586.495485496609, 4603.539646035387, 4611.400517932664, 4571.679982401233, 4579.771011449431, 4585.72485650862, 4588.816447342102, 4602.2636378179495, 4406.294992801152, 4456.9331460028125, 4502.077074199781, 4517.367568540407, 4542.295944128666, 3929.25344184604, 3952.616584195537, 3958.1094253792953, 3970.7816070116132, 3975.860844870416, 5618.831435056954, 5626.324841019063, 5629.80295689653, 5646.435356464362, 5697.604086998712, 5117.130087885069, 5157.355330583677, 5174.042802081627, 5179.249008893689, 5200.92198617021, 6031.155638210654, 6063.878722425527, 6088.786892458743, 6107.175531303306, 6127.387261273837, 4305.117450922585, 4405.471343438808, 4432.84485043022, 4435.135148646015, 4435.445569303837, 6142.447179753799, 6265.248170219603, 6319.399657032574, 6331.905021424683, 6369.299377068519, 4387.188370151468, 4444.28891377378, 4450.488193857683, 4453.732776033448, 4463.129689710475, 4520.95479045212, 4534.977325113353, 4541.205289074399, 4548.977255113769, 4570.702904311209, 5144.30551875492, 5157.252198431206, 5204.8178313758835, 5206.375234971828, 5248.6588371755715, 6062.666553339609, 6089.817305480843, 6104.26748790148, 6106.877862442726, 6147.262328520606, 1539.698954383544, 1549.124744519343, 1556.758702401107, 1562.9218539073001, 1563.6169138560897, 955.2405837359316, 988.9961688885787, 998.2513115163628, 1002.9348092373972, 1023.1865666794915, 5234.450382709837, 5251.553617942518, 5259.632495551108, 5307.0545847055255, 5314.4951229633125, 5453.291072160594, 5475.1110866750905, 5553.861153471195, 5568.359638641608, 5575.0, 5033.3205017322925, 5328.413874473805, 5342.652727572696, 5367.194920761917, 5422.728863556807, 2956.9704302956693, 2975.0925967580097, 2980.4039192161654, 2983.537551679493, 2992.5660779186896, 5512.173174023928, 5627.071533196977, 5713.22871412354, 5713.942860571341, 5727.6849773262875, 5566.972165139148, 5608.214849921014, 5618.634788738719, 5629.6903670298525, 5706.828795136153, 5670.801521946794, 5679.517241034476, 5686.549929767965, 5703.119924438215, 5729.799457018984, 5311.681299122065, 5469.42571030052, 5555.9721451531595, 5562.499375056264, 5574.888502229852, 5429.402765695691, 5458.717201457734, 5467.234587157722, 5542.612017158922, 5544.889102218014, 6208.72060757258, 6309.0, 6500.869982600227, 6547.70535325921, 6561.573497722824, 4358.520562738159, 4414.911701766011, 4419.337099434982, 4420.447444069491, 4424.650481603142, 6117.235345581802, 6275.8117256480555, 6335.90496142549, 6345.73085382916, 6367.777127800505, 6075.756969721162, 6088.391160883876, 6104.306894328867, 6151.661658608819, 6183.783567575113, 5858.882822343444, 6117.388261173847, 6121.571489995657, 6150.630962142286, 6175.84560385994, 6079.574429789872, 6091.786787462417, 6112.388761123852, 6121.112438696453, 6160.322364539987, 6174.475169610633, 6218.906716399167, 6240.783047305913, 6246.219222597176, 6274.1843560336865, 5729.742161602821, 6025.78909738157, 6032.336442991355, 6055.576109672281, 6055.697215139137], "value": 4516.311572991265, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [838.0237023867192, 859.9527026013567, 893.5710858787781, 912.2610685344869, 924.0528458330215, 16248.675026499477, 17541.91229043853, 17650.293988240486, 17806.554836129104, 17881.374151904685, 15725.0, 15765.448209312677, 15949.840501594981, 16057.678846423065, 16355.83644163558, 884.884964954555, 892.1168043636818, 913.6619450803212, 915.9587818548148, 917.3257655623112, 917.3532659475087, 918.7794929217007, 919.0901008002063, 920.0385597051064, 921.870938068671, 980.4509474694158, 981.9950900245489, 982.4056445850265, 982.4252812104927, 984.4929861121528, 956.8325543030012, 965.3821554205314, 967.690339091491, 967.762898089969, 968.7965527239253, 713.6752777486283, 714.2714431280065, 714.8856183010745, 714.9428045756379, 715.677944924786, 17262.395816146374, 17387.91345038591, 17486.562835929075, 17533.736993945102, 17550.0, 14255.0, 14572.854271457256, 15152.242387880671, 15152.924235378752, 15601.609959751097, 14757.335919883632, 14822.555323340317, 14907.627309317246, 14971.625709357244, 15029.323680434407, 4854.320395144682, 4867.440244371907, 4877.780499877514, 4882.584980276681, 4900.705957642553, 89995.55002224947, 90083.54958225232, 90795.0, 91111.5444422787, 91926.0, 12375.0, 12464.688382790322, 12608.054395920342, 12610.243385396905, 12747.61757147287, 22886.42783930415, 23032.88483557605, 23103.653445198335, 23184.65223021656, 23427.297181084596, 8936.910630893737, 9059.547022648841, 9106.499142547224, 9202.079792020879, 9293.30300227472, 5156.355455568054, 5229.947700523022, 5261.0793111205385, 5276.34045744282, 5312.840614781563, 3629.2197177606413, 3683.5211422514903, 3693.649103335179, 3723.2925744108534, 3738.0467980664757, 649.831043928582, 653.3825534869598, 683.8700646877078, 716.2228981554968, 719.4783781758183, 679.7756740275697, 747.7158679701679, 791.7569416016913, 819.4919150126959, 825.4551995682821, 9086.727398178065, 10524.789504209873, 10555.977457132634, 10650.680479585624, 10680.946595266974, 642.2517766801717, 685.533836990851, 771.1941021632408, 789.5460110436522, 803.3894240377356, 4934.259861020875, 5105.923411148836, 5142.794288228504, 5166.483351664879, 5178.559822415062, 74048.0, 74836.0, 76385.0, 115451.0, 125420.0, 108132.45933770438, 109871.45064274628, 110027.0, 110586.0, 111674.99999999837, 94498.0, 94987.0, 97993.51003244938, 99075.50462247642, 102245.0, 9775.706728798148, 10090.671246829666, 10092.596296148366, 10134.594616215563, 10212.846807297748, 57507.712461437426, 57617.13574296305, 58247.70876145592, 58776.41223587709, 59357.40642593519, 81939.5903020481, 85236.57381713051, 86089.0, 86873.0, 89792.5510372444, 13250.271235082164, 13272.734545309233, 13586.048976571545, 13636.181829090287, 13639.249841258827, 14694.412223510939, 14823.555293341216, 14826.40694372213, 14915.328810203784, 16403.589910251863, 15348.23258837076, 15663.216839158224, 15855.603609909844, 16104.919475402548, 16375.0, 15221.847781522043, 15373.615659608151, 15455.84544154544, 15742.0, 16297.266623001757, 15020.0, 15212.011219271155, 15964.920175399513, 16235.269412876685, 16425.342986280415, 12545.686357841127, 12669.272651123743, 12748.298843563693, 12913.418896149507, 13597.79603305971, 13074.215547067206, 13210.80183797263, 13320.733585328433, 13371.732565348833, 13581.524646637321], "value": 8384.703936374606, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1504.5999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 111.0, 111.0, 107.0, 112.0, 112.0, 111.0, 111.0, 110.0, 122.0], "value": 111.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 119.0, 140.0, 172.0, 122.0, 148.0, 145.0, 117.0, 171.0, 116.0], "value": 139.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 161.0, 163.0, 161.0, 164.0, 161.0, 162.0, 162.0, 164.0], "value": 162.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 74.0, 173.0, 75.0, 75.0, 75.0, 75.0, 181.0, 76.0], "value": 95.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 150.0, 85.0, 85.0, 84.0, 133.0, 84.0, 84.0, 151.0], "value": 102.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 117.0, 117.0, 118.0, 117.0, 118.0, 117.0, 117.0, 117.0], "value": 117.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 148.0, 147.0, 147.0, 146.0, 148.0, 148.0, 147.0, 147.0], "value": 147.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 97.0, 95.0, 96.0, 96.0, 95.0, 95.0, 95.0, 96.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 51.0, 52.0, 51.0, 52.0, 51.0, 52.0, 56.0, 50.0, 51.0], "value": 51.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 61.0, 61.0, 64.0, 60.0, 61.0, 61.0, 61.0, 61.0], "value": 61.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 73.0, 83.0, 75.0, 72.0, 81.0, 73.0, 73.0, 73.0, 81.0], "value": 76.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 130.0, 126.0, 118.0, 120.0, 125.0, 138.0, 118.0, 168.0, 123.0], "value": 128.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 159.0, 152.0, 155.0, 151.0, 151.0, 152.0, 152.0, 151.0, 152.0], "value": 152.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 63.0, 61.0, 62.0, 62.0, 68.0, 65.0, 66.0, 62.0], "value": 63.7, "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.434731Z] 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: 1189s; CPU: 13.0%; Read bytes: 266240; Write bytes: 328015872; Read time: 16; Write time: 234424 05:40:30 INFO - TinderboxPrint: CPU usage
13.3% 05:40:30 INFO - TinderboxPrint: I/O read bytes / time
266,240 / 16 05:40:30 INFO - TinderboxPrint: I/O write bytes / time
328,015,872 / 234,424 05:40:30 INFO - TinderboxPrint: CPU idle
8,222.7 (86.7%) 05:40:30 INFO - TinderboxPrint: CPU user
1,206.8 (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: 78729216; Read time: 0; Write time: 76244 05:40:30 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 266240; Write bytes: 248668160; Read time: 16; Write time: 158148 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/dromaeojs-e10s_errorsummary.log ... 05:40:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:40:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:40:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:40:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:40:32 INFO - (blobuploader) - INFO - Done attempting. 05:40:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:40:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:40:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:40:41 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:40:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:40:41 INFO - (blobuploader) - INFO - Done attempting. 05:40:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:40:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:40:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:40:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:40:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:40:41 INFO - (blobuploader) - INFO - Done attempting. 05:40:41 INFO - (blobuploader) - INFO - Iteration through files over. 05:40:41 INFO - Return code: 0 05:40:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:40:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:40:41 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/07477e5e5c4e69ee6185ad84360a1d9191a97b29dfcfa05a7e25651d597b5ea67fca8b3641bbf9fe18fa07db8ba8cd04cc31484e0cf3746e78a954512b156ac4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/85c5cae3dcca7e160ed80c151b9aaa260b72ef2b29f1103e71243689ea990d53bbb95f3f8fb6bf7c26420edc83fec44180bba941754a6175a2bc1bc104106790", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2c9ac3bcf6c3d80d45a4b84a0412a429c7a5df397c7df94af30729057633aae879cafe9df555b9dc41af6875eea41cd9aa33a79d32ee013dfb7aac7f1692d17d"} 05:40:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:40:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:40:41 INFO - Contents: 05:40:41 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/07477e5e5c4e69ee6185ad84360a1d9191a97b29dfcfa05a7e25651d597b5ea67fca8b3641bbf9fe18fa07db8ba8cd04cc31484e0cf3746e78a954512b156ac4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/85c5cae3dcca7e160ed80c151b9aaa260b72ef2b29f1103e71243689ea990d53bbb95f3f8fb6bf7c26420edc83fec44180bba941754a6175a2bc1bc104106790", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2c9ac3bcf6c3d80d45a4b84a0412a429c7a5df397c7df94af30729057633aae879cafe9df555b9dc41af6875eea41cd9aa33a79d32ee013dfb7aac7f1692d17d"} 05:40:41 INFO - Running post-run listener: copy_logs_to_upload_dir 05:40:41 INFO - Copying logs to upload dir... 05:40:41 INFO - mkdir: /builds/slave/test/build/upload/logs 05:40:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1682.306998 ========= master_lag: 0.29 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 2 secs) (at 2016-09-12 05:40:41.575746) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-12 05:40:41.580770) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1473669467.785587-1039223074 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/07477e5e5c4e69ee6185ad84360a1d9191a97b29dfcfa05a7e25651d597b5ea67fca8b3641bbf9fe18fa07db8ba8cd04cc31484e0cf3746e78a954512b156ac4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/85c5cae3dcca7e160ed80c151b9aaa260b72ef2b29f1103e71243689ea990d53bbb95f3f8fb6bf7c26420edc83fec44180bba941754a6175a2bc1bc104106790", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2c9ac3bcf6c3d80d45a4b84a0412a429c7a5df397c7df94af30729057633aae879cafe9df555b9dc41af6875eea41cd9aa33a79d32ee013dfb7aac7f1692d17d"} 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.011250 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/07477e5e5c4e69ee6185ad84360a1d9191a97b29dfcfa05a7e25651d597b5ea67fca8b3641bbf9fe18fa07db8ba8cd04cc31484e0cf3746e78a954512b156ac4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/85c5cae3dcca7e160ed80c151b9aaa260b72ef2b29f1103e71243689ea990d53bbb95f3f8fb6bf7c26420edc83fec44180bba941754a6175a2bc1bc104106790", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2c9ac3bcf6c3d80d45a4b84a0412a429c7a5df397c7df94af30729057633aae879cafe9df555b9dc41af6875eea41cd9aa33a79d32ee013dfb7aac7f1692d17d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-12 05:40:41.609392) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 05:40:41.609736) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1473669467.785587-1039223074 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004540 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 05:40:41.667499) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-12 05:40:41.667869) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-12 05:40:41.668329) ========= ========= Total master_lag: 0.49 =========