builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-056 starttime: 1471477724.92 results: success (0) buildid: 20160817134614 builduid: 3741967821444b2f98d57ee7edaf35a6 revision: 6e2bee015c3558d815c136c03ce816bfc38c70c8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-17 16:48:44.924946) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-17 16:48:44.925429) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-17 16:48:44.925735) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-17 16:48:44.942239) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-17 16:48:44.942505) ========= 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=52faae308a556c440401f8e5000001e8-1471477723.638147-1164050914 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-17 16:48:45-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 82.1M=0s 2016-08-17 16:48:45 (82.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.212089 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-17 16:48:45.199725) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-17 16:48:45.200052) ========= 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=52faae308a556c440401f8e5000001e8-1471477723.638147-1164050914 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.071834 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-17 16:48:45.284317) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-17 16:48:45.284704) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 6e2bee015c3558d815c136c03ce816bfc38c70c8 --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 6e2bee015c3558d815c136c03ce816bfc38c70c8 --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=52faae308a556c440401f8e5000001e8-1471477723.638147-1164050914 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-17 16:48:45,340 truncating revision to first 12 chars 2016-08-17 16:48:45,340 Setting DEBUG logging. 2016-08-17 16:48:45,340 attempt 1/10 2016-08-17 16:48:45,340 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/6e2bee015c35?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-17 16:48:45,859 unpacking tar archive at: mozilla-aurora-6e2bee015c35/testing/mozharness/ program finished with exit code 0 elapsedTime=1.022006 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-17 16:48:46.317577) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-17 16:48:46.317909) ========= script_repo_revision: 6e2bee015c3558d815c136c03ce816bfc38c70c8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-17 16:48:46.318303) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-17 16:48:46.318567) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-17 16:48:46.329134) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 12 secs) (at 2016-08-17 16:48:46.329403) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', '--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=52faae308a556c440401f8e5000001e8-1471477723.638147-1164050914 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:48:48 INFO - MultiFileLogger online at 20160817 16:48:48 in /builds/slave/test 16:48:48 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 16:48:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:48:48 INFO - {'append_to_log': False, 16:48:48 INFO - 'base_work_dir': '/builds/slave/test', 16:48:48 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 16:48:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:48:48 INFO - 'branch': 'Mozilla-Aurora', 16:48:48 INFO - 'buildbot_json_path': 'buildprops.json', 16:48:48 INFO - 'config_files': ('talos/linux_config.py',), 16:48:48 INFO - 'default_actions': ('clobber', 16:48:48 INFO - 'read-buildbot-config', 16:48:48 INFO - 'download-and-extract', 16:48:48 INFO - 'populate-webroot', 16:48:48 INFO - 'create-virtualenv', 16:48:48 INFO - 'install', 16:48:48 INFO - 'run-tests'), 16:48:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:48:48 INFO - 'download_minidump_stackwalk': True, 16:48:48 INFO - 'download_symbols': 'ondemand', 16:48:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:48:48 INFO - 'tooltool.py': '/tools/tooltool.py', 16:48:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:48:48 INFO - '/tools/misc-python/virtualenv.py')}, 16:48:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:48:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:48:48 INFO - 'installer_path': 'installer.exe', 16:48:48 INFO - 'log_level': 'info', 16:48:48 INFO - 'log_name': 'talos', 16:48:48 INFO - 'log_to_console': True, 16:48:48 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:48:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:48:48 INFO - 'opt_config_files': (), 16:48:48 INFO - 'pip_index': False, 16:48:48 INFO - 'sps_profile': False, 16:48:48 INFO - 'sps_profile_interval': 0, 16:48:48 INFO - 'suite': 'dromaeojs', 16:48:48 INFO - 'system_bits': '32', 16:48:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:48:48 INFO - 'title': 'talos-linux64-ix-056', 16:48:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:48:48 INFO - 'use_talos_json': True, 16:48:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:48:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:48:48 INFO - 'work_dir': 'build'} 16:48:48 INFO - [mozharness: 2016-08-17 23:48:48.079827Z] Running clobber step. 16:48:48 INFO - Running pre-action listener: _resource_record_pre_action 16:48:48 INFO - Running main action method: clobber 16:48:48 INFO - rmtree: /builds/slave/test/build 16:48:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:48:52 INFO - Running post-action listener: _resource_record_post_action 16:48:52 INFO - [mozharness: 2016-08-17 23:48:52.494026Z] Finished clobber step (success) 16:48:52 INFO - [mozharness: 2016-08-17 23:48:52.494137Z] Running read-buildbot-config step. 16:48:52 INFO - Running pre-action listener: _resource_record_pre_action 16:48:52 INFO - Running main action method: read_buildbot_config 16:48:52 INFO - Using buildbot properties: 16:48:52 INFO - { 16:48:52 INFO - "project": "", 16:48:52 INFO - "product": "firefox", 16:48:52 INFO - "script_repo_revision": "production", 16:48:52 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 16:48:52 INFO - "repository": "", 16:48:52 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 16:48:52 INFO - "buildid": "20160817134614", 16:48:52 INFO - "pgo_build": "True", 16:48:52 INFO - "basedir": "/builds/slave/test", 16:48:52 INFO - "buildnumber": 679, 16:48:52 INFO - "slavename": "talos-linux64-ix-056", 16:48:52 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 16:48:52 INFO - "platform": "ubuntu64_hw", 16:48:52 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 16:48:52 INFO - "branch": "mozilla-aurora", 16:48:52 INFO - "repo_path": "releases/mozilla-aurora", 16:48:52 INFO - "revision": "6e2bee015c3558d815c136c03ce816bfc38c70c8", 16:48:52 INFO - "stage_platform": "linux64-pgo", 16:48:52 INFO - "builduid": "3741967821444b2f98d57ee7edaf35a6", 16:48:52 INFO - "slavebuilddir": "test" 16:48:52 INFO - } 16:48:52 INFO - Found installer url https://queue.taskcluster.net/v1/task/S5AIVV8nTSKNIcNjBUF9ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 16:48:52 INFO - Running post-action listener: _resource_record_post_action 16:48:52 INFO - [mozharness: 2016-08-17 23:48:52.497159Z] Finished read-buildbot-config step (success) 16:48:52 INFO - [mozharness: 2016-08-17 23:48:52.497259Z] Running download-and-extract step. 16:48:52 INFO - Running pre-action listener: _resource_record_pre_action 16:48:52 INFO - Running main action method: download_and_extract 16:48:52 INFO - mkdir: /builds/slave/test/build/tests 16:48:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:48:52 INFO - https://queue.taskcluster.net/v1/task/S5AIVV8nTSKNIcNjBUF9ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 16:48:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S5AIVV8nTSKNIcNjBUF9ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 16:48:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S5AIVV8nTSKNIcNjBUF9ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 16:48:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S5AIVV8nTSKNIcNjBUF9ww/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 16:48:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S5AIVV8nTSKNIcNjBUF9ww/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 16:48:52 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S5AIVV8nTSKNIcNjBUF9ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 16:48:52 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 16:48:52 INFO - retry: Failed, sleeping 30 seconds before retrying 16:49:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S5AIVV8nTSKNIcNjBUF9ww/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 16:49:22 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S5AIVV8nTSKNIcNjBUF9ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 16:49:22 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 16:49:22 INFO - retry: Failed, sleeping 60 seconds before retrying 16:50:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S5AIVV8nTSKNIcNjBUF9ww/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 16:50:22 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S5AIVV8nTSKNIcNjBUF9ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 16:50:22 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 16:50:22 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S5AIVV8nTSKNIcNjBUF9ww/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! 16:50:22 INFO - Caught exception: HTTP Error 404: Not Found 16:50:22 INFO - Caught exception: HTTP Error 404: Not Found 16:50:22 INFO - Caught exception: HTTP Error 404: Not Found 16:50:22 INFO - trying https://queue.taskcluster.net/v1/task/S5AIVV8nTSKNIcNjBUF9ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 16:50:22 INFO - Downloading https://queue.taskcluster.net/v1/task/S5AIVV8nTSKNIcNjBUF9ww/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 16:50:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/S5AIVV8nTSKNIcNjBUF9ww/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 16:50:23 INFO - Downloaded 1361 bytes. 16:50:23 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 16:50:23 INFO - Using the following test package requirements: 16:50:23 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 16:50:23 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 16:50:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 16:50:23 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 16:50:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 16:50:23 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 16:50:23 INFO - u'jsshell-linux-x86_64.zip'], 16:50:23 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 16:50:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 16:50:23 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 16:50:23 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 16:50:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 16:50:23 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 16:50:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 16:50:23 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 16:50:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 16:50:23 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 16:50:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 16:50:23 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:50:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:50:23 INFO - https://queue.taskcluster.net/v1/task/S5AIVV8nTSKNIcNjBUF9ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:50:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S5AIVV8nTSKNIcNjBUF9ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 16:50:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S5AIVV8nTSKNIcNjBUF9ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 16:50:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S5AIVV8nTSKNIcNjBUF9ww/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 16:50:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S5AIVV8nTSKNIcNjBUF9ww/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 16:50:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S5AIVV8nTSKNIcNjBUF9ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 16:50:23 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 16:50:23 INFO - retry: Failed, sleeping 30 seconds before retrying 16:50:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S5AIVV8nTSKNIcNjBUF9ww/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 16:50:53 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S5AIVV8nTSKNIcNjBUF9ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 16:50:53 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 16:50:53 INFO - retry: Failed, sleeping 60 seconds before retrying 16:51:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S5AIVV8nTSKNIcNjBUF9ww/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 16:51:53 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S5AIVV8nTSKNIcNjBUF9ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 16:51:53 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 16:51:53 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S5AIVV8nTSKNIcNjBUF9ww/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! 16:51:53 INFO - Caught exception: HTTP Error 404: Not Found 16:51:53 INFO - Caught exception: HTTP Error 404: Not Found 16:51:53 INFO - Caught exception: HTTP Error 404: Not Found 16:51:53 INFO - trying https://queue.taskcluster.net/v1/task/S5AIVV8nTSKNIcNjBUF9ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 16:51:53 INFO - Downloading https://queue.taskcluster.net/v1/task/S5AIVV8nTSKNIcNjBUF9ww/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 16:51:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/S5AIVV8nTSKNIcNjBUF9ww/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 16:51:54 INFO - Downloaded 21766095 bytes. 16:51:54 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'] 16:51:54 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 16:51:55 INFO - Return code: 0 16:51:55 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 16:51:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:51:55 INFO - https://queue.taskcluster.net/v1/task/S5AIVV8nTSKNIcNjBUF9ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:51:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S5AIVV8nTSKNIcNjBUF9ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 16:51:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S5AIVV8nTSKNIcNjBUF9ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 16:51:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S5AIVV8nTSKNIcNjBUF9ww/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 16:51:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S5AIVV8nTSKNIcNjBUF9ww/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 16:51:55 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S5AIVV8nTSKNIcNjBUF9ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 16:51:55 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 16:51:55 INFO - retry: Failed, sleeping 30 seconds before retrying 16:52:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S5AIVV8nTSKNIcNjBUF9ww/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 16:52:25 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S5AIVV8nTSKNIcNjBUF9ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 16:52:25 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 16:52:25 INFO - retry: Failed, sleeping 60 seconds before retrying 16:53:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S5AIVV8nTSKNIcNjBUF9ww/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 16:53:25 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S5AIVV8nTSKNIcNjBUF9ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 16:53:25 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 16:53:25 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S5AIVV8nTSKNIcNjBUF9ww/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! 16:53:25 INFO - Caught exception: HTTP Error 404: Not Found 16:53:25 INFO - Caught exception: HTTP Error 404: Not Found 16:53:25 INFO - Caught exception: HTTP Error 404: Not Found 16:53:25 INFO - trying https://queue.taskcluster.net/v1/task/S5AIVV8nTSKNIcNjBUF9ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 16:53:25 INFO - Downloading https://queue.taskcluster.net/v1/task/S5AIVV8nTSKNIcNjBUF9ww/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 16:53:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/S5AIVV8nTSKNIcNjBUF9ww/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 16:53:26 INFO - Downloaded 21766095 bytes. 16:53:26 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'] 16:53:26 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 16:53:27 INFO - Return code: 0 16:53:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:53:27 INFO - https://queue.taskcluster.net/v1/task/S5AIVV8nTSKNIcNjBUF9ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 16:53:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S5AIVV8nTSKNIcNjBUF9ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 16:53:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S5AIVV8nTSKNIcNjBUF9ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 16:53:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S5AIVV8nTSKNIcNjBUF9ww/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 16:53:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S5AIVV8nTSKNIcNjBUF9ww/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 16:53:27 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S5AIVV8nTSKNIcNjBUF9ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 16:53:27 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 16:53:27 INFO - retry: Failed, sleeping 30 seconds before retrying 16:53:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S5AIVV8nTSKNIcNjBUF9ww/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 16:53:57 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S5AIVV8nTSKNIcNjBUF9ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 16:53:57 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 16:53:57 INFO - retry: Failed, sleeping 60 seconds before retrying 16:54:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S5AIVV8nTSKNIcNjBUF9ww/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 16:54:57 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S5AIVV8nTSKNIcNjBUF9ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 16:54:57 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 16:54:57 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S5AIVV8nTSKNIcNjBUF9ww/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! 16:54:57 INFO - Caught exception: HTTP Error 404: Not Found 16:54:57 INFO - Caught exception: HTTP Error 404: Not Found 16:54:57 INFO - Caught exception: HTTP Error 404: Not Found 16:54:57 INFO - trying https://queue.taskcluster.net/v1/task/S5AIVV8nTSKNIcNjBUF9ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 16:54:57 INFO - Downloading https://queue.taskcluster.net/v1/task/S5AIVV8nTSKNIcNjBUF9ww/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 16:54:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/S5AIVV8nTSKNIcNjBUF9ww/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 16:54:58 INFO - Downloaded 14413723 bytes. 16:54:58 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'] 16:54:58 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 16:54:58 INFO - Return code: 0 16:54:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:54:58 INFO - https://queue.taskcluster.net/v1/task/S5AIVV8nTSKNIcNjBUF9ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 16:54:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S5AIVV8nTSKNIcNjBUF9ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 16:54:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S5AIVV8nTSKNIcNjBUF9ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 16:54:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S5AIVV8nTSKNIcNjBUF9ww/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 16:54:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S5AIVV8nTSKNIcNjBUF9ww/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 16:54:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S5AIVV8nTSKNIcNjBUF9ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 16:54:58 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 16:54:58 INFO - retry: Failed, sleeping 30 seconds before retrying 16:55:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S5AIVV8nTSKNIcNjBUF9ww/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 16:55:28 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S5AIVV8nTSKNIcNjBUF9ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 16:55:28 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 16:55:28 INFO - retry: Failed, sleeping 60 seconds before retrying 16:56:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S5AIVV8nTSKNIcNjBUF9ww/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 16:56:28 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S5AIVV8nTSKNIcNjBUF9ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 16:56:28 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 16:56:28 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S5AIVV8nTSKNIcNjBUF9ww/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! 16:56:28 INFO - Caught exception: HTTP Error 404: Not Found 16:56:28 INFO - Caught exception: HTTP Error 404: Not Found 16:56:28 INFO - Caught exception: HTTP Error 404: Not Found 16:56:28 INFO - trying https://queue.taskcluster.net/v1/task/S5AIVV8nTSKNIcNjBUF9ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 16:56:28 INFO - Downloading https://queue.taskcluster.net/v1/task/S5AIVV8nTSKNIcNjBUF9ww/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 16:56:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/S5AIVV8nTSKNIcNjBUF9ww/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 16:56:30 INFO - Downloaded 63758600 bytes. 16:56:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/S5AIVV8nTSKNIcNjBUF9ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 16:56:30 INFO - mkdir: /builds/slave/test/properties 16:56:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:56:30 INFO - Writing to file /builds/slave/test/properties/build_url 16:56:30 INFO - Contents: 16:56:30 INFO - build_url:https://queue.taskcluster.net/v1/task/S5AIVV8nTSKNIcNjBUF9ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 16:56:30 INFO - Running post-action listener: _resource_record_post_action 16:56:30 INFO - Running post-action listener: set_extra_try_arguments 16:56:30 INFO - [mozharness: 2016-08-17 23:56:30.802500Z] Finished download-and-extract step (success) 16:56:30 INFO - [mozharness: 2016-08-17 23:56:30.802736Z] Running populate-webroot step. 16:56:30 INFO - Running pre-action listener: _resource_record_pre_action 16:56:30 INFO - Running main action method: populate_webroot 16:56:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:56:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:56:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:56:30 INFO - '--tppagecycles', 16:56:30 INFO - '7'], 16:56:30 INFO - 'tests': ['tsvgm']}}, 16:56:30 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:56:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:56:30 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:56:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:56:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:56:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:56:30 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:56:30 INFO - 'talos_options': ['--disable-e10s'], 16:56:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:56:30 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:56:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:56:30 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:56:30 INFO - 'talos_options': ['--disable-e10s'], 16:56:30 INFO - 'tests': ['damp', 'tps']}, 16:56:30 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:56:30 INFO - 'tests': ['damp', 'tps']}, 16:56:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:56:30 INFO - 'tests': ['dromaeo_dom']}, 16:56:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:56:30 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:56:30 INFO - 'tests': ['basic_compositor_video']}, 16:56:30 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:56:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:56:30 INFO - 'tests': ['a11yr', 16:56:30 INFO - 'ts_paint', 16:56:30 INFO - 'tpaint', 16:56:30 INFO - 'sessionrestore', 16:56:30 INFO - 'sessionrestore_no_auto_restore', 16:56:30 INFO - 'tabpaint']}, 16:56:30 INFO - 'other-e10s': {'tests': ['a11yr', 16:56:30 INFO - 'ts_paint', 16:56:30 INFO - 'tpaint', 16:56:30 INFO - 'sessionrestore', 16:56:30 INFO - 'sessionrestore_no_auto_restore', 16:56:30 INFO - 'tabpaint']}, 16:56:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:56:30 INFO - 'ts_paint', 16:56:30 INFO - 'tpaint', 16:56:30 INFO - 'sessionrestore', 16:56:30 INFO - 'sessionrestore_no_auto_restore', 16:56:30 INFO - 'tabpaint']}, 16:56:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:56:30 INFO - 'ts_paint', 16:56:30 INFO - 'tpaint', 16:56:30 INFO - 'sessionrestore', 16:56:30 INFO - 'sessionrestore_no_auto_restore', 16:56:30 INFO - 'tabpaint']}, 16:56:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:56:30 INFO - 'tests': ['a11yr', 16:56:30 INFO - 'ts_paint', 16:56:30 INFO - 'tpaint', 16:56:30 INFO - 'sessionrestore', 16:56:30 INFO - 'sessionrestore_no_auto_restore', 16:56:30 INFO - 'tabpaint']}, 16:56:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:56:30 INFO - 'tests': ['a11yr', 16:56:30 INFO - 'ts_paint', 16:56:30 INFO - 'tpaint', 16:56:30 INFO - 'sessionrestore', 16:56:30 INFO - 'sessionrestore_no_auto_restore', 16:56:30 INFO - 'tabpaint']}, 16:56:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:56:30 INFO - 'tests': ['tsvgx', 16:56:30 INFO - 'tsvgr_opacity', 16:56:30 INFO - 'tart', 16:56:30 INFO - 'tscrollx', 16:56:30 INFO - 'cart']}, 16:56:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:56:30 INFO - 'tsvgr_opacity', 16:56:30 INFO - 'tart', 16:56:30 INFO - 'tscrollx', 16:56:30 INFO - 'cart']}, 16:56:30 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:56:30 INFO - 'talos_options': ['--disable-e10s'], 16:56:30 INFO - 'tests': ['tp5o']}, 16:56:30 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:56:30 INFO - 'tests': ['tp5o']}, 16:56:30 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:56:30 INFO - 'talos_options': ['--disable-e10s', 16:56:30 INFO - '--xperf_path', 16:56:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:56:30 INFO - 'tests': ['tp5n']}, 16:56:30 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:56:30 INFO - 'talos_options': ['--xperf_path', 16:56:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:56:30 INFO - 'tests': ['tp5n']}}, 16:56:30 INFO - 'talos.zip': {'path': '', 16:56:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:56:30 INFO - Running post-action listener: _resource_record_post_action 16:56:30 INFO - [mozharness: 2016-08-17 23:56:30.820892Z] Finished populate-webroot step (success) 16:56:30 INFO - [mozharness: 2016-08-17 23:56:30.820980Z] Running create-virtualenv step. 16:56:30 INFO - Running pre-action listener: _resource_record_pre_action 16:56:30 INFO - Running main action method: create_virtualenv 16:56:30 INFO - Creating virtualenv /builds/slave/test/build/venv 16:56:30 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:56:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:56:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:56:30 INFO - Using real prefix '/usr' 16:56:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:56:31 INFO - Installing distribute.............................................................................................................................................................................................done. 16:56:33 INFO - Installing pip.................done. 16:56:33 INFO - Return code: 0 16:56:33 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:56: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')]} 16:56:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:56:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:56: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')]} 16:56:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:56:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:56:33 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 0xd81030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xee48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1042180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10403b0>, '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 0x10412b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1041740>, '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': '52faae308a556c440401f8e5000001e8-1471477723.638147-1164050914', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:56:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:56:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:56:33 INFO - Using env: {'DISPLAY': ':0', 16:56:33 INFO - 'HOME': '/home/cltbld', 16:56:33 INFO - 'LANG': 'en_US.UTF-8', 16:56:33 INFO - 'LANGUAGE': 'en_US:en', 16:56:33 INFO - 'LOGNAME': 'cltbld', 16:56:33 INFO - 'MAIL': '/var/mail/cltbld', 16:56:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:56:33 INFO - 'MOZ_NO_REMOTE': '1', 16:56:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:56:33 INFO - 'NO_EM_RESTART': '1', 16:56:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:56:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:56:33 INFO - 'PWD': '/builds/slave/test', 16:56:33 INFO - 'SHELL': '/bin/bash', 16:56:33 INFO - 'SHLVL': '1', 16:56:33 INFO - 'TERM': 'linux', 16:56:33 INFO - 'TMOUT': '86400', 16:56:33 INFO - 'USER': 'cltbld', 16:56:33 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1471477723.638147-1164050914', 16:56:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:56:33 INFO - '_': '/tools/buildbot/bin/python'} 16:56:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:56:33 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 16:56:33 INFO - Cleaning up... 16:56:33 INFO - Return code: 0 16:56:33 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:56: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')]} 16:56:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:56:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:56: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')]} 16:56:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:56:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:56:33 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 0xd81030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xee48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1042180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10403b0>, '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 0x10412b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1041740>, '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': '52faae308a556c440401f8e5000001e8-1471477723.638147-1164050914', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:56:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:56:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:56:33 INFO - Using env: {'DISPLAY': ':0', 16:56:33 INFO - 'HOME': '/home/cltbld', 16:56:33 INFO - 'LANG': 'en_US.UTF-8', 16:56:33 INFO - 'LANGUAGE': 'en_US:en', 16:56:33 INFO - 'LOGNAME': 'cltbld', 16:56:33 INFO - 'MAIL': '/var/mail/cltbld', 16:56:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:56:33 INFO - 'MOZ_NO_REMOTE': '1', 16:56:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:56:33 INFO - 'NO_EM_RESTART': '1', 16:56:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:56:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:56:33 INFO - 'PWD': '/builds/slave/test', 16:56:33 INFO - 'SHELL': '/bin/bash', 16:56:33 INFO - 'SHLVL': '1', 16:56:33 INFO - 'TERM': 'linux', 16:56:33 INFO - 'TMOUT': '86400', 16:56:33 INFO - 'USER': 'cltbld', 16:56:33 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1471477723.638147-1164050914', 16:56:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:56:33 INFO - '_': '/tools/buildbot/bin/python'} 16:56:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:56:34 INFO - Downloading/unpacking psutil>=3.1.1 16:56:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:56:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:56:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:56:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:56:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:56:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:56:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:56:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:56:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:56:36 INFO - Installing collected packages: psutil 16:56:36 INFO - Running setup.py install for psutil 16:56:36 INFO - building 'psutil._psutil_linux' extension 16:56:36 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 16:56:37 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 16:56:37 INFO - building 'psutil._psutil_posix' extension 16:56:37 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 16:56:37 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 16:56:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:56:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:56:37 INFO - Successfully installed psutil 16:56:37 INFO - Cleaning up... 16:56:37 INFO - Return code: 0 16:56:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:56:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:56:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:56:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:56:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:56:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:56:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:56:37 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 0xd81030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xee48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1042180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10403b0>, '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 0x10412b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1041740>, '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': '52faae308a556c440401f8e5000001e8-1471477723.638147-1164050914', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:56:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:56:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:56:37 INFO - Using env: {'DISPLAY': ':0', 16:56:37 INFO - 'HOME': '/home/cltbld', 16:56:37 INFO - 'LANG': 'en_US.UTF-8', 16:56:37 INFO - 'LANGUAGE': 'en_US:en', 16:56:37 INFO - 'LOGNAME': 'cltbld', 16:56:37 INFO - 'MAIL': '/var/mail/cltbld', 16:56:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:56:37 INFO - 'MOZ_NO_REMOTE': '1', 16:56:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:56:37 INFO - 'NO_EM_RESTART': '1', 16:56:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:56:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:56:37 INFO - 'PWD': '/builds/slave/test', 16:56:37 INFO - 'SHELL': '/bin/bash', 16:56:37 INFO - 'SHLVL': '1', 16:56:37 INFO - 'TERM': 'linux', 16:56:37 INFO - 'TMOUT': '86400', 16:56:37 INFO - 'USER': 'cltbld', 16:56:37 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1471477723.638147-1164050914', 16:56:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:56:37 INFO - '_': '/tools/buildbot/bin/python'} 16:56:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:56:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:56: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 16:56: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 16:56: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 16:56: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 16:56: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 16:56: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 16:56:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:56:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:56:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:56:39 INFO - Installing collected packages: mozsystemmonitor 16:56:39 INFO - Running setup.py install for mozsystemmonitor 16:56:40 INFO - Successfully installed mozsystemmonitor 16:56:40 INFO - Cleaning up... 16:56:40 INFO - Return code: 0 16:56:40 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:56: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')]} 16:56:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:56:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:56: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')]} 16:56:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:56:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:56: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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd81030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xee48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1042180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10403b0>, '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 0x10412b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1041740>, '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': '52faae308a556c440401f8e5000001e8-1471477723.638147-1164050914', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:56: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==2.5.1'] in /builds/slave/test/build 16:56: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==2.5.1 16:56:40 INFO - Using env: {'DISPLAY': ':0', 16:56:40 INFO - 'HOME': '/home/cltbld', 16:56:40 INFO - 'LANG': 'en_US.UTF-8', 16:56:40 INFO - 'LANGUAGE': 'en_US:en', 16:56:40 INFO - 'LOGNAME': 'cltbld', 16:56:40 INFO - 'MAIL': '/var/mail/cltbld', 16:56:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:56:40 INFO - 'MOZ_NO_REMOTE': '1', 16:56:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:56:40 INFO - 'NO_EM_RESTART': '1', 16:56:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:56:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:56:40 INFO - 'PWD': '/builds/slave/test', 16:56:40 INFO - 'SHELL': '/bin/bash', 16:56:40 INFO - 'SHLVL': '1', 16:56:40 INFO - 'TERM': 'linux', 16:56:40 INFO - 'TMOUT': '86400', 16:56:40 INFO - 'USER': 'cltbld', 16:56:40 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1471477723.638147-1164050914', 16:56:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:56:40 INFO - '_': '/tools/buildbot/bin/python'} 16:56:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:56:40 INFO - Downloading/unpacking jsonschema==2.5.1 16:56:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:56:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:56:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:56:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:56:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:56:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:56:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:56:42 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:56:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:56:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:56:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:56:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:56:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:56:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:56:42 INFO - Downloading functools32-3.2.3-2.tar.gz 16:56:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:56:42 INFO - warning: no files found matching '*.txt' 16:56:42 INFO - no previously-included directories found matching 'build' 16:56:42 INFO - no previously-included directories found matching 'dist' 16:56:42 INFO - no previously-included directories found matching '.git*' 16:56:42 INFO - Installing collected packages: jsonschema, functools32 16:56:42 INFO - Running setup.py install for functools32 16:56:42 INFO - warning: no files found matching '*.txt' 16:56:42 INFO - no previously-included directories found matching 'build' 16:56:42 INFO - no previously-included directories found matching 'dist' 16:56:42 INFO - no previously-included directories found matching '.git*' 16:56:42 INFO - Successfully installed jsonschema functools32 16:56:42 INFO - Cleaning up... 16:56:42 INFO - Return code: 0 16:56:42 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:56:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:56:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:56:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:56:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:56:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:56:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:56:42 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 0xd81030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xee48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1042180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10403b0>, '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 0x10412b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1041740>, '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': '52faae308a556c440401f8e5000001e8-1471477723.638147-1164050914', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:56:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:56:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 16:56:42 INFO - Using env: {'DISPLAY': ':0', 16:56:42 INFO - 'HOME': '/home/cltbld', 16:56:42 INFO - 'LANG': 'en_US.UTF-8', 16:56:42 INFO - 'LANGUAGE': 'en_US:en', 16:56:42 INFO - 'LOGNAME': 'cltbld', 16:56:42 INFO - 'MAIL': '/var/mail/cltbld', 16:56:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:56:42 INFO - 'MOZ_NO_REMOTE': '1', 16:56:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:56:42 INFO - 'NO_EM_RESTART': '1', 16:56:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:56:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:56:42 INFO - 'PWD': '/builds/slave/test', 16:56:42 INFO - 'SHELL': '/bin/bash', 16:56:42 INFO - 'SHLVL': '1', 16:56:42 INFO - 'TERM': 'linux', 16:56:42 INFO - 'TMOUT': '86400', 16:56:42 INFO - 'USER': 'cltbld', 16:56:42 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1471477723.638147-1164050914', 16:56:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:56:42 INFO - '_': '/tools/buildbot/bin/python'} 16:56:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:56:42 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:56:42 INFO - Cleaning up... 16:56:42 INFO - Return code: 0 16:56:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:56:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:56:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:56:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:56:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:56:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:56:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:56:42 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 0xd81030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xee48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1042180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10403b0>, '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 0x10412b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1041740>, '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': '52faae308a556c440401f8e5000001e8-1471477723.638147-1164050914', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:56:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:56:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:56:42 INFO - Using env: {'DISPLAY': ':0', 16:56:42 INFO - 'HOME': '/home/cltbld', 16:56:42 INFO - 'LANG': 'en_US.UTF-8', 16:56:42 INFO - 'LANGUAGE': 'en_US:en', 16:56:42 INFO - 'LOGNAME': 'cltbld', 16:56:42 INFO - 'MAIL': '/var/mail/cltbld', 16:56:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:56:42 INFO - 'MOZ_NO_REMOTE': '1', 16:56:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:56:42 INFO - 'NO_EM_RESTART': '1', 16:56:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:56:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:56:42 INFO - 'PWD': '/builds/slave/test', 16:56:42 INFO - 'SHELL': '/bin/bash', 16:56:42 INFO - 'SHLVL': '1', 16:56:42 INFO - 'TERM': 'linux', 16:56:42 INFO - 'TMOUT': '86400', 16:56:42 INFO - 'USER': 'cltbld', 16:56:42 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1471477723.638147-1164050914', 16:56:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:56:42 INFO - '_': '/tools/buildbot/bin/python'} 16:56:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:56:43 INFO - Downloading/unpacking blobuploader==1.2.4 16:56:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:56:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:56:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:56:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:56:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:56:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:56:44 INFO - Downloading blobuploader-1.2.4.tar.gz 16:56:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:56:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:56:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:56:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:56:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:56:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:56:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:56:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:56:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:56:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:56:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:56:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:56:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:56:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:56:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:56:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:56:45 INFO - Downloading docopt-0.6.1.tar.gz 16:56:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:56:45 INFO - Installing collected packages: blobuploader, requests, docopt 16:56:45 INFO - Running setup.py install for blobuploader 16:56:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:56:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:56:45 INFO - Running setup.py install for requests 16:56:46 INFO - Running setup.py install for docopt 16:56:46 INFO - Successfully installed blobuploader requests docopt 16:56:46 INFO - Cleaning up... 16:56:46 INFO - Return code: 0 16:56:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:56: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')]} 16:56:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:56:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:56: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')]} 16:56:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:56:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:56:46 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 0xd81030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xee48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1042180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10403b0>, '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 0x10412b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1041740>, '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': '52faae308a556c440401f8e5000001e8-1471477723.638147-1164050914', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:56:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:56:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:56:46 INFO - Using env: {'DISPLAY': ':0', 16:56:46 INFO - 'HOME': '/home/cltbld', 16:56:46 INFO - 'LANG': 'en_US.UTF-8', 16:56:46 INFO - 'LANGUAGE': 'en_US:en', 16:56:46 INFO - 'LOGNAME': 'cltbld', 16:56:46 INFO - 'MAIL': '/var/mail/cltbld', 16:56:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:56:46 INFO - 'MOZ_NO_REMOTE': '1', 16:56:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:56:46 INFO - 'NO_EM_RESTART': '1', 16:56:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:56:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:56:46 INFO - 'PWD': '/builds/slave/test', 16:56:46 INFO - 'SHELL': '/bin/bash', 16:56:46 INFO - 'SHLVL': '1', 16:56:46 INFO - 'TERM': 'linux', 16:56:46 INFO - 'TMOUT': '86400', 16:56:46 INFO - 'USER': 'cltbld', 16:56:46 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1471477723.638147-1164050914', 16:56:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:56:46 INFO - '_': '/tools/buildbot/bin/python'} 16:56:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:56:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:56:46 INFO - Running setup.py (path:/tmp/pip-JI90r3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:56:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:56:46 INFO - Running setup.py (path:/tmp/pip-R9sGLX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:56:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:56:46 INFO - Running setup.py (path:/tmp/pip-QGQO95-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:56:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:56:46 INFO - Running setup.py (path:/tmp/pip-uLhSqG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:56:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:56:46 INFO - Running setup.py (path:/tmp/pip-mx7ZY2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:56:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:56:46 INFO - Running setup.py (path:/tmp/pip-_wCakC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:56:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:56:47 INFO - Running setup.py (path:/tmp/pip-7yeXXV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:56:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:56:47 INFO - Running setup.py (path:/tmp/pip-fcH6y_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:56:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:56:47 INFO - Running setup.py (path:/tmp/pip-HPVbKy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:56:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:56:47 INFO - Running setup.py (path:/tmp/pip-DqCDwi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:56:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:56:47 INFO - Running setup.py (path:/tmp/pip-MIzPM3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:56:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:56:47 INFO - Running setup.py (path:/tmp/pip-BxIEzD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:56:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:56:47 INFO - Running setup.py (path:/tmp/pip-8tNRuu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:56:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:56:47 INFO - Running setup.py (path:/tmp/pip-CO5iSN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:56:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:56:47 INFO - Running setup.py (path:/tmp/pip-BIqv5S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:56:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:56:47 INFO - Running setup.py (path:/tmp/pip-dmP6rC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:56:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:56:47 INFO - Running setup.py (path:/tmp/pip-NIJIJF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:56:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:56:47 INFO - Running setup.py install for manifestparser 16:56:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:56:47 INFO - Running setup.py install for mozcrash 16:56:48 INFO - Running setup.py install for mozdebug 16:56:48 INFO - Running setup.py install for mozdevice 16:56:48 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:56:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:56:48 INFO - Running setup.py install for mozfile 16:56:48 INFO - Running setup.py install for mozhttpd 16:56:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:56:48 INFO - Running setup.py install for mozinfo 16:56:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:56:48 INFO - Running setup.py install for mozInstall 16:56:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:56:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:56:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:56:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:56:48 INFO - Running setup.py install for mozleak 16:56:48 INFO - Running setup.py install for mozlog 16:56:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:56:48 INFO - Running setup.py install for moznetwork 16:56:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:56:48 INFO - Running setup.py install for mozprocess 16:56:49 INFO - Running setup.py install for mozprofile 16:56:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:56:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:56:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:56:49 INFO - Running setup.py install for mozrunner 16:56:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:56:49 INFO - Running setup.py install for mozscreenshot 16:56:49 INFO - Running setup.py install for moztest 16:56:49 INFO - Running setup.py install for mozversion 16:56:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:56:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:56:49 INFO - Cleaning up... 16:56:49 INFO - Return code: 0 16:56:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:56:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:56:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:56:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:56:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:56:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:56:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:56:49 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 0xd81030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xee48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1042180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10403b0>, '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 0x10412b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1041740>, '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': '52faae308a556c440401f8e5000001e8-1471477723.638147-1164050914', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:56:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:56:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:56:49 INFO - Using env: {'DISPLAY': ':0', 16:56:49 INFO - 'HOME': '/home/cltbld', 16:56:49 INFO - 'LANG': 'en_US.UTF-8', 16:56:49 INFO - 'LANGUAGE': 'en_US:en', 16:56:49 INFO - 'LOGNAME': 'cltbld', 16:56:49 INFO - 'MAIL': '/var/mail/cltbld', 16:56:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:56:49 INFO - 'MOZ_NO_REMOTE': '1', 16:56:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:56:49 INFO - 'NO_EM_RESTART': '1', 16:56:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:56:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:56:49 INFO - 'PWD': '/builds/slave/test', 16:56:49 INFO - 'SHELL': '/bin/bash', 16:56:49 INFO - 'SHLVL': '1', 16:56:49 INFO - 'TERM': 'linux', 16:56:49 INFO - 'TMOUT': '86400', 16:56:49 INFO - 'USER': 'cltbld', 16:56:49 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1471477723.638147-1164050914', 16:56:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:56:49 INFO - '_': '/tools/buildbot/bin/python'} 16:56:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:56:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:56:49 INFO - Running setup.py (path:/tmp/pip-S538PF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:56:49 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 16:56:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:56:49 INFO - Running setup.py (path:/tmp/pip-B4hWOf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:56:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:56:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:56:50 INFO - Running setup.py (path:/tmp/pip-yLS7Wv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:56:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:56:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:56:50 INFO - Running setup.py (path:/tmp/pip-e85Sm6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:56:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:56:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:56:50 INFO - Running setup.py (path:/tmp/pip-J3eRg2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:56:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 16:56:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:56:50 INFO - Running setup.py (path:/tmp/pip-Iz2bVr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:56:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 16:56:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:56:50 INFO - Running setup.py (path:/tmp/pip-Uepe9m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:56:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 16:56:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:56:50 INFO - Running setup.py (path:/tmp/pip-UB4hdV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:56:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 16:56:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:56:50 INFO - Running setup.py (path:/tmp/pip-0uRQhh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:56:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 16:56:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:56:50 INFO - Running setup.py (path:/tmp/pip-Wj8UUm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:56:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:56:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:56:50 INFO - Running setup.py (path:/tmp/pip-cm0X5P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:56:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 16:56:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:56:50 INFO - Running setup.py (path:/tmp/pip-NtQKei-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:56:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 16:56:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:56:50 INFO - Running setup.py (path:/tmp/pip-TdyfD2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:56:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 16:56:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:56:50 INFO - Running setup.py (path:/tmp/pip-UAPENx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:56:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 16:56:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:56:50 INFO - Running setup.py (path:/tmp/pip-gKzdJR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:56:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 16:56:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:56:50 INFO - Running setup.py (path:/tmp/pip-vb_vOR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:56:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 16:56:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:56:51 INFO - Running setup.py (path:/tmp/pip-1LnOHW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:56:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 16:56:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:56:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:56:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:56:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:56:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:56:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:56:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:56:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:56:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:56:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:56:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:56:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:56:52 INFO - Downloading blessings-1.6.tar.gz 16:56:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:56:53 INFO - Installing collected packages: blessings 16:56:53 INFO - Running setup.py install for blessings 16:56:53 INFO - Successfully installed blessings 16:56:53 INFO - Cleaning up... 16:56:53 INFO - Return code: 0 16:56:53 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:56:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:56:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:56:53 INFO - Reading from file tmpfile_stdout 16:56:53 INFO - Current package versions: 16:56:53 INFO - argparse == 1.2.1 16:56:53 INFO - blessings == 1.6 16:56:53 INFO - blobuploader == 1.2.4 16:56:53 INFO - docopt == 0.6.1 16:56:53 INFO - functools32 == 3.2.3-2 16:56:53 INFO - jsonschema == 2.5.1 16:56:53 INFO - manifestparser == 1.1 16:56:53 INFO - mozInstall == 1.12 16:56:53 INFO - mozcrash == 0.17 16:56:53 INFO - mozdebug == 0.1 16:56:53 INFO - mozdevice == 0.48 16:56:53 INFO - mozfile == 1.2 16:56:53 INFO - mozhttpd == 0.7 16:56:53 INFO - mozinfo == 0.9 16:56:53 INFO - mozleak == 0.1 16:56:53 INFO - mozlog == 3.3 16:56:53 INFO - moznetwork == 0.27 16:56:53 INFO - mozprocess == 0.23 16:56:53 INFO - mozprofile == 0.28 16:56:53 INFO - mozrunner == 6.12 16:56:53 INFO - mozscreenshot == 0.1 16:56:53 INFO - mozsystemmonitor == 0.3 16:56:53 INFO - moztest == 0.7 16:56:53 INFO - mozversion == 1.4 16:56:53 INFO - psutil == 3.1.1 16:56:53 INFO - requests == 1.2.3 16:56:53 INFO - wsgiref == 0.1.2 16:56:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:56:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:56:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:56:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:56:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:56:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:56:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:56:53 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 0xd81030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xee48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1042180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10403b0>, '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 0x10412b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1041740>, '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': '52faae308a556c440401f8e5000001e8-1471477723.638147-1164050914', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:56:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 16:56:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:56:53 INFO - Using env: {'DISPLAY': ':0', 16:56:53 INFO - 'HOME': '/home/cltbld', 16:56:53 INFO - 'LANG': 'en_US.UTF-8', 16:56:53 INFO - 'LANGUAGE': 'en_US:en', 16:56:53 INFO - 'LOGNAME': 'cltbld', 16:56:53 INFO - 'MAIL': '/var/mail/cltbld', 16:56:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:56:53 INFO - 'MOZ_NO_REMOTE': '1', 16:56:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:56:53 INFO - 'NO_EM_RESTART': '1', 16:56:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:56:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:56:53 INFO - 'PWD': '/builds/slave/test', 16:56:53 INFO - 'SHELL': '/bin/bash', 16:56:53 INFO - 'SHLVL': '1', 16:56:53 INFO - 'TERM': 'linux', 16:56:53 INFO - 'TMOUT': '86400', 16:56:53 INFO - 'USER': 'cltbld', 16:56:53 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1471477723.638147-1164050914', 16:56:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:56:53 INFO - '_': '/tools/buildbot/bin/python'} 16:56:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:56:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:56:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 16:56:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 16:56:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:56:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 16:56:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 16:56:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 16:56:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 16:56:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 16:56:53 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:56:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:56:53 INFO - Cleaning up... 16:56:53 INFO - Return code: 0 16:56:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:56:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:56:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:56:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:56:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:56:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:56:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:56:53 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 0xd81030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xee48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1042180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10403b0>, '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 0x10412b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1041740>, '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': '52faae308a556c440401f8e5000001e8-1471477723.638147-1164050914', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:56:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:56:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:56:53 INFO - Using env: {'DISPLAY': ':0', 16:56:53 INFO - 'HOME': '/home/cltbld', 16:56:53 INFO - 'LANG': 'en_US.UTF-8', 16:56:53 INFO - 'LANGUAGE': 'en_US:en', 16:56:53 INFO - 'LOGNAME': 'cltbld', 16:56:53 INFO - 'MAIL': '/var/mail/cltbld', 16:56:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:56:53 INFO - 'MOZ_NO_REMOTE': '1', 16:56:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:56:53 INFO - 'NO_EM_RESTART': '1', 16:56:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:56:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:56:53 INFO - 'PWD': '/builds/slave/test', 16:56:53 INFO - 'SHELL': '/bin/bash', 16:56:53 INFO - 'SHLVL': '1', 16:56:53 INFO - 'TERM': 'linux', 16:56:53 INFO - 'TMOUT': '86400', 16:56:53 INFO - 'USER': 'cltbld', 16:56:53 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1471477723.638147-1164050914', 16:56:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:56:53 INFO - '_': '/tools/buildbot/bin/python'} 16:56:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:56:53 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:56:53 INFO - Cleaning up... 16:56:53 INFO - Return code: 0 16:56:53 INFO - Running post-action listener: _resource_record_post_action 16:56:53 INFO - Running post-action listener: _start_resource_monitoring 16:56:53 INFO - Starting resource monitoring. 16:56:53 INFO - [mozharness: 2016-08-17 23:56:53.961161Z] Finished create-virtualenv step (success) 16:56:53 INFO - [mozharness: 2016-08-17 23:56:53.962041Z] Running install step. 16:56:53 INFO - Running pre-action listener: _resource_record_pre_action 16:56:53 INFO - Running main action method: install 16:56:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:56:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:56:54 INFO - Reading from file tmpfile_stdout 16:56:54 INFO - Detecting whether we're running mozinstall >=1.0... 16:56:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:56:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:56:54 INFO - Reading from file tmpfile_stdout 16:56:54 INFO - Output received: 16:56:54 INFO - Usage: mozinstall [options] installer 16:56:54 INFO - Options: 16:56:54 INFO - -h, --help show this help message and exit 16:56:54 INFO - -d DEST, --destination=DEST 16:56:54 INFO - Directory to install application into. [default: 16:56:54 INFO - "/builds/slave/test"] 16:56:54 INFO - --app=APP Application being installed. [default: firefox] 16:56:54 INFO - mkdir: /builds/slave/test/build/application 16:56:54 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'] 16:56:54 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 16:57:11 INFO - Reading from file tmpfile_stdout 16:57:11 INFO - Output received: 16:57:11 INFO - /builds/slave/test/build/application/firefox/firefox 16:57:11 INFO - Running post-action listener: _resource_record_post_action 16:57:11 INFO - [mozharness: 2016-08-17 23:57:11.184585Z] Finished install step (success) 16:57:11 INFO - [mozharness: 2016-08-17 23:57:11.184926Z] Running run-tests step. 16:57:11 INFO - Running pre-action listener: _resource_record_pre_action 16:57:11 INFO - Running main action method: run_tests 16:57:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:57:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:57:11 INFO - Python 2.7.3 16:57:11 INFO - Return code: 0 16:57:11 INFO - grabbing minidump binary from tooltool 16:57:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:57:11 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 0x10403b0>, '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 0x10412b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1041740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:57:11 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 16:57:11 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 16:57:11 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 16:57:11 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:57:11 INFO - Return code: 0 16:57:11 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:57:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:57:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:57:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:57:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:57:11 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', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/S5AIVV8nTSKNIcNjBUF9ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 16:57:11 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 --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/S5AIVV8nTSKNIcNjBUF9ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-056 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 16:57:11 INFO - Using env: {'DISPLAY': ':0', 16:57:11 INFO - 'HOME': '/home/cltbld', 16:57:11 INFO - 'LANG': 'en_US.UTF-8', 16:57:11 INFO - 'LANGUAGE': 'en_US:en', 16:57:11 INFO - 'LOGNAME': 'cltbld', 16:57:11 INFO - 'MAIL': '/var/mail/cltbld', 16:57:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:57:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:57:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:57:11 INFO - 'MOZ_NO_REMOTE': '1', 16:57:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:57:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:57:11 INFO - 'NO_EM_RESTART': '1', 16:57:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:57:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:57:11 INFO - 'PWD': '/builds/slave/test', 16:57:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:57:11 INFO - 'SHELL': '/bin/bash', 16:57:11 INFO - 'SHLVL': '1', 16:57:11 INFO - 'TERM': 'linux', 16:57:11 INFO - 'TMOUT': '86400', 16:57:11 INFO - 'USER': 'cltbld', 16:57:11 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1471477723.638147-1164050914', 16:57:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:57:11 INFO - '_': '/tools/buildbot/bin/python'} 16:57:11 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/S5AIVV8nTSKNIcNjBUF9ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 16:57:11 INFO - mozversion application_buildid: 20160817134614 16:57:11 INFO - mozversion application_changeset: 6e2bee015c3558d815c136c03ce816bfc38c70c8 16:57:11 INFO - mozversion application_display_name: FirefoxDeveloperEdition 16:57:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:57:11 INFO - mozversion application_name: Firefox 16:57:11 INFO - mozversion application_remotingname: firefox-dev 16:57:11 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 16:57:11 INFO - mozversion application_vendor: Mozilla 16:57:11 INFO - mozversion application_version: 50.0a2 16:57:11 INFO - mozversion platform_buildid: 20160817134614 16:57:11 INFO - mozversion platform_changeset: 6e2bee015c3558d815c136c03ce816bfc38c70c8 16:57:11 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 16:57:11 INFO - mozversion platform_version: 50.0a2 16:57:11 INFO - using testdate: 1471478231 16:57:11 INFO - actual date: 1471478231 16:57:11 INFO - starting webserver on 'localhost:40896' 16:57:11 INFO - SUITE-START | Running 2 tests 16:57:11 INFO - TEST-START | dromaeo_css 16:57:11 INFO - operating with platform_type : linux_ 16:57:11 INFO - Initialising browser for dromaeo_css test... 16:57:11 INFO - TEST-INFO | started process 3304 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp43UBYx/profile http://localhost:40896/getInfo.html) 16:57:17 INFO - PROCESS | 3304 | __metrics Screen width/height:1600/1200 16:57:17 INFO - PROCESS | 3304 | colorDepth:24 16:57:17 INFO - PROCESS | 3304 | Browser inner width/height: 1024/697 16:57:17 INFO - PROCESS | 3304 | __metrics 16:57:18 INFO - PROCESS | 3304 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:57:18 INFO - PROCESS | 3304 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:57:19 INFO - TEST-INFO | 3304: exit 0 16:57:19 INFO - Browser initialized. 16:57:19 INFO - Running cycle 1/1 for dromaeo_css test... 16:57:19 INFO - TEST-INFO | started process 3397 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp43UBYx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:57:31 INFO - PROCESS | 3397 | RSS: Main: 145268736 16:57:31 INFO - PROCESS | 3397 | 16:58:22 INFO - PROCESS | 3397 | 1471478302154 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] 17:00:31 INFO - PROCESS | 3397 | Cycle 1(1): loaded http://localhost:40896/tests/dromaeo/cssquery-dojo.html (next: http://localhost:40896/tests/dromaeo/cssquery-ext.html) 17:00:32 INFO - PROCESS | 3397 | RSS: Main: 279367680 17:00:32 INFO - PROCESS | 3397 | 17:03:35 INFO - PROCESS | 3397 | Cycle 1(1): loaded http://localhost:40896/tests/dromaeo/cssquery-ext.html (next: http://localhost:40896/tests/dromaeo/cssquery-jquery.html) 17:03:35 INFO - PROCESS | 3397 | RSS: Main: 228638720 17:03:35 INFO - PROCESS | 3397 | 17:06:37 INFO - PROCESS | 3397 | Cycle 1(1): loaded http://localhost:40896/tests/dromaeo/cssquery-jquery.html (next: http://localhost:40896/tests/dromaeo/cssquery-mootools.html) 17:06:38 INFO - PROCESS | 3397 | RSS: Main: 175476736 17:06:38 INFO - PROCESS | 3397 | 17:09:40 INFO - PROCESS | 3397 | Cycle 1(1): loaded http://localhost:40896/tests/dromaeo/cssquery-mootools.html (next: http://localhost:40896/tests/dromaeo/cssquery-prototype.html) 17:09:40 INFO - PROCESS | 3397 | RSS: Main: 201064448 17:09:40 INFO - PROCESS | 3397 | 17:12:43 INFO - PROCESS | 3397 | Cycle 1(1): loaded http://localhost:40896/tests/dromaeo/cssquery-prototype.html (next: http://localhost:40896/tests/dromaeo/cssquery-yui.html) 17:12:43 INFO - PROCESS | 3397 | RSS: Main: 176979968 17:12:43 INFO - PROCESS | 3397 | 17:15:46 INFO - PROCESS | 3397 | Cycle 1(1): loaded http://localhost:40896/tests/dromaeo/cssquery-yui.html (next: http://localhost:40896/tests/dromaeo/cssquery-dojo.html) 17:15:46 INFO - PROCESS | 3397 | RSS: Main: 264253440 17:15:46 INFO - PROCESS | 3397 | 17:15:46 INFO - PROCESS | 3397 | __start_tp_report 17:15:46 INFO - PROCESS | 3397 | _x_x_mozilla_page_load 17:15:46 INFO - PROCESS | 3397 | _x_x_mozilla_page_load_details 17:15:46 INFO - PROCESS | 3397 | |i|pagename|runs| 17:15:46 INFO - PROCESS | 3397 | |0;dojo.html;3284.8357582120893;3314.2211580278617;3327.2846338037316;3329.517220003101;3374.4263475209214;5677.176809362645;5806.390329015456;5811.6803575803315;5816.534677225823;5857.677827719468;5715.771369145229;5716.742746576404;5728.570357223214;5730.856728581787;5811.535077193825;5087.033463641915;5156.458571849958;5206.713630750307;5265.315508983846;5298.761555730001;5249.921251181236;5288.65623734456;5299.072662284085;5306.151015837467;5307.469253074701;5145.871353216162;5169.819056333029;5173.249878767577;5200.557952574017;5283.365996080456;5274.498922602346;5282.366116066097;5298.470152984709;5308.681479111227;5326.813561525328;5092.7453627318855;5094.872628184288;5151.021305951857;5181.559567436772;5185.714785686786;5634.04221282383;5650.265465489501;5657.20799088128;5668.26312579362;5701.543876489871;6167.784127555514;6181.690915454209;6181.845453863644;6183;6189.2572891252885;6018.037114061773;6101.877962440771;6117.66352850593;6133.907991380132;6223.190985171944;5792.812473442934;5797.565182611321;5814.156947242668;5823.126531020381;5823.941760582382;6249.34381889895;6303.054541818764;6306.801707675527;6309.3690630936535;6309.652969086745;5937.614055086406;5996.340402555716;6041.214642096499;6050.757969681252;6118.071005858243;6154.661493617805;6192.907106393408;6196;6196.659183744847;6209.720562574695;6089.360617135226;6141.140240366352;6151.231096112986;6154.1999540059505;6162.691865406801;5984.251968503937;6064.696765161724;6095.329513753483;6108.480779133734;6172.506199504029;4672.719636821802;4724.71651700899;4728.0780247851335;4751.714897106115;4761.333413322138;4183.728057676241;4195.160967806451;4203.831846726158;4208.095259519218;4227.98528353193;5683.971580142155;5758.712064396764;5769.307683078057;5802.158686990363;5833.941660583425;6140.293866205444;6141.140240366352;6146.661933593606;6152.077188421773;6159.692015399301;5833.066709326487;5839.532837373;5844.1818145459665;5847.707614619337;5848.4151584841175;5191.688498690015;5273.973630131902;5295.576353891679;5302.416734159239;5331.680099194061;6324.1146239527425;6383.904241436289;6396.328385519547;6413.390727880844;6422.165118534561;6140.938590614037;6211.3478084802255;6222.906656400067;6225.595336303126;6249.125122482947;6121.117656725397;6142.877142457215;6144.293406258159;6165.167702360124;6398.680065996775;6046.395360464095;6047.697615119315;6048.4858787003595;6051.243594550681;6081.6655083969035;6109.725062372115;6132.386761323832;6135.263768347827;6139.447449729546;6150.477209437248;5926.377730338252;5965.552583556164;5987.970060149672;5992.610480318765;6017.518598512196;5946.881062378815;5989.071693887455;5995.070764031582;6026.0659597762415;6035.426634469718;5880.26496687914;5932.347441781487;5942.10868369748;5950.31571369298;5962.4633782959745;5876.471117599436;5963.3142188647125;6000.69996500182;6037.396260373927;6052.727627256696;5889.204957330706;5900.734466948912;5903.763849445974;5909.734061967136;5945.791897283574 17:15:46 INFO - PROCESS | 3397 | |1;ext.html;600.3036477685886;654.509118161379;666.5667316244437;671.3689132215717;688.614375949468;35609.46585801212;39194.804025979865;50060.24909626354;51335;51480;48265.27602085963;48490.757546212306;48758.51241487584;48809;48938.75530622351;8390.32877369812;9161.129692679186;9310.720678379626;9314.906850931506;9318.76703082425;8370.958145209295;8455.239028487435;8460.703875364363;10912.563497460133;11147.721306967309;10864.565417383295;10881.83677244842;10923.617673381434;10927.945360273188;10932.398718070503;11164.720881977933;11211.887881121165;11244.943775281154;11267;11268.4365781711;11051.281666691639;11083.057940075103;11101.278416902875;11121.610743624015;11129.332240065622;38518.42222366667;38686.419703704465;38726.806365968274;38740.03149921246;38848.611513885066;3909.139989202371;3973.980130099331;4078.1028173801715;4139.730917490354;4578.404807375054;30560;30805.38389232203;30808.537871931938;31234.843825780954;32428.83785581057;4598.356230127785;4659.836905708284;4737.549932756383;4750.572448479619;4780.904381912377;324750;325223;325890;326308;326904.3654781735;54827.72586137044;55044;55161.448385516436;55554.16668749972;55701;35249.823750881595;35635.465468018;35839.104022399646;35861.64138358635;35922.64077359193;17423.21595528204;17527.912360438117;17549.473515794547;17574.824251757575;17600.823991759917;11579.073674106052;13501.59495215145;13549.390277437538;13592.796108058386;13592.864071359358;20244.392668219978;20682;21040.473988150417;21722.23972160967;21870;6152.969235153795;6177.459567218091;6248.5313601479165;6429.260635026938;6441.0982462455295;1002.0530598584268;1088.292609803634;1096.2928910852456;1124.656979621207;1139.1342579639368;31301.217469563442;31483.37033259322;31790.523142152888;31802.2049448761;31994.200144996095;1203.6990752311922;1223.8837310455492;1228.8586812516496;1255.3911352993791;1291.9547815826402;17498.825011749974;17503.824961750473;17544.473665790047;17588.560285992953;17843.553911152325;366478;659858;662342;662430;663964;55726.442735572935;56020.15969760457;56156.43843561512;56237.718811405684;56266.71866640641;1357.565579014706;1452.3609611770999;1452.6295794572316;1461.1598330959528;1496.438835436733;34903.65096349004;36238.27523449569;36361.27277454489;36448.63551364558;36513.26973460569;55919;56374.718126409105;56476.43523564712;56578;56746;428600;429383;430184;431102;433427;30110.698893011664;30332.545011824397;30671;30879.536806948363;31225.84387078141;52171;53205.46794532005;53538;54188.72905635447;54388.09641662523;43485.782571086944;46473.302900457194;46567.534324657674;46751.766241168574;47286.29070564013;44603.77698111489;45254.32118518159;46471.30293045539;46927;47632.28551571799;44633;45539.54460455353;46057.76971115123;46247.53752462432;46526;38427.615723842406;40350;40539.1892162161;40609.390859137726;41069.38395924123;29342.853285733436;30573.847130764203;30992.22519437032;31002;31128.844355778077 17:15:46 INFO - PROCESS | 3397 | |2;jquery.html;399.06817580948484;413.18602353363883;415.47649961049075;420.23097731151984;430.39744357898957;15195.316210770514;19773.901130494363;20403.08186131624;20424.183032678695;20514.794852051476;18699.3455229067;18709.532261693465;18759.343422980197;18938.62122757544;18948.052597370144;5787.508061814729;5803.6227645203035;5922;5966.970165149168;6034.818955431322;5868.882622347551;5939.495142912836;5947.5836691431405;5950.256217972754;5953.255843019622;5717.714114294289;5848.152017957394;5868.061110222366;5879.32387775407;5896.911546326808;5706.343770466408;5879.235699359077;5912.260967379078;5922.4373684500115;5930.080837470199;5251.10731175701;5255.527002569749;5256;5256.316678831728;5291.417944026154;23281.301560953198;23836.880815595985;23869.761302386927;24031;24149.15477958263;2646.1958443965063;2666.946661066768;2669.4927963686837;2763.8618069096665;2769.2246171072134;14645.926770366186;15002.099874007594;15183.924080379527;15292.847071529253;15864.36542538297;1977.7824439311664;1989.6120256549978;2026.331310667491;2063;2079.864808787439;86364;87215.56392218062;87360.56319718361;87406.5629671854;88103.55948220218;10702;10995.395253261147;11369.772604547863;11479.942600286946;11657.358845263423;15660.68678626444;15730.134842583768;15740.134292613788;15770.76343854843;15818.130002849724;8292.751217463485;8326.50040997542;8533.445326053787;8571.82856342882;8621.353398495137;8297.502149871027;8305.252527272574;8334.583270836556;8503;8535.573221338907;6787.321267873173;6791.320867913169;6810.31896810315;6810.9318906810295;6871.484638652021;2011.597680463913;2033.1867253098583;2049.211053744319;2050.7846676098793;2086.3636590839938;887.2547060469108;918.0452329577298;919.3518569408551;921.2077613252598;942.2697409507687;18111.818881811276;18986.71519927202;19401.932893690984;19599.60800784005;19794.802051979583;932.849781254202;967.690339091484;984.9507524623883;988.515627342597;1014.5079636376352;10292.176625869912;10602.840957385646;10785.298955567863;10818.29681070728;10888.78222435547;555279;582086;586398;599985;610957;85224;85510;85615;85734;86150.56924715337;1021.7752094539189;1036.3574583757968;1042.9426381549088;1057.5241141486208;1065.4566171252561;16931.576710582332;17457;17862.821371786635;18181.272749089887;18851.905740471757;46924.530754692016;47321.29018064801;47452;47620.76189619168;49081.2637810426;61376;61516.69241653763;61669;61956;63724.68137659467;7960.562169080757;8459.703910363107;8528;8660.307175426076;8669.176428239307;10455.477226138815;12847.807282890952;13055.738885222052;13560;13970.580882573538;10932.289401189215;11177.72055698614;11202.66392008241;11282.830757538479;11746.17776755619;11376.146788991024;11565.59520416815;11585.826212606986;11589.594364197214;11709.824352634887;11560.017398520884;11846.644600661994;12177.269363838199;12297;12449.751004980031;8347.499150050773;8528.91471085306;8694.82610347802;8712.825743485222;9101.226395756436;11069.889301106887;11267.492962816528;11821.408929553316;11986.160968732105;12082.33547154915 17:15:46 INFO - PROCESS | 3397 | |3;mootools.html;1245.0786418050645;1307.2744626732162;1443.9531339778662;1473.2928194466656;1818.4181062060145;8993.59528821203;9447.480388578626;9464;9639.180669643089;9697.563609637566;9522.285828562852;9629.662961796339;9630.18143457807;9633.951830240856;9635.903640963588;2468.975310246897;2489.2158969924417;2495.6880389951257;2497.67530221071;2503.0988844016138;2443.1693224303735;2521.6091505816626;2523.205190365029;2526.343150780792;2533.721290658026;2443.780059794618;2452.350127216289;2453.7791598756107;2462.0398044762555;2475.987620061906;2360.6695062691238;2392.676988606533;2399.4121440247163;2424.7211570669247;2429.2347910408166;2414.7464516225896;2438.8414753040997;2447.192426499251;2453.766892145243;2473.839200451983;9516.571754271074;9563.713088607354;9620.90379096214;9633.133018028411;9638.807223855554;5289.973550132225;5432.891342173134;5473.480019398191;5474.972625136888;5507.449255074501;3576.409892367756;3598.6221446747977;3656.835442405098;3671.5777685566236;3676.7058635309113;1152.6369193703956;1161.384466232898;1174.6123779152858;1175.518037604578;1196.9640910772691;13437.260950647807;13477.730445391135;13543.390547425292;13544.322783860767;13572.117812342167;4585.335126406652;4712.128256272532;4777.283407488904;4878.707277563358;4882.12121818069;10316.43259620728;10319.48402579868;10319.690409287734;10323.535440905178;10333.431661258703;5378.058839703036;5398.055340315429;5405.7567409466665;5411.756470958816;5416.620836541404;4455.866324010285;4936.580390666833;4950.430700469492;5056.646034777604;5093.414257360451;4094.897627559335;4134.855280065183;4181.770002649822;4220.198162349206;4242.745435273893;1150.5110328110686;1150.8331291962786;1160.9419529023514;1187.679326581818;1194.7192409783695;1017.8320577104695;1045.9790804183874;1053.3837704942694;1056.57736905237;1059.422614675004;6192.318844927055;6251.093591429309;6415.101885735908;6430.228372595318;6670.633115178614;459.90571932753704;465.41822721598004;472.34344261476576;492.1608657239398;502.2767215210086;4327;4417.624501917308;4422.845200418034;4464.910701785946;4488.955110448919;11714.355710435837;11938.22401543897;12030.4586293617;12040.458179381949;12070.45682944252;11975.101867359983;11975.401229938469;11978.281303121666;11979.58071467512;11984.22102563348;8478.872816907751;8828.602712877935;8843.380963332505;9069.18377346042;9078.86381704275;6571.145751052524;6584.539082264196;6603.801885943237;6690.264070952094;6854.383105520527;10820.134389248999;11080.61217857371;11088.944555277172;11115.22193446451;11212.383318917538;11251.774964500828;11318.094552435621;11337.433128343715;11364.204505684853;11375;7568.962155189409;8056.5971701415865;8064.9596752018215;8194.467359621605;8389.496630202208;9414.811703766023;9596.95201523988;9613.182879455324;9621.326507144433;9633.807323853343;8096.433249672466;8196.30331421812;8277.54473503963;8298.128696486781;8503.787405314917;8012.901374728579;8018.719344822904;8030.678772849021;8099.433039687401;8383.413161078666;7521.210272921483;7526.924730752622;7556.962215188889;7623.352015078781;7623.618819058914;5295.099833028317;5375.3012108425655;5409.2427060212385;5440.401555828935;5532.1701744737;5455.809046683347;5505.009098362333;5779.913301300399;5818.44724751148;5851.385604511635 17:15:46 INFO - PROCESS | 3397 | |4;prototype.html;339.32135728542914;341.18456888037593;351.213282247765;354.8669249031613;357.96957258633;5560.4161563035905;5569.303837020372;5593.776248950047;5601.6753047485345;5602.53488465075;5236.250968241675;5367.624266301361;5384.165454354572;5397.46025397461;5519.586031047677;4234.449521562193;4312.956870431287;4335.43639326887;4353.825846966118;4411.867643970686;4331.5884990925815;4343.413639158721;4344.543822898598;4364.956350436502;4376.803043863034;4124.773137477438;4305.849295274666;4321.459817522809;4322.675799315049;4340.370646256291;4288.849890253858;4295.248331541968;4300.526942036374;4304.612584867361;4333.804978775946;3840.36633955397;3848.306745032177;3850.9397472352157;3855.8843234703004;3859.6333348331864;5221.71280579568;5246.291750613656;5377.4084850666395;5434.510894019557;5449.427810079964;4881.414230292387;4895.5838753705975;4898.755062246873;4898.893475805402;4898.90202195954;5639.477341117916;5664.886702265972;5699.857503562403;5759.884802303931;5760.251167348218;3990.7206495545324;4130.710850240484;4141.5237247716295;4149.937750933738;4186.446267776654;5896.882062358772;5921.526277897758;5926.822195334147;5966.254218222723;5984.820455386345;4269.033022049645;4272.743635381825;4277.337012763027;4296.183725092223;4301.0537681709975;4114.6091121343425;4325.2214601371425;4326.286162783105;4345.261305577996;4354.521002689701;4851.417829860439;4912.557869791736;4914.140025495524;4917.483664215278;4941.629377796679;5798.478136967608;5811.79658711943;5813.447722466359;5837.6497410155525;5852.414758524199;1458.7738900470445;1459.5986103821322;1480.9555713328402;1482.1773915477013;1491.486141566311;925.9907400925905;939.9472590698462;950.0515058755766;951.8672779392639;959.5633986536109;4923.261510773413;4937.87655308613;4955.306257124005;4976.477469865612;4978.431793984883;5157.776389756402;5159.561437277798;5186.325777648882;5221.660592061504;5232.874931889586;4784.258439941815;4935.629827762932;4954.777035033432;4971.900561988812;5059.494050594984;2806.8175568587976;2810.2552823501787;2822.6612806463354;2825.971740282571;2830.3207230265;5411.428457218296;5413;5416.350037995466;5428.5385742211565;5435.83692489226;5080.974595127001;5231.398389185217;5278.551323137576;5299.788008479694;5311.521963023347;5237.764300606482;5242.370915490165;5245.423003469616;5252.527272545489;5325.707086110224;5179.326687530597;5206.589683757145;5212.635115541876;5212.957408518236;5248.317718696545;4834.540718631724;5103.137354865522;5122.974385128051;5124.154514505065;5131.512506311894;5257.185136303879;5307.363116426053;5336.919946200887;5362.302900622894;5382.650127741685;3873.3608954523315;3882.204148149566;3890.191061156576;3915.158240978203;4036.1322315702264;5433.429489903503;5885.323187833283;5893.941060589511;5908.379620139824;5910.231669882888;5770.5095066919785;5774.816162686555;5787.536997040311;5787.7974270902005;5799;5505.559555235652;5776.8555786104;5787.652740835563;5801.709914504342;5805.332386775575;5763.971180144073;5769.9711501442225;5776.249087618583;5794.304683438015;5794.333651630117;5829.659178389079;5848.5028772553105;5871.412858714265;5874.647521148745;5880.970595146997;5510.448955104457;5675.914861277001;5683.658980461186;5694.259746232963;5708.600397972102 17:15:46 INFO - PROCESS | 3397 | |5;yui.html;765.2232983521725;840.1514470384914;844.6452489954219;857.7898414888351;910.1035480052143;13980.580582582526;14732;14821.55535333939;14864.851351486483;14893.255337233122;14074.929625351859;14150.78773818391;14185;14186.290685465738;14207.715845683106;739.5267029101379;844.5397258494136;861.332467337815;882.7837179890936;882.9867551986694;887.7026196224273;889.8798662180587;890.1899271662788;891.5408564589242;891.5631340643102;942.236788201559;946.1437399153776;947.8957314695378;948.7201275623673;949.1505102932871;762;779.1779672445539;781.2460975158942;886.3308202307217;933.785229397239;671.1275342055358;674.7402250133684;675.1459403854128;675.2909445082644;675.6013951768431;14911.254437278201;14941.701165976729;14954.252287385587;14954.40182392703;14955.700885982329;13633;13700.588982330446;13982.161070335813;14173.220472873987;14238.715225695429;14364.928175359057;14533.34599943005;14583.854161458356;14773.409063637442;14790.260486975609;4607.17070927233;4634.536546345372;4661.720296782204;4728.219843725781;4747.169245382044;83274.58362708148;83480.16519834785;83597.58201209016;83629.74555381674;83930.58034709848;11974.281543107441;12011.75976480483;12031.518739250332;12032.458539365749;12122.090843186796;23091.88454057719;23215.883920580625;23224;23242.302730918098;24492.510149796904;8473.5339556325;8554.27288680469;8581.828363432696;8748.212660860427;8803.779905502413;4829.203181475017;5032.345795046621;5092.872678183075;5248.63259571834;5291.603129765283;3628.238070005276;3758.210775737126;3772.434134879735;3773.75470594416;3775.4714339992424;622.4180391334118;629.9622022678562;630.5932673425615;642.9099926010363;668.1647940074906;633.9746410143542;713.6182142553708;713.7430525010992;720.1070672366228;733.0873063036554;9665.613375464902;9722;9746.171575416027;9843.753906152404;9903.554340054714;640.3404493371835;682.8429461223947;701.5930760159096;708.4969671533166;719.2304234469173;4548.863534093983;4639.768011599406;4640.396748422683;4860.003699241647;4871.269309603517;76219.61890190624;76569.61715191389;136602;139530.30234848964;144226;88640;104882;105523.39242683299;105778;110028;66979;92034.53982730044;93652;94146.52926735322;95512.52243738598;10522.842157367493;10651.89348106509;10704.678859634223;10759.89240107589;10910.290831095954;53576.46423535715;54817.45182548283;55036.44963550474;55377.723111384184;56099.15851262153;75346;77048.61475692586;82479.58760206161;83614.58192708998;83970.58014709887;13652.931735341259;13804.102733322303;13919.582412527641;14079.718405631626;14128.364223609757;15692.76460853111;16080.758788618414;16268.837311626732;16363.34546618122;16640.73335752665;10589.788204235718;11232.887671123184;13637.79543306871;15660.295286711897;16076.115813630362;15251.237438127828;15255.923720381326;15550.066995980278;15693.450729224422;16970.490885272968;14200.360983755962;14468.710625787637;14837.827392454516;14854.851451485347;15399.769003464733;12230.755384892074;12625.179363341353;12653.430595623035;12681.619551413472;12779.23324600527;12899.419526121159;12934.676633083871;13217.206967581977;13266.867331326563;13280 17:15:46 INFO - PROCESS | 3397 | __end_tp_report 17:15:46 INFO - PROCESS | 3397 | __start_cc_report 17:15:46 INFO - PROCESS | 3397 | _x_x_mozilla_cycle_collect,1141 17:15:46 INFO - PROCESS | 3397 | __end_cc_report 17:15:46 INFO - PROCESS | 3397 | __startTimestamp1471479346498__endTimestamp 17:15:46 INFO - PROCESS | 3397 | 17:15:46 INFO - PROCESS | 3397 | ------- Summary: start ------- 17:15:46 INFO - PROCESS | 3397 | Number of tests: 6 17:15:46 INFO - PROCESS | 3397 | 17:15:46 INFO - PROCESS | 3397 | [#0] dojo.html Cycles:165 Average:5704.75 Median:5932.35 stddev:642.43 (10.8%) stddev-sans-first:615.71 17:15:46 INFO - PROCESS | 3397 | Values: 3284.8 3314.2 3327.3 3329.5 3374.4 5677.2 5806.4 5811.7 5816.5 5857.7 5715.8 5716.7 5728.6 5730.9 5811.5 5087.0 5156.5 5206.7 5265.3 5298.8 5249.9 5288.7 5299.1 5306.2 5307.5 5145.9 5169.8 5173.2 5200.6 5283.4 5274.5 5282.4 5298.5 5308.7 5326.8 5092.7 5094.9 5151.0 5181.6 5185.7 5634.0 5650.3 5657.2 5668.3 5701.5 6167.8 6181.7 6181.8 6183.0 6189.3 6018.0 6101.9 6117.7 6133.9 6223.2 5792.8 5797.6 5814.2 5823.1 5823.9 6249.3 6303.1 6306.8 6309.4 6309.7 5937.6 5996.3 6041.2 6050.8 6118.1 6154.7 6192.9 6196.0 6196.7 6209.7 6089.4 6141.1 6151.2 6154.2 6162.7 5984.3 6064.7 6095.3 6108.5 6172.5 4672.7 4724.7 4728.1 4751.7 4761.3 4183.7 4195.2 4203.8 4208.1 4228.0 5684.0 5758.7 5769.3 5802.2 5833.9 6140.3 6141.1 6146.7 6152.1 6159.7 5833.1 5839.5 5844.2 5847.7 5848.4 5191.7 5274.0 5295.6 5302.4 5331.7 6324.1 6383.9 6396.3 6413.4 6422.2 6140.9 6211.3 6222.9 6225.6 6249.1 6121.1 6142.9 6144.3 6165.2 6398.7 6046.4 6047.7 6048.5 6051.2 6081.7 6109.7 6132.4 6135.3 6139.4 6150.5 5926.4 5965.6 5988.0 5992.6 6017.5 5946.9 5989.1 5995.1 6026.1 6035.4 5880.3 5932.3 5942.1 5950.3 5962.5 5876.5 5963.3 6000.7 6037.4 6052.7 5889.2 5900.7 5903.8 5909.7 5945.8 17:15:46 INFO - PROCESS | 3397 | 17:15:46 INFO - PROCESS | 3397 | [#1] ext.html Cycles:180 Average:61901.89 Median:38602.42 stddev:125703.67 (325.6%) stddev-sans-first:125972.04 17:15:46 INFO - PROCESS | 3397 | Values: 600.3 654.5 666.6 671.4 688.6 35609.5 39194.8 50060.2 51335.0 51480.0 48265.3 48490.8 48758.5 48809.0 48938.8 8390.3 9161.1 9310.7 9314.9 9318.8 8371.0 8455.2 8460.7 10912.6 11147.7 10864.6 10881.8 10923.6 10927.9 10932.4 11164.7 11211.9 11244.9 11267.0 11268.4 11051.3 11083.1 11101.3 11121.6 11129.3 38518.4 38686.4 38726.8 38740.0 38848.6 3909.1 3974.0 4078.1 4139.7 4578.4 30560.0 30805.4 30808.5 31234.8 32428.8 4598.4 4659.8 4737.5 4750.6 4780.9 324750.0 325223.0 325890.0 326308.0 326904.4 54827.7 55044.0 55161.4 55554.2 55701.0 35249.8 35635.5 35839.1 35861.6 35922.6 17423.2 17527.9 17549.5 17574.8 17600.8 11579.1 13501.6 13549.4 13592.8 13592.9 20244.4 20682.0 21040.5 21722.2 21870.0 6153.0 6177.5 6248.5 6429.3 6441.1 1002.1 1088.3 1096.3 1124.7 1139.1 31301.2 31483.4 31790.5 31802.2 31994.2 1203.7 1223.9 1228.9 1255.4 1292.0 17498.8 17503.8 17544.5 17588.6 17843.6 366478.0 659858.0 662342.0 662430.0 663964.0 55726.4 56020.2 56156.4 56237.7 56266.7 1357.6 1452.4 1452.6 1461.2 1496.4 34903.7 36238.3 36361.3 36448.6 36513.3 55919.0 56374.7 56476.4 56578.0 56746.0 428600.0 429383.0 430184.0 431102.0 433427.0 30110.7 30332.5 30671.0 30879.5 31225.8 52171.0 53205.5 53538.0 54188.7 54388.1 43485.8 46473.3 46567.5 46751.8 47286.3 44603.8 45254.3 46471.3 46927.0 47632.3 44633.0 45539.5 46057.8 46247.5 46526.0 38427.6 40350.0 40539.2 40609.4 41069.4 29342.9 30573.8 30992.2 31002.0 31128.8 17:15:46 INFO - PROCESS | 3397 | 17:15:46 INFO - PROCESS | 3397 | [#2] jquery.html Cycles:180 Average:32235.70 Median:1584.15 stddev:96366.57 (6083.2%) stddev-sans-first:96607.25 17:15:46 INFO - PROCESS | 3397 | Values: 399.1 413.2 415.5 420.2 430.4 15195.3 19773.9 20403.1 20424.2 20514.8 18699.3 18709.5 18759.3 18938.6 18948.1 5787.5 5803.6 5922.0 5967.0 6034.8 5868.9 5939.5 5947.6 5950.3 5953.3 5717.7 5848.2 5868.1 5879.3 5896.9 5706.3 5879.2 5912.3 5922.4 5930.1 5251.1 5255.5 5256.0 5256.3 5291.4 23281.3 23836.9 23869.8 24031.0 24149.2 2646.2 2666.9 2669.5 2763.9 2769.2 14645.9 15002.1 15183.9 15292.8 15864.4 1977.8 1989.6 2026.3 2063.0 2079.9 86364.0 87215.6 87360.6 87406.6 88103.6 10702.0 10995.4 11369.8 11479.9 11657.4 15660.7 15730.1 15740.1 15770.8 15818.1 8292.8 8326.5 8533.4 8571.8 8621.4 8297.5 8305.3 8334.6 8503.0 8535.6 6787.3 6791.3 6810.3 6810.9 6871.5 2011.6 2033.2 2049.2 2050.8 2086.4 887.3 918.0 919.4 921.2 942.3 18111.8 18986.7 19401.9 19599.6 19794.8 932.8 967.7 985.0 988.5 1014.5 10292.2 10602.8 10785.3 10818.3 10888.8 555279.0 582086.0 586398.0 599985.0 610957.0 85224.0 85510.0 85615.0 85734.0 86150.6 1021.8 1036.4 1042.9 1057.5 1065.5 16931.6 17457.0 17862.8 18181.3 18851.9 46924.5 47321.3 47452.0 47620.8 49081.3 61376.0 61516.7 61669.0 61956.0 63724.7 7960.6 8459.7 8528.0 8660.3 8669.2 10455.5 12847.8 13055.7 13560.0 13970.6 10932.3 11177.7 11202.7 11282.8 11746.2 11376.1 11565.6 11585.8 11589.6 11709.8 11560.0 11846.6 12177.3 12297.0 12449.8 8347.5 8528.9 8694.8 8712.8 9101.2 11069.9 11267.5 11821.4 11986.2 12082.3 17:15:46 INFO - PROCESS | 3397 | 17:15:46 INFO - PROCESS | 3397 | [#3] mootools.html Cycles:180 Average:6216.07 Median:4537.15 stddev:3643.09 (80.3%) stddev-sans-first:3634.15 17:15:46 INFO - PROCESS | 3397 | Values: 1245.1 1307.3 1444.0 1473.3 1818.4 8993.6 9447.5 9464.0 9639.2 9697.6 9522.3 9629.7 9630.2 9634.0 9635.9 2469.0 2489.2 2495.7 2497.7 2503.1 2443.2 2521.6 2523.2 2526.3 2533.7 2443.8 2452.4 2453.8 2462.0 2476.0 2360.7 2392.7 2399.4 2424.7 2429.2 2414.7 2438.8 2447.2 2453.8 2473.8 9516.6 9563.7 9620.9 9633.1 9638.8 5290.0 5432.9 5473.5 5475.0 5507.4 3576.4 3598.6 3656.8 3671.6 3676.7 1152.6 1161.4 1174.6 1175.5 1197.0 13437.3 13477.7 13543.4 13544.3 13572.1 4585.3 4712.1 4777.3 4878.7 4882.1 10316.4 10319.5 10319.7 10323.5 10333.4 5378.1 5398.1 5405.8 5411.8 5416.6 4455.9 4936.6 4950.4 5056.6 5093.4 4094.9 4134.9 4181.8 4220.2 4242.7 1150.5 1150.8 1160.9 1187.7 1194.7 1017.8 1046.0 1053.4 1056.6 1059.4 6192.3 6251.1 6415.1 6430.2 6670.6 459.9 465.4 472.3 492.2 502.3 4327.0 4417.6 4422.8 4464.9 4489.0 11714.4 11938.2 12030.5 12040.5 12070.5 11975.1 11975.4 11978.3 11979.6 11984.2 8478.9 8828.6 8843.4 9069.2 9078.9 6571.1 6584.5 6603.8 6690.3 6854.4 10820.1 11080.6 11088.9 11115.2 11212.4 11251.8 11318.1 11337.4 11364.2 11375.0 7569.0 8056.6 8065.0 8194.5 8389.5 9414.8 9597.0 9613.2 9621.3 9633.8 8096.4 8196.3 8277.5 8298.1 8503.8 8012.9 8018.7 8030.7 8099.4 8383.4 7521.2 7526.9 7557.0 7623.4 7623.6 5295.1 5375.3 5409.2 5440.4 5532.2 5455.8 5505.0 5779.9 5818.4 5851.4 17:15:46 INFO - PROCESS | 3397 | 17:15:46 INFO - PROCESS | 3397 | [#4] prototype.html Cycles:180 Average:4670.58 Median:5209.61 stddev:1341.20 (25.7%) stddev-sans-first:1304.97 17:15:46 INFO - PROCESS | 3397 | Values: 339.3 341.2 351.2 354.9 358.0 5560.4 5569.3 5593.8 5601.7 5602.5 5236.3 5367.6 5384.2 5397.5 5519.6 4234.4 4313.0 4335.4 4353.8 4411.9 4331.6 4343.4 4344.5 4365.0 4376.8 4124.8 4305.8 4321.5 4322.7 4340.4 4288.8 4295.2 4300.5 4304.6 4333.8 3840.4 3848.3 3850.9 3855.9 3859.6 5221.7 5246.3 5377.4 5434.5 5449.4 4881.4 4895.6 4898.8 4898.9 4898.9 5639.5 5664.9 5699.9 5759.9 5760.3 3990.7 4130.7 4141.5 4149.9 4186.4 5896.9 5921.5 5926.8 5966.3 5984.8 4269.0 4272.7 4277.3 4296.2 4301.1 4114.6 4325.2 4326.3 4345.3 4354.5 4851.4 4912.6 4914.1 4917.5 4941.6 5798.5 5811.8 5813.4 5837.6 5852.4 1458.8 1459.6 1481.0 1482.2 1491.5 926.0 939.9 950.1 951.9 959.6 4923.3 4937.9 4955.3 4976.5 4978.4 5157.8 5159.6 5186.3 5221.7 5232.9 4784.3 4935.6 4954.8 4971.9 5059.5 2806.8 2810.3 2822.7 2826.0 2830.3 5411.4 5413.0 5416.4 5428.5 5435.8 5081.0 5231.4 5278.6 5299.8 5311.5 5237.8 5242.4 5245.4 5252.5 5325.7 5179.3 5206.6 5212.6 5213.0 5248.3 4834.5 5103.1 5123.0 5124.2 5131.5 5257.2 5307.4 5336.9 5362.3 5382.7 3873.4 3882.2 3890.2 3915.2 4036.1 5433.4 5885.3 5893.9 5908.4 5910.2 5770.5 5774.8 5787.5 5787.8 5799.0 5505.6 5776.9 5787.7 5801.7 5805.3 5764.0 5770.0 5776.2 5794.3 5794.3 5829.7 5848.5 5871.4 5874.6 5881.0 5510.4 5675.9 5683.7 5694.3 5708.6 17:15:46 INFO - PROCESS | 3397 | 17:15:46 INFO - PROCESS | 3397 | [#5] yui.html Cycles:180 Average:21864.07 Median:4640.08 stddev:31520.33 (679.3%) stddev-sans-first:31568.94 17:15:46 INFO - PROCESS | 3397 | Values: 765.2 840.2 844.6 857.8 910.1 13980.6 14732.0 14821.6 14864.9 14893.3 14074.9 14150.8 14185.0 14186.3 14207.7 739.5 844.5 861.3 882.8 883.0 887.7 889.9 890.2 891.5 891.6 942.2 946.1 947.9 948.7 949.2 762.0 779.2 781.2 886.3 933.8 671.1 674.7 675.1 675.3 675.6 14911.3 14941.7 14954.3 14954.4 14955.7 13633.0 13700.6 13982.2 14173.2 14238.7 14364.9 14533.3 14583.9 14773.4 14790.3 4607.2 4634.5 4661.7 4728.2 4747.2 83274.6 83480.2 83597.6 83629.7 83930.6 11974.3 12011.8 12031.5 12032.5 12122.1 23091.9 23215.9 23224.0 23242.3 24492.5 8473.5 8554.3 8581.8 8748.2 8803.8 4829.2 5032.3 5092.9 5248.6 5291.6 3628.2 3758.2 3772.4 3773.8 3775.5 622.4 630.0 630.6 642.9 668.2 634.0 713.6 713.7 720.1 733.1 9665.6 9722.0 9746.2 9843.8 9903.6 640.3 682.8 701.6 708.5 719.2 4548.9 4639.8 4640.4 4860.0 4871.3 76219.6 76569.6 136602.0 139530.3 144226.0 88640.0 104882.0 105523.4 105778.0 110028.0 66979.0 92034.5 93652.0 94146.5 95512.5 10522.8 10651.9 10704.7 10759.9 10910.3 53576.5 54817.5 55036.4 55377.7 56099.2 75346.0 77048.6 82479.6 83614.6 83970.6 13652.9 13804.1 13919.6 14079.7 14128.4 15692.8 16080.8 16268.8 16363.3 16640.7 10589.8 11232.9 13637.8 15660.3 16076.1 15251.2 15255.9 15550.1 15693.5 16970.5 14200.4 14468.7 14837.8 14854.9 15399.8 12230.8 12625.2 12653.4 12681.6 12779.2 12899.4 12934.7 13217.2 13266.9 13280.0 17:15:46 INFO - PROCESS | 3397 | -------- Summary: end -------- 17:15:46 INFO - PROCESS | 3397 | 17:15:47 INFO - TEST-INFO | 3397: exit 0 17:15:47 INFO - TEST-OK | dromaeo_css | took 1115708ms 17:15:47 INFO - TEST-START | kraken 17:15:47 INFO - operating with platform_type : linux_ 17:15:47 INFO - Initialising browser for kraken test... 17:15:47 INFO - TEST-INFO | started process 3593 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmps5_X2z/profile http://localhost:40896/getInfo.html) 17:15:51 INFO - PROCESS | 3593 | __metrics Screen width/height:1600/1200 17:15:51 INFO - PROCESS | 3593 | colorDepth:24 17:15:51 INFO - PROCESS | 3593 | Browser inner width/height: 1024/697 17:15:51 INFO - PROCESS | 3593 | __metrics 17:15:52 INFO - PROCESS | 3593 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:15:52 INFO - PROCESS | 3593 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:15:53 INFO - TEST-INFO | 3593: exit 0 17:15:53 INFO - Browser initialized. 17:15:53 INFO - Running cycle 1/1 for kraken test... 17:15:53 INFO - TEST-INFO | started process 3683 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmps5_X2z/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:16:05 INFO - PROCESS | 3683 | RSS: Main: 145084416 17:16:05 INFO - PROCESS | 3683 | 17:16:08 INFO - PROCESS | 3683 | Cycle 1(1): loaded http://localhost:40896/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:40896/tests/kraken/driver.html?testName=audio-beat-detection) 17:16:08 INFO - PROCESS | 3683 | RSS: Main: 190959616 17:16:08 INFO - PROCESS | 3683 | 17:16:11 INFO - PROCESS | 3683 | Cycle 1(1): loaded http://localhost:40896/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:40896/tests/kraken/driver.html?testName=audio-dft) 17:16:12 INFO - PROCESS | 3683 | RSS: Main: 181911552 17:16:12 INFO - PROCESS | 3683 | 17:16:14 INFO - PROCESS | 3683 | Cycle 1(1): loaded http://localhost:40896/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:40896/tests/kraken/driver.html?testName=audio-fft) 17:16:15 INFO - PROCESS | 3683 | RSS: Main: 269651968 17:16:15 INFO - PROCESS | 3683 | 17:16:17 INFO - PROCESS | 3683 | Cycle 1(1): loaded http://localhost:40896/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:40896/tests/kraken/driver.html?testName=audio-oscillator) 17:16:18 INFO - PROCESS | 3683 | RSS: Main: 175554560 17:16:18 INFO - PROCESS | 3683 | 17:16:20 INFO - PROCESS | 3683 | Cycle 1(1): loaded http://localhost:40896/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:40896/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:16:20 INFO - PROCESS | 3683 | RSS: Main: 176848896 17:16:20 INFO - PROCESS | 3683 | 17:16:23 INFO - PROCESS | 3683 | Cycle 1(1): loaded http://localhost:40896/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:40896/tests/kraken/driver.html?testName=imaging-darkroom) 17:16:24 INFO - PROCESS | 3683 | RSS: Main: 213282816 17:16:24 INFO - PROCESS | 3683 | 17:16:27 INFO - PROCESS | 3683 | Cycle 1(1): loaded http://localhost:40896/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:40896/tests/kraken/driver.html?testName=imaging-desaturate) 17:16:28 INFO - PROCESS | 3683 | RSS: Main: 212246528 17:16:28 INFO - PROCESS | 3683 | 17:16:31 INFO - PROCESS | 3683 | Cycle 1(1): loaded http://localhost:40896/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:40896/tests/kraken/driver.html?testName=json-parse-financial) 17:16:31 INFO - PROCESS | 3683 | RSS: Main: 212262912 17:16:31 INFO - PROCESS | 3683 | 17:16:33 INFO - PROCESS | 3683 | Cycle 1(1): loaded http://localhost:40896/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:40896/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:16:34 INFO - PROCESS | 3683 | RSS: Main: 176521216 17:16:34 INFO - PROCESS | 3683 | 17:16:35 INFO - PROCESS | 3683 | Cycle 1(1): loaded http://localhost:40896/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:40896/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:16:36 INFO - PROCESS | 3683 | RSS: Main: 182358016 17:16:36 INFO - PROCESS | 3683 | 17:16:38 INFO - PROCESS | 3683 | Cycle 1(1): loaded http://localhost:40896/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:40896/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:16:38 INFO - PROCESS | 3683 | RSS: Main: 187207680 17:16:38 INFO - PROCESS | 3683 | 17:16:41 INFO - PROCESS | 3683 | Cycle 1(1): loaded http://localhost:40896/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:40896/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:16:42 INFO - PROCESS | 3683 | RSS: Main: 182611968 17:16:42 INFO - PROCESS | 3683 | 17:16:45 INFO - PROCESS | 3683 | Cycle 1(1): loaded http://localhost:40896/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:40896/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:16:46 INFO - PROCESS | 3683 | RSS: Main: 185102336 17:16:46 INFO - PROCESS | 3683 | 17:16:47 INFO - PROCESS | 3683 | Cycle 1(1): loaded http://localhost:40896/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:40896/tests/kraken/driver.html?testName=ai-astar) 17:16:47 INFO - PROCESS | 3683 | RSS: Main: 235352064 17:16:47 INFO - PROCESS | 3683 | 17:16:47 INFO - PROCESS | 3683 | __start_tp_report 17:16:47 INFO - PROCESS | 3683 | _x_x_mozilla_page_load 17:16:47 INFO - PROCESS | 3683 | _x_x_mozilla_page_load_details 17:16:47 INFO - PROCESS | 3683 | |i|pagename|runs| 17:16:47 INFO - PROCESS | 3683 | |0;ai-astar;99;110;110;110;109;111;109;110;108;109 17:16:47 INFO - PROCESS | 3683 | |1;audio-beat-detection;147;121;116;175;116;118;146;119;155;119 17:16:47 INFO - PROCESS | 3683 | |2;audio-dft;161;161;160;159;162;163;160;160;159;160 17:16:47 INFO - PROCESS | 3683 | |3;audio-fft;74;75;74;112;75;75;74;75;103;74 17:16:47 INFO - PROCESS | 3683 | |4;audio-oscillator;83;83;136;83;83;83;123;82;83;131 17:16:47 INFO - PROCESS | 3683 | |5;imaging-gaussian-blur;117;117;117;117;117;117;116;117;117;116 17:16:47 INFO - PROCESS | 3683 | |6;imaging-darkroom;146;146;146;146;147;145;146;146;147;146 17:16:47 INFO - PROCESS | 3683 | |7;imaging-desaturate;95;95;95;95;95;95;96;95;95;95 17:16:47 INFO - PROCESS | 3683 | |8;json-parse-financial;51;50;50;49;48;50;52;49;50;51 17:16:47 INFO - PROCESS | 3683 | |9;json-stringify-tinderbox;61;61;60;60;61;60;59;60;59;60 17:16:47 INFO - PROCESS | 3683 | |10;stanford-crypto-aes;73;74;75;74;73;73;73;72;78;80 17:16:47 INFO - PROCESS | 3683 | |11;stanford-crypto-ccm;121;121;121;121;133;117;118;127;157;122 17:16:47 INFO - PROCESS | 3683 | |12;stanford-crypto-pbkdf2;155;153;150;153;154;154;160;155;154;156 17:16:47 INFO - PROCESS | 3683 | |13;stanford-crypto-sha256-iterative;63;61;66;61;63;65;60;61;61;60 17:16:47 INFO - PROCESS | 3683 | __end_tp_report 17:16:47 INFO - PROCESS | 3683 | __start_cc_report 17:16:47 INFO - PROCESS | 3683 | _x_x_mozilla_cycle_collect,1633 17:16:47 INFO - PROCESS | 3683 | __end_cc_report 17:16:47 INFO - PROCESS | 3683 | __startTimestamp1471479407959__endTimestamp 17:16:47 INFO - PROCESS | 3683 | 17:16:47 INFO - PROCESS | 3683 | ------- Summary: start ------- 17:16:47 INFO - PROCESS | 3683 | Number of tests: 14 17:16:47 INFO - PROCESS | 3683 | 17:16:47 INFO - PROCESS | 3683 | [#0] ai-astar Cycles:10 Average:108.50 Median:110.00 stddev:3.44 (3.1%) stddev-sans-first:0.88 17:16:47 INFO - PROCESS | 3683 | Values: 99.0 110.0 110.0 110.0 109.0 111.0 109.0 110.0 108.0 109.0 17:16:47 INFO - PROCESS | 3683 | 17:16:47 INFO - PROCESS | 3683 | [#1] audio-beat-detection Cycles:10 Average:133.20 Median:133.50 stddev:20.95 (15.7%) stddev-sans-first:21.62 17:16:47 INFO - PROCESS | 3683 | Values: 147.0 121.0 116.0 175.0 116.0 118.0 146.0 119.0 155.0 119.0 17:16:47 INFO - PROCESS | 3683 | 17:16:47 INFO - PROCESS | 3683 | [#2] audio-dft Cycles:10 Average:160.50 Median:160.50 stddev:1.27 (0.8%) stddev-sans-first:1.33 17:16:47 INFO - PROCESS | 3683 | Values: 161.0 161.0 160.0 159.0 162.0 163.0 160.0 160.0 159.0 160.0 17:16:47 INFO - PROCESS | 3683 | 17:16:47 INFO - PROCESS | 3683 | [#3] audio-fft Cycles:10 Average:81.10 Median:74.50 stddev:14.08 (18.9%) stddev-sans-first:14.70 17:16:47 INFO - PROCESS | 3683 | Values: 74.0 75.0 74.0 112.0 75.0 75.0 74.0 75.0 103.0 74.0 17:16:47 INFO - PROCESS | 3683 | 17:16:47 INFO - PROCESS | 3683 | [#4] audio-oscillator Cycles:10 Average:97.00 Median:83.00 stddev:22.98 (27.7%) stddev-sans-first:23.81 17:16:47 INFO - PROCESS | 3683 | Values: 83.0 83.0 136.0 83.0 83.0 83.0 123.0 82.0 83.0 131.0 17:16:47 INFO - PROCESS | 3683 | 17:16:47 INFO - PROCESS | 3683 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 17:16:47 INFO - PROCESS | 3683 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 116.0 117.0 117.0 116.0 17:16:47 INFO - PROCESS | 3683 | 17:16:47 INFO - PROCESS | 3683 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 17:16:47 INFO - PROCESS | 3683 | Values: 146.0 146.0 146.0 146.0 147.0 145.0 146.0 146.0 147.0 146.0 17:16:47 INFO - PROCESS | 3683 | 17:16:47 INFO - PROCESS | 3683 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 17:16:47 INFO - PROCESS | 3683 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 96.0 95.0 95.0 95.0 17:16:47 INFO - PROCESS | 3683 | 17:16:47 INFO - PROCESS | 3683 | [#8] json-parse-financial Cycles:10 Average:50.00 Median:50.00 stddev:1.15 (2.3%) stddev-sans-first:1.17 17:16:47 INFO - PROCESS | 3683 | Values: 51.0 50.0 50.0 49.0 48.0 50.0 52.0 49.0 50.0 51.0 17:16:47 INFO - PROCESS | 3683 | 17:16:47 INFO - PROCESS | 3683 | [#9] json-stringify-tinderbox Cycles:10 Average:60.10 Median:60.00 stddev:0.74 (1.2%) stddev-sans-first:0.71 17:16:47 INFO - PROCESS | 3683 | Values: 61.0 61.0 60.0 60.0 61.0 60.0 59.0 60.0 59.0 60.0 17:16:47 INFO - PROCESS | 3683 | 17:16:47 INFO - PROCESS | 3683 | [#10] stanford-crypto-aes Cycles:10 Average:74.50 Median:74.00 stddev:2.55 (3.4%) stddev-sans-first:2.65 17:16:47 INFO - PROCESS | 3683 | Values: 73.0 74.0 75.0 74.0 73.0 73.0 73.0 72.0 78.0 80.0 17:16:47 INFO - PROCESS | 3683 | 17:16:47 INFO - PROCESS | 3683 | [#11] stanford-crypto-ccm Cycles:10 Average:125.80 Median:121.50 stddev:11.89 (9.8%) stddev-sans-first:12.48 17:16:47 INFO - PROCESS | 3683 | Values: 121.0 121.0 121.0 121.0 133.0 117.0 118.0 127.0 157.0 122.0 17:16:47 INFO - PROCESS | 3683 | 17:16:47 INFO - PROCESS | 3683 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.40 Median:154.50 stddev:2.55 (1.6%) stddev-sans-first:2.69 17:16:47 INFO - PROCESS | 3683 | Values: 155.0 153.0 150.0 153.0 154.0 154.0 160.0 155.0 154.0 156.0 17:16:47 INFO - PROCESS | 3683 | 17:16:47 INFO - PROCESS | 3683 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.10 Median:62.00 stddev:2.08 (3.4%) stddev-sans-first:2.18 17:16:47 INFO - PROCESS | 3683 | Values: 63.0 61.0 66.0 61.0 63.0 65.0 60.0 61.0 61.0 60.0 17:16:47 INFO - PROCESS | 3683 | -------- Summary: end -------- 17:16:47 INFO - PROCESS | 3683 | 17:16:48 INFO - TEST-INFO | 3683: exit 0 17:16:49 INFO - TEST-OK | kraken | took 61743ms 17:16:49 INFO - SUITE-END | took 1177s 17:16:49 INFO - Completed test suite (00:19:37) 17:16:49 INFO - javascript benchmark 17:16:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3284.8357582120893, 3314.2211580278617, 3327.2846338037316, 3329.517220003101, 3374.4263475209214, 5677.176809362645, 5806.390329015456, 5811.6803575803315, 5816.534677225823, 5857.677827719468, 5715.771369145229, 5716.742746576404, 5728.570357223214, 5730.856728581787, 5811.535077193825, 5087.033463641915, 5156.458571849958, 5206.713630750307, 5265.315508983846, 5298.761555730001, 5249.921251181236, 5288.65623734456, 5299.072662284085, 5306.151015837467, 5307.469253074701, 5145.871353216162, 5169.819056333029, 5173.249878767577, 5200.557952574017, 5283.365996080456, 5274.498922602346, 5282.366116066097, 5298.470152984709, 5308.681479111227, 5326.813561525328, 5092.7453627318855, 5094.872628184288, 5151.021305951857, 5181.559567436772, 5185.714785686786, 5634.04221282383, 5650.265465489501, 5657.20799088128, 5668.26312579362, 5701.543876489871, 6167.784127555514, 6181.690915454209, 6181.845453863644, 6183.0, 6189.2572891252885, 6018.037114061773, 6101.877962440771, 6117.66352850593, 6133.907991380132, 6223.190985171944, 5792.812473442934, 5797.565182611321, 5814.156947242668, 5823.126531020381, 5823.941760582382, 6249.34381889895, 6303.054541818764, 6306.801707675527, 6309.3690630936535, 6309.652969086745, 5937.614055086406, 5996.340402555716, 6041.214642096499, 6050.757969681252, 6118.071005858243, 6154.661493617805, 6192.907106393408, 6196.0, 6196.659183744847, 6209.720562574695, 6089.360617135226, 6141.140240366352, 6151.231096112986, 6154.1999540059505, 6162.691865406801, 5984.251968503937, 6064.696765161724, 6095.329513753483, 6108.480779133734, 6172.506199504029, 4672.719636821802, 4724.71651700899, 4728.0780247851335, 4751.714897106115, 4761.333413322138, 4183.728057676241, 4195.160967806451, 4203.831846726158, 4208.095259519218, 4227.98528353193, 5683.971580142155, 5758.712064396764, 5769.307683078057, 5802.158686990363, 5833.941660583425, 6140.293866205444, 6141.140240366352, 6146.661933593606, 6152.077188421773, 6159.692015399301, 5833.066709326487, 5839.532837373, 5844.1818145459665, 5847.707614619337, 5848.4151584841175, 5191.688498690015, 5273.973630131902, 5295.576353891679, 5302.416734159239, 5331.680099194061, 6324.1146239527425, 6383.904241436289, 6396.328385519547, 6413.390727880844, 6422.165118534561, 6140.938590614037, 6211.3478084802255, 6222.906656400067, 6225.595336303126, 6249.125122482947, 6121.117656725397, 6142.877142457215, 6144.293406258159, 6165.167702360124, 6398.680065996775, 6046.395360464095, 6047.697615119315, 6048.4858787003595, 6051.243594550681, 6081.6655083969035, 6109.725062372115, 6132.386761323832, 6135.263768347827, 6139.447449729546, 6150.477209437248, 5926.377730338252, 5965.552583556164, 5987.970060149672, 5992.610480318765, 6017.518598512196, 5946.881062378815, 5989.071693887455, 5995.070764031582, 6026.0659597762415, 6035.426634469718, 5880.26496687914, 5932.347441781487, 5942.10868369748, 5950.31571369298, 5962.4633782959745, 5876.471117599436, 5963.3142188647125, 6000.69996500182, 6037.396260373927, 6052.727627256696, 5889.204957330706, 5900.734466948912, 5903.763849445974, 5909.734061967136, 5945.791897283574], "value": 5661.550635464371, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [600.3036477685886, 654.509118161379, 666.5667316244437, 671.3689132215717, 688.614375949468, 35609.46585801212, 39194.804025979865, 50060.24909626354, 51335.0, 51480.0, 48265.27602085963, 48490.757546212306, 48758.51241487584, 48809.0, 48938.75530622351, 8390.32877369812, 9161.129692679186, 9310.720678379626, 9314.906850931506, 9318.76703082425, 8370.958145209295, 8455.239028487435, 8460.703875364363, 10912.563497460133, 11147.721306967309, 10864.565417383295, 10881.83677244842, 10923.617673381434, 10927.945360273188, 10932.398718070503, 11164.720881977933, 11211.887881121165, 11244.943775281154, 11267.0, 11268.4365781711, 11051.281666691639, 11083.057940075103, 11101.278416902875, 11121.610743624015, 11129.332240065622, 38518.42222366667, 38686.419703704465, 38726.806365968274, 38740.03149921246, 38848.611513885066, 3909.139989202371, 3973.980130099331, 4078.1028173801715, 4139.730917490354, 4578.404807375054, 30560.0, 30805.38389232203, 30808.537871931938, 31234.843825780954, 32428.83785581057, 4598.356230127785, 4659.836905708284, 4737.549932756383, 4750.572448479619, 4780.904381912377, 324750.0, 325223.0, 325890.0, 326308.0, 326904.3654781735, 54827.72586137044, 55044.0, 55161.448385516436, 55554.16668749972, 55701.0, 35249.823750881595, 35635.465468018, 35839.104022399646, 35861.64138358635, 35922.64077359193, 17423.21595528204, 17527.912360438117, 17549.473515794547, 17574.824251757575, 17600.823991759917, 11579.073674106052, 13501.59495215145, 13549.390277437538, 13592.796108058386, 13592.864071359358, 20244.392668219978, 20682.0, 21040.473988150417, 21722.23972160967, 21870.0, 6152.969235153795, 6177.459567218091, 6248.5313601479165, 6429.260635026938, 6441.0982462455295, 1002.0530598584268, 1088.292609803634, 1096.2928910852456, 1124.656979621207, 1139.1342579639368, 31301.217469563442, 31483.37033259322, 31790.523142152888, 31802.2049448761, 31994.200144996095, 1203.6990752311922, 1223.8837310455492, 1228.8586812516496, 1255.3911352993791, 1291.9547815826402, 17498.825011749974, 17503.824961750473, 17544.473665790047, 17588.560285992953, 17843.553911152325, 366478.0, 659858.0, 662342.0, 662430.0, 663964.0, 55726.442735572935, 56020.15969760457, 56156.43843561512, 56237.718811405684, 56266.71866640641, 1357.565579014706, 1452.3609611770999, 1452.6295794572316, 1461.1598330959528, 1496.438835436733, 34903.65096349004, 36238.27523449569, 36361.27277454489, 36448.63551364558, 36513.26973460569, 55919.0, 56374.718126409105, 56476.43523564712, 56578.0, 56746.0, 428600.0, 429383.0, 430184.0, 431102.0, 433427.0, 30110.698893011664, 30332.545011824397, 30671.0, 30879.536806948363, 31225.84387078141, 52171.0, 53205.46794532005, 53538.0, 54188.72905635447, 54388.09641662523, 43485.782571086944, 46473.302900457194, 46567.534324657674, 46751.766241168574, 47286.29070564013, 44603.77698111489, 45254.32118518159, 46471.30293045539, 46927.0, 47632.28551571799, 44633.0, 45539.54460455353, 46057.76971115123, 46247.53752462432, 46526.0, 38427.615723842406, 40350.0, 40539.1892162161, 40609.390859137726, 41069.38395924123, 29342.853285733436, 30573.847130764203, 30992.22519437032, 31002.0, 31128.844355778077], "value": 21135.908409254316, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [399.06817580948484, 413.18602353363883, 415.47649961049075, 420.23097731151984, 430.39744357898957, 15195.316210770514, 19773.901130494363, 20403.08186131624, 20424.183032678695, 20514.794852051476, 18699.3455229067, 18709.532261693465, 18759.343422980197, 18938.62122757544, 18948.052597370144, 5787.508061814729, 5803.6227645203035, 5922.0, 5966.970165149168, 6034.818955431322, 5868.882622347551, 5939.495142912836, 5947.5836691431405, 5950.256217972754, 5953.255843019622, 5717.714114294289, 5848.152017957394, 5868.061110222366, 5879.32387775407, 5896.911546326808, 5706.343770466408, 5879.235699359077, 5912.260967379078, 5922.4373684500115, 5930.080837470199, 5251.10731175701, 5255.527002569749, 5256.0, 5256.316678831728, 5291.417944026154, 23281.301560953198, 23836.880815595985, 23869.761302386927, 24031.0, 24149.15477958263, 2646.1958443965063, 2666.946661066768, 2669.4927963686837, 2763.8618069096665, 2769.2246171072134, 14645.926770366186, 15002.099874007594, 15183.924080379527, 15292.847071529253, 15864.36542538297, 1977.7824439311664, 1989.6120256549978, 2026.331310667491, 2063.0, 2079.864808787439, 86364.0, 87215.56392218062, 87360.56319718361, 87406.5629671854, 88103.55948220218, 10702.0, 10995.395253261147, 11369.772604547863, 11479.942600286946, 11657.358845263423, 15660.68678626444, 15730.134842583768, 15740.134292613788, 15770.76343854843, 15818.130002849724, 8292.751217463485, 8326.50040997542, 8533.445326053787, 8571.82856342882, 8621.353398495137, 8297.502149871027, 8305.252527272574, 8334.583270836556, 8503.0, 8535.573221338907, 6787.321267873173, 6791.320867913169, 6810.31896810315, 6810.9318906810295, 6871.484638652021, 2011.597680463913, 2033.1867253098583, 2049.211053744319, 2050.7846676098793, 2086.3636590839938, 887.2547060469108, 918.0452329577298, 919.3518569408551, 921.2077613252598, 942.2697409507687, 18111.818881811276, 18986.71519927202, 19401.932893690984, 19599.60800784005, 19794.802051979583, 932.849781254202, 967.690339091484, 984.9507524623883, 988.515627342597, 1014.5079636376352, 10292.176625869912, 10602.840957385646, 10785.298955567863, 10818.29681070728, 10888.78222435547, 555279.0, 582086.0, 586398.0, 599985.0, 610957.0, 85224.0, 85510.0, 85615.0, 85734.0, 86150.56924715337, 1021.7752094539189, 1036.3574583757968, 1042.9426381549088, 1057.5241141486208, 1065.4566171252561, 16931.576710582332, 17457.0, 17862.821371786635, 18181.272749089887, 18851.905740471757, 46924.530754692016, 47321.29018064801, 47452.0, 47620.76189619168, 49081.2637810426, 61376.0, 61516.69241653763, 61669.0, 61956.0, 63724.68137659467, 7960.562169080757, 8459.703910363107, 8528.0, 8660.307175426076, 8669.176428239307, 10455.477226138815, 12847.807282890952, 13055.738885222052, 13560.0, 13970.580882573538, 10932.289401189215, 11177.72055698614, 11202.66392008241, 11282.830757538479, 11746.17776755619, 11376.146788991024, 11565.59520416815, 11585.826212606986, 11589.594364197214, 11709.824352634887, 11560.017398520884, 11846.644600661994, 12177.269363838199, 12297.0, 12449.751004980031, 8347.499150050773, 8528.91471085306, 8694.82610347802, 8712.825743485222, 9101.226395756436, 11069.889301106887, 11267.492962816528, 11821.408929553316, 11986.160968732105, 12082.33547154915], "value": 9627.157347071767, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1245.0786418050645, 1307.2744626732162, 1443.9531339778662, 1473.2928194466656, 1818.4181062060145, 8993.59528821203, 9447.480388578626, 9464.0, 9639.180669643089, 9697.563609637566, 9522.285828562852, 9629.662961796339, 9630.18143457807, 9633.951830240856, 9635.903640963588, 2468.975310246897, 2489.2158969924417, 2495.6880389951257, 2497.67530221071, 2503.0988844016138, 2443.1693224303735, 2521.6091505816626, 2523.205190365029, 2526.343150780792, 2533.721290658026, 2443.780059794618, 2452.350127216289, 2453.7791598756107, 2462.0398044762555, 2475.987620061906, 2360.6695062691238, 2392.676988606533, 2399.4121440247163, 2424.7211570669247, 2429.2347910408166, 2414.7464516225896, 2438.8414753040997, 2447.192426499251, 2453.766892145243, 2473.839200451983, 9516.571754271074, 9563.713088607354, 9620.90379096214, 9633.133018028411, 9638.807223855554, 5289.973550132225, 5432.891342173134, 5473.480019398191, 5474.972625136888, 5507.449255074501, 3576.409892367756, 3598.6221446747977, 3656.835442405098, 3671.5777685566236, 3676.7058635309113, 1152.6369193703956, 1161.384466232898, 1174.6123779152858, 1175.518037604578, 1196.9640910772691, 13437.260950647807, 13477.730445391135, 13543.390547425292, 13544.322783860767, 13572.117812342167, 4585.335126406652, 4712.128256272532, 4777.283407488904, 4878.707277563358, 4882.12121818069, 10316.43259620728, 10319.48402579868, 10319.690409287734, 10323.535440905178, 10333.431661258703, 5378.058839703036, 5398.055340315429, 5405.7567409466665, 5411.756470958816, 5416.620836541404, 4455.866324010285, 4936.580390666833, 4950.430700469492, 5056.646034777604, 5093.414257360451, 4094.897627559335, 4134.855280065183, 4181.770002649822, 4220.198162349206, 4242.745435273893, 1150.5110328110686, 1150.8331291962786, 1160.9419529023514, 1187.679326581818, 1194.7192409783695, 1017.8320577104695, 1045.9790804183874, 1053.3837704942694, 1056.57736905237, 1059.422614675004, 6192.318844927055, 6251.093591429309, 6415.101885735908, 6430.228372595318, 6670.633115178614, 459.90571932753704, 465.41822721598004, 472.34344261476576, 492.1608657239398, 502.2767215210086, 4327.0, 4417.624501917308, 4422.845200418034, 4464.910701785946, 4488.955110448919, 11714.355710435837, 11938.22401543897, 12030.4586293617, 12040.458179381949, 12070.45682944252, 11975.101867359983, 11975.401229938469, 11978.281303121666, 11979.58071467512, 11984.22102563348, 8478.872816907751, 8828.602712877935, 8843.380963332505, 9069.18377346042, 9078.86381704275, 6571.145751052524, 6584.539082264196, 6603.801885943237, 6690.264070952094, 6854.383105520527, 10820.134389248999, 11080.61217857371, 11088.944555277172, 11115.22193446451, 11212.383318917538, 11251.774964500828, 11318.094552435621, 11337.433128343715, 11364.204505684853, 11375.0, 7568.962155189409, 8056.5971701415865, 8064.9596752018215, 8194.467359621605, 8389.496630202208, 9414.811703766023, 9596.95201523988, 9613.182879455324, 9621.326507144433, 9633.807323853343, 8096.433249672466, 8196.30331421812, 8277.54473503963, 8298.128696486781, 8503.787405314917, 8012.901374728579, 8018.719344822904, 8030.678772849021, 8099.433039687401, 8383.413161078666, 7521.210272921483, 7526.924730752622, 7556.962215188889, 7623.352015078781, 7623.618819058914, 5295.099833028317, 5375.3012108425655, 5409.2427060212385, 5440.401555828935, 5532.1701744737, 5455.809046683347, 5505.009098362333, 5779.913301300399, 5818.44724751148, 5851.385604511635], "value": 4828.293370452008, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [339.32135728542914, 341.18456888037593, 351.213282247765, 354.8669249031613, 357.96957258633, 5560.4161563035905, 5569.303837020372, 5593.776248950047, 5601.6753047485345, 5602.53488465075, 5236.250968241675, 5367.624266301361, 5384.165454354572, 5397.46025397461, 5519.586031047677, 4234.449521562193, 4312.956870431287, 4335.43639326887, 4353.825846966118, 4411.867643970686, 4331.5884990925815, 4343.413639158721, 4344.543822898598, 4364.956350436502, 4376.803043863034, 4124.773137477438, 4305.849295274666, 4321.459817522809, 4322.675799315049, 4340.370646256291, 4288.849890253858, 4295.248331541968, 4300.526942036374, 4304.612584867361, 4333.804978775946, 3840.36633955397, 3848.306745032177, 3850.9397472352157, 3855.8843234703004, 3859.6333348331864, 5221.71280579568, 5246.291750613656, 5377.4084850666395, 5434.510894019557, 5449.427810079964, 4881.414230292387, 4895.5838753705975, 4898.755062246873, 4898.893475805402, 4898.90202195954, 5639.477341117916, 5664.886702265972, 5699.857503562403, 5759.884802303931, 5760.251167348218, 3990.7206495545324, 4130.710850240484, 4141.5237247716295, 4149.937750933738, 4186.446267776654, 5896.882062358772, 5921.526277897758, 5926.822195334147, 5966.254218222723, 5984.820455386345, 4269.033022049645, 4272.743635381825, 4277.337012763027, 4296.183725092223, 4301.0537681709975, 4114.6091121343425, 4325.2214601371425, 4326.286162783105, 4345.261305577996, 4354.521002689701, 4851.417829860439, 4912.557869791736, 4914.140025495524, 4917.483664215278, 4941.629377796679, 5798.478136967608, 5811.79658711943, 5813.447722466359, 5837.6497410155525, 5852.414758524199, 1458.7738900470445, 1459.5986103821322, 1480.9555713328402, 1482.1773915477013, 1491.486141566311, 925.9907400925905, 939.9472590698462, 950.0515058755766, 951.8672779392639, 959.5633986536109, 4923.261510773413, 4937.87655308613, 4955.306257124005, 4976.477469865612, 4978.431793984883, 5157.776389756402, 5159.561437277798, 5186.325777648882, 5221.660592061504, 5232.874931889586, 4784.258439941815, 4935.629827762932, 4954.777035033432, 4971.900561988812, 5059.494050594984, 2806.8175568587976, 2810.2552823501787, 2822.6612806463354, 2825.971740282571, 2830.3207230265, 5411.428457218296, 5413.0, 5416.350037995466, 5428.5385742211565, 5435.83692489226, 5080.974595127001, 5231.398389185217, 5278.551323137576, 5299.788008479694, 5311.521963023347, 5237.764300606482, 5242.370915490165, 5245.423003469616, 5252.527272545489, 5325.707086110224, 5179.326687530597, 5206.589683757145, 5212.635115541876, 5212.957408518236, 5248.317718696545, 4834.540718631724, 5103.137354865522, 5122.974385128051, 5124.154514505065, 5131.512506311894, 5257.185136303879, 5307.363116426053, 5336.919946200887, 5362.302900622894, 5382.650127741685, 3873.3608954523315, 3882.204148149566, 3890.191061156576, 3915.158240978203, 4036.1322315702264, 5433.429489903503, 5885.323187833283, 5893.941060589511, 5908.379620139824, 5910.231669882888, 5770.5095066919785, 5774.816162686555, 5787.536997040311, 5787.7974270902005, 5799.0, 5505.559555235652, 5776.8555786104, 5787.652740835563, 5801.709914504342, 5805.332386775575, 5763.971180144073, 5769.9711501442225, 5776.249087618583, 5794.304683438015, 5794.333651630117, 5829.659178389079, 5848.5028772553105, 5871.412858714265, 5874.647521148745, 5880.970595146997, 5510.448955104457, 5675.914861277001, 5683.658980461186, 5694.259746232963, 5708.600397972102], "value": 4247.509922832849, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [765.2232983521725, 840.1514470384914, 844.6452489954219, 857.7898414888351, 910.1035480052143, 13980.580582582526, 14732.0, 14821.55535333939, 14864.851351486483, 14893.255337233122, 14074.929625351859, 14150.78773818391, 14185.0, 14186.290685465738, 14207.715845683106, 739.5267029101379, 844.5397258494136, 861.332467337815, 882.7837179890936, 882.9867551986694, 887.7026196224273, 889.8798662180587, 890.1899271662788, 891.5408564589242, 891.5631340643102, 942.236788201559, 946.1437399153776, 947.8957314695378, 948.7201275623673, 949.1505102932871, 762.0, 779.1779672445539, 781.2460975158942, 886.3308202307217, 933.785229397239, 671.1275342055358, 674.7402250133684, 675.1459403854128, 675.2909445082644, 675.6013951768431, 14911.254437278201, 14941.701165976729, 14954.252287385587, 14954.40182392703, 14955.700885982329, 13633.0, 13700.588982330446, 13982.161070335813, 14173.220472873987, 14238.715225695429, 14364.928175359057, 14533.34599943005, 14583.854161458356, 14773.409063637442, 14790.260486975609, 4607.17070927233, 4634.536546345372, 4661.720296782204, 4728.219843725781, 4747.169245382044, 83274.58362708148, 83480.16519834785, 83597.58201209016, 83629.74555381674, 83930.58034709848, 11974.281543107441, 12011.75976480483, 12031.518739250332, 12032.458539365749, 12122.090843186796, 23091.88454057719, 23215.883920580625, 23224.0, 23242.302730918098, 24492.510149796904, 8473.5339556325, 8554.27288680469, 8581.828363432696, 8748.212660860427, 8803.779905502413, 4829.203181475017, 5032.345795046621, 5092.872678183075, 5248.63259571834, 5291.603129765283, 3628.238070005276, 3758.210775737126, 3772.434134879735, 3773.75470594416, 3775.4714339992424, 622.4180391334118, 629.9622022678562, 630.5932673425615, 642.9099926010363, 668.1647940074906, 633.9746410143542, 713.6182142553708, 713.7430525010992, 720.1070672366228, 733.0873063036554, 9665.613375464902, 9722.0, 9746.171575416027, 9843.753906152404, 9903.554340054714, 640.3404493371835, 682.8429461223947, 701.5930760159096, 708.4969671533166, 719.2304234469173, 4548.863534093983, 4639.768011599406, 4640.396748422683, 4860.003699241647, 4871.269309603517, 76219.61890190624, 76569.61715191389, 136602.0, 139530.30234848964, 144226.0, 88640.0, 104882.0, 105523.39242683299, 105778.0, 110028.0, 66979.0, 92034.53982730044, 93652.0, 94146.52926735322, 95512.52243738598, 10522.842157367493, 10651.89348106509, 10704.678859634223, 10759.89240107589, 10910.290831095954, 53576.46423535715, 54817.45182548283, 55036.44963550474, 55377.723111384184, 56099.15851262153, 75346.0, 77048.61475692586, 82479.58760206161, 83614.58192708998, 83970.58014709887, 13652.931735341259, 13804.102733322303, 13919.582412527641, 14079.718405631626, 14128.364223609757, 15692.76460853111, 16080.758788618414, 16268.837311626732, 16363.34546618122, 16640.73335752665, 10589.788204235718, 11232.887671123184, 13637.79543306871, 15660.295286711897, 16076.115813630362, 15251.237438127828, 15255.923720381326, 15550.066995980278, 15693.450729224422, 16970.490885272968, 14200.360983755962, 14468.710625787637, 14837.827392454516, 14854.851451485347, 15399.769003464733, 12230.755384892074, 12625.179363341353, 12653.430595623035, 12681.619551413472, 12779.23324600527, 12899.419526121159, 12934.676633083871, 13217.206967581977, 13266.867331326563, 13280.0], "value": 7996.120448091547, "unit": "score"}], "name": "dromaeo_css", "value": 7575.03142918012, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 110.0, 110.0, 110.0, 109.0, 111.0, 109.0, 110.0, 108.0, 109.0], "value": 108.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 121.0, 116.0, 175.0, 116.0, 118.0, 146.0, 119.0, 155.0, 119.0], "value": 133.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 160.0, 159.0, 162.0, 163.0, 160.0, 160.0, 159.0, 160.0], "value": 160.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 74.0, 112.0, 75.0, 75.0, 74.0, 75.0, 103.0, 74.0], "value": 81.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 136.0, 83.0, 83.0, 83.0, 123.0, 82.0, 83.0, 131.0], "value": 97.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 116.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 147.0, 145.0, 146.0, 146.0, 147.0, 146.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 50.0, 49.0, 48.0, 50.0, 52.0, 49.0, 50.0, 51.0], "value": 50.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 60.0, 60.0, 61.0, 60.0, 59.0, 60.0, 59.0, 60.0], "value": 60.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 75.0, 74.0, 73.0, 73.0, 73.0, 72.0, 78.0, 80.0], "value": 74.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 121.0, 121.0, 121.0, 133.0, 117.0, 118.0, 127.0, 157.0, 122.0], "value": 125.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 153.0, 150.0, 153.0, 154.0, 154.0, 160.0, 155.0, 154.0, 156.0], "value": 154.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 61.0, 66.0, 61.0, 63.0, 65.0, 60.0, 61.0, 61.0, 60.0], "value": 62.1, "unit": "score"}], "name": "kraken", "value": 1465.1999999999998, "alertThreshold": 2.0}]} 17:16:49 INFO - Return code: 0 17:16:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:16:49 INFO - # TBPL SUCCESS # 17:16:49 INFO - Running post-action listener: _resource_record_post_action 17:16:49 INFO - [mozharness: 2016-08-18 00:16:49.284010Z] Finished run-tests step (success) 17:16:49 INFO - Running post-run listener: _resource_record_post_run 17:16:49 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 7909376; Write bytes: 466067456; Read time: 1820; Write time: 557832 17:16:49 INFO - TinderboxPrint: CPU usage
13.0% 17:16:49 INFO - TinderboxPrint: I/O read bytes / time
7,909,376 / 1,820 17:16:49 INFO - TinderboxPrint: I/O write bytes / time
466,067,456 / 557,832 17:16:49 INFO - TinderboxPrint: CPU idle
8,289.8 (86.9%) 17:16:49 INFO - TinderboxPrint: CPU user
1,190.6 (12.5%) 17:16:49 INFO - TinderboxPrint: Swap in / out
0 / 0 17:16:49 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 79142912; Read time: 0; Write time: 140648 17:16:49 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 5668864; Write bytes: 386924544; Read time: 1652; Write time: 417184 17:16:49 INFO - Running post-run listener: _upload_blobber_files 17:16:49 INFO - Blob upload gear active. 17:16:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:16:49 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 17:16:49 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'] 17:16:49 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 17:16:50 INFO - (blobuploader) - INFO - Open directory for files ... 17:16:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:16:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:16:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:16:56 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:16:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:16:56 INFO - (blobuploader) - INFO - Done attempting. 17:16:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:16:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:16:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:16:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:16:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:16:57 INFO - (blobuploader) - INFO - Done attempting. 17:16:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:16:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:16:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:16:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:16:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:16:58 INFO - (blobuploader) - INFO - Done attempting. 17:16:58 INFO - (blobuploader) - INFO - Iteration through files over. 17:16:58 INFO - Return code: 0 17:16:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:16:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:16:58 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2438d5748917dfa6a2bf4402b230bfea3ca0c2415d4762fe28f8f9739bad6fb89ec12d9715ec514811ed9a55cf39b9b2d0118fa274553c40f7778ea555d99f99", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/17e09c920d52c08a9b687d73c7f76b8b8c10f51d02e29298793f6da75e86557868b2568787ed8ae91da552047b18cb6855c60ae324cb46d5d99eb600834c9445", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bfa0f0da2482e208316d18662b5a9b56c824d0cd93e91b023277f57bf76e1874d495d007648e3172bed5ee9eac1dc522cde45e8051bf9c885ffca4f06dae7bf9"} 17:16:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:16:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:16:58 INFO - Contents: 17:16:58 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2438d5748917dfa6a2bf4402b230bfea3ca0c2415d4762fe28f8f9739bad6fb89ec12d9715ec514811ed9a55cf39b9b2d0118fa274553c40f7778ea555d99f99", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/17e09c920d52c08a9b687d73c7f76b8b8c10f51d02e29298793f6da75e86557868b2568787ed8ae91da552047b18cb6855c60ae324cb46d5d99eb600834c9445", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bfa0f0da2482e208316d18662b5a9b56c824d0cd93e91b023277f57bf76e1874d495d007648e3172bed5ee9eac1dc522cde45e8051bf9c885ffca4f06dae7bf9"} 17:16:58 INFO - Running post-run listener: copy_logs_to_upload_dir 17:16:58 INFO - Copying logs to upload dir... 17:16:58 INFO - mkdir: /builds/slave/test/build/upload/logs 17:16:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1692.061856 ========= master_lag: -0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 12 secs) (at 2016-08-17 17:16:58.373958) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-17 17:16:58.377063) ========= 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=52faae308a556c440401f8e5000001e8-1471477723.638147-1164050914 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2438d5748917dfa6a2bf4402b230bfea3ca0c2415d4762fe28f8f9739bad6fb89ec12d9715ec514811ed9a55cf39b9b2d0118fa274553c40f7778ea555d99f99", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/17e09c920d52c08a9b687d73c7f76b8b8c10f51d02e29298793f6da75e86557868b2568787ed8ae91da552047b18cb6855c60ae324cb46d5d99eb600834c9445", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bfa0f0da2482e208316d18662b5a9b56c824d0cd93e91b023277f57bf76e1874d495d007648e3172bed5ee9eac1dc522cde45e8051bf9c885ffca4f06dae7bf9"} build_url:https://queue.taskcluster.net/v1/task/S5AIVV8nTSKNIcNjBUF9ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011186 build_url: 'https://queue.taskcluster.net/v1/task/S5AIVV8nTSKNIcNjBUF9ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2438d5748917dfa6a2bf4402b230bfea3ca0c2415d4762fe28f8f9739bad6fb89ec12d9715ec514811ed9a55cf39b9b2d0118fa274553c40f7778ea555d99f99", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/17e09c920d52c08a9b687d73c7f76b8b8c10f51d02e29298793f6da75e86557868b2568787ed8ae91da552047b18cb6855c60ae324cb46d5d99eb600834c9445", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bfa0f0da2482e208316d18662b5a9b56c824d0cd93e91b023277f57bf76e1874d495d007648e3172bed5ee9eac1dc522cde45e8051bf9c885ffca4f06dae7bf9"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-17 17:16:58.401773) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-17 17:16:58.402072) ========= 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=52faae308a556c440401f8e5000001e8-1471477723.638147-1164050914 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004302 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-17 17:16:58.486426) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-17 17:16:58.486743) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-17 17:16:58.487047) ========= ========= Total master_lag: 0.14 =========