builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-012 starttime: 1474333850.3 results: success (0) buildid: 20160919151517 builduid: 5120fb171b414a6aa8cccdc1c6ff137a revision: 4376f2389699910ebe72e39977d563c77a094a1d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-19 18:10:50.304000) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-19 18:10:50.304485) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-19 18:10:50.304801) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-19 18:10:50.319889) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 18:10:50.320285) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1474333849.873723-1928949858 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-19 18:10:50-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 222M=0s 2016-09-19 18:10:50 (222 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.094622 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 18:10:50.431111) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 18:10:50.431653) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1474333849.873723-1928949858 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018624 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 18:10:50.484516) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-19 18:10:50.485154) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 4376f2389699910ebe72e39977d563c77a094a1d --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 4376f2389699910ebe72e39977d563c77a094a1d --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=2c5dd2b0a310ecf02fd87158000001b4-1474333849.873723-1928949858 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-19 18:10:50,794 truncating revision to first 12 chars 2016-09-19 18:10:50,794 Setting DEBUG logging. 2016-09-19 18:10:50,794 attempt 1/10 2016-09-19 18:10:50,794 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/4376f2389699?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-19 18:10:52,238 unpacking tar archive at: mozilla-beta-4376f2389699/testing/mozharness/ program finished with exit code 0 elapsedTime=1.791697 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-19 18:10:52.291803) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-19 18:10:52.292187) ========= script_repo_revision: 4376f2389699910ebe72e39977d563c77a094a1d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-19 18:10:52.292669) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-19 18:10:52.292988) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-19 18:10:52.303839) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 53 secs) (at 2016-09-19 18:10:52.304237) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=2c5dd2b0a310ecf02fd87158000001b4-1474333849.873723-1928949858 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:10:52 INFO - MultiFileLogger online at 20160919 18:10:52 in /builds/slave/test 18:10:52 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 18:10:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:10:52 INFO - {'append_to_log': False, 18:10:52 INFO - 'base_work_dir': '/builds/slave/test', 18:10:52 INFO - 'blob_upload_branch': 'Mozilla-Beta', 18:10:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:10:52 INFO - 'branch': 'Mozilla-Beta', 18:10:52 INFO - 'buildbot_json_path': 'buildprops.json', 18:10:52 INFO - 'config_files': ('talos/linux_config.py',), 18:10:52 INFO - 'default_actions': ('clobber', 18:10:52 INFO - 'read-buildbot-config', 18:10:52 INFO - 'download-and-extract', 18:10:52 INFO - 'populate-webroot', 18:10:52 INFO - 'create-virtualenv', 18:10:52 INFO - 'install', 18:10:52 INFO - 'run-tests'), 18:10:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:10:52 INFO - 'download_minidump_stackwalk': True, 18:10:52 INFO - 'download_symbols': 'ondemand', 18:10:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:10:52 INFO - 'tooltool.py': '/tools/tooltool.py', 18:10:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:10:52 INFO - '/tools/misc-python/virtualenv.py')}, 18:10:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:10:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:10:52 INFO - 'installer_path': 'installer.exe', 18:10:52 INFO - 'log_level': 'info', 18:10:52 INFO - 'log_name': 'talos', 18:10:52 INFO - 'log_to_console': True, 18:10:52 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:10:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:10:52 INFO - 'opt_config_files': (), 18:10:52 INFO - 'pip_index': False, 18:10:52 INFO - 'sps_profile': False, 18:10:52 INFO - 'sps_profile_interval': 0, 18:10:52 INFO - 'suite': 'dromaeojs', 18:10:52 INFO - 'system_bits': '32', 18:10:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:10:52 INFO - 'title': 'talos-linux64-ix-012', 18:10:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:10:52 INFO - 'use_talos_json': True, 18:10:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:10:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:10:52 INFO - 'work_dir': 'build'} 18:10:52 INFO - [mozharness: 2016-09-20 01:10:52.705603Z] Running clobber step. 18:10:52 INFO - Running pre-action listener: _resource_record_pre_action 18:10:52 INFO - Running main action method: clobber 18:10:52 INFO - rmtree: /builds/slave/test/build 18:10:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:10:53 INFO - Running post-action listener: _resource_record_post_action 18:10:53 INFO - [mozharness: 2016-09-20 01:10:53.172338Z] Finished clobber step (success) 18:10:53 INFO - [mozharness: 2016-09-20 01:10:53.172438Z] Running read-buildbot-config step. 18:10:53 INFO - Running pre-action listener: _resource_record_pre_action 18:10:53 INFO - Running main action method: read_buildbot_config 18:10:53 INFO - Using buildbot properties: 18:10:53 INFO - { 18:10:53 INFO - "project": "", 18:10:53 INFO - "product": "firefox", 18:10:53 INFO - "script_repo_revision": "production", 18:10:53 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 18:10:53 INFO - "repository": "", 18:10:53 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 18:10:53 INFO - "buildid": "20160919151517", 18:10:53 INFO - "pgo_build": "True", 18:10:53 INFO - "basedir": "/builds/slave/test", 18:10:53 INFO - "buildnumber": 242, 18:10:53 INFO - "slavename": "talos-linux64-ix-012", 18:10:53 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 18:10:53 INFO - "platform": "ubuntu64_hw", 18:10:53 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 18:10:53 INFO - "branch": "mozilla-beta", 18:10:53 INFO - "repo_path": "releases/mozilla-beta", 18:10:53 INFO - "revision": "4376f2389699910ebe72e39977d563c77a094a1d", 18:10:53 INFO - "stage_platform": "linux64-pgo", 18:10:53 INFO - "builduid": "5120fb171b414a6aa8cccdc1c6ff137a", 18:10:53 INFO - "slavebuilddir": "test" 18:10:53 INFO - } 18:10:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 18:10:53 INFO - Running post-action listener: _resource_record_post_action 18:10:53 INFO - [mozharness: 2016-09-20 01:10:53.174888Z] Finished read-buildbot-config step (success) 18:10:53 INFO - [mozharness: 2016-09-20 01:10:53.174976Z] Running download-and-extract step. 18:10:53 INFO - Running pre-action listener: _resource_record_pre_action 18:10:53 INFO - Running main action method: download_and_extract 18:10:53 INFO - mkdir: /builds/slave/test/build/tests 18:10: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')]} 18:10:53 INFO - https://queue.taskcluster.net/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 18:10:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 18:10:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 18:10:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 18:10:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 18:10:53 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 18:10:53 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 18:10:53 INFO - retry: Failed, sleeping 30 seconds before retrying 18:11:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #2 18:11:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 18:11:23 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 18:11:23 INFO - retry: Failed, sleeping 60 seconds before retrying 18:12:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #3 18:12:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 18:12:23 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 18:12:23 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json! 18:12:23 INFO - Caught exception: HTTP Error 404: Not Found 18:12:23 INFO - Caught exception: HTTP Error 404: Not Found 18:12:23 INFO - Caught exception: HTTP Error 404: Not Found 18:12:23 INFO - trying https://queue.taskcluster.net/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 18:12:23 INFO - Downloading https://queue.taskcluster.net/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 18:12:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 18:12:23 INFO - Downloaded 1327 bytes. 18:12:23 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 18:12:23 INFO - Using the following test package requirements: 18:12:23 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 18:12:23 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 18:12:23 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 18:12:23 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 18:12:23 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 18:12:23 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 18:12:23 INFO - u'jsshell-linux-x86_64.zip'], 18:12:23 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 18:12:23 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 18:12:23 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 18:12:23 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 18:12:23 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 18:12:23 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 18:12:23 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 18:12:23 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 18:12:23 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 18:12:23 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 18:12:23 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 18:12:23 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:12: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')]} 18:12:23 INFO - https://queue.taskcluster.net/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:12:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 18:12:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 18:12:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 18:12:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:12:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 18:12:23 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 18:12:23 INFO - retry: Failed, sleeping 30 seconds before retrying 18:12:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #2 18:12:53 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 18:12:53 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 18:12:53 INFO - retry: Failed, sleeping 60 seconds before retrying 18:13:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #3 18:13:54 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 18:13:54 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 18:13:54 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip! 18:13:54 INFO - Caught exception: HTTP Error 404: Not Found 18:13:54 INFO - Caught exception: HTTP Error 404: Not Found 18:13:54 INFO - Caught exception: HTTP Error 404: Not Found 18:13:54 INFO - trying https://queue.taskcluster.net/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 18:13:54 INFO - Downloading https://queue.taskcluster.net/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 18:13:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:13:54 INFO - Downloaded 21771354 bytes. 18:13:54 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:13:54 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 18:13:56 INFO - Return code: 0 18:13:56 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 18:13:56 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')]} 18:13:56 INFO - https://queue.taskcluster.net/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:13:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 18:13:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 18:13:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 18:13:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:13:56 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 18:13:56 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 18:13:56 INFO - retry: Failed, sleeping 30 seconds before retrying 18:14:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #2 18:14:26 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 18:14:26 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 18:14:26 INFO - retry: Failed, sleeping 60 seconds before retrying 18:15:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #3 18:15:26 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 18:15:26 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 18:15:26 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip! 18:15:26 INFO - Caught exception: HTTP Error 404: Not Found 18:15:26 INFO - Caught exception: HTTP Error 404: Not Found 18:15:26 INFO - Caught exception: HTTP Error 404: Not Found 18:15:26 INFO - trying https://queue.taskcluster.net/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 18:15:26 INFO - Downloading https://queue.taskcluster.net/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 18:15:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:15:26 INFO - Downloaded 21771354 bytes. 18:15:26 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:15:26 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 18:15:28 INFO - Return code: 0 18:15:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:15:28 INFO - https://queue.taskcluster.net/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 18:15:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 18:15:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 18:15:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 18:15:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 18:15:28 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 18:15:28 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 18:15:28 INFO - retry: Failed, sleeping 30 seconds before retrying 18:15:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 18:15:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 18:15:58 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 18:15:58 INFO - retry: Failed, sleeping 60 seconds before retrying 18:16:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 18:16:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 18:16:58 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 18:16:58 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip! 18:16:58 INFO - Caught exception: HTTP Error 404: Not Found 18:16:58 INFO - Caught exception: HTTP Error 404: Not Found 18:16:58 INFO - Caught exception: HTTP Error 404: Not Found 18:16:58 INFO - trying https://queue.taskcluster.net/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 18:16:58 INFO - Downloading https://queue.taskcluster.net/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 18:16:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 18:16:59 INFO - Downloaded 14413740 bytes. 18:16:59 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:16:59 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 18:16:59 INFO - Return code: 0 18:16:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:16:59 INFO - https://queue.taskcluster.net/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 18:16:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 18:16:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 18:16:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 18:16:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 18:16:59 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 18:16:59 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 18:16:59 INFO - retry: Failed, sleeping 30 seconds before retrying 18:17:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #2 18:17:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 18:17:29 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 18:17:29 INFO - retry: Failed, sleeping 60 seconds before retrying 18:18:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #3 18:18:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 18:18:29 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 18:18:29 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2! 18:18:29 INFO - Caught exception: HTTP Error 404: Not Found 18:18:29 INFO - Caught exception: HTTP Error 404: Not Found 18:18:29 INFO - Caught exception: HTTP Error 404: Not Found 18:18:29 INFO - trying https://queue.taskcluster.net/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 18:18:29 INFO - Downloading https://queue.taskcluster.net/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 18:18:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 18:18:30 INFO - Downloaded 56770127 bytes. 18:18:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 18:18:30 INFO - mkdir: /builds/slave/test/properties 18:18:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:18:30 INFO - Writing to file /builds/slave/test/properties/build_url 18:18:30 INFO - Contents: 18:18:30 INFO - build_url:https://queue.taskcluster.net/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 18:18:31 INFO - Running post-action listener: _resource_record_post_action 18:18:31 INFO - Running post-action listener: set_extra_try_arguments 18:18:31 INFO - [mozharness: 2016-09-20 01:18:31.377505Z] Finished download-and-extract step (success) 18:18:31 INFO - [mozharness: 2016-09-20 01:18:31.377740Z] Running populate-webroot step. 18:18:31 INFO - Running pre-action listener: _resource_record_pre_action 18:18:31 INFO - Running main action method: populate_webroot 18:18:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:18:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:18:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:18:31 INFO - '--tppagecycles', 18:18:31 INFO - '7'], 18:18:31 INFO - 'tests': ['tsvgm']}}, 18:18:31 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:18:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:18:31 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:18:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:18:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:18:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:18:31 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:18:31 INFO - 'talos_options': ['--disable-e10s'], 18:18:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:18:31 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:18:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:18:31 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:18:31 INFO - 'talos_options': ['--disable-e10s'], 18:18:31 INFO - 'tests': ['damp', 'tps']}, 18:18:31 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:18:31 INFO - 'tests': ['damp', 'tps']}, 18:18:31 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:18:31 INFO - 'tests': ['dromaeo_dom']}, 18:18:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:18:31 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:18:31 INFO - 'tests': ['basic_compositor_video']}, 18:18:31 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:18:31 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:18:31 INFO - 'tests': ['a11yr', 18:18:31 INFO - 'ts_paint', 18:18:31 INFO - 'tpaint', 18:18:31 INFO - 'sessionrestore', 18:18:31 INFO - 'sessionrestore_no_auto_restore', 18:18:31 INFO - 'tabpaint']}, 18:18:31 INFO - 'other-e10s': {'tests': ['a11yr', 18:18:31 INFO - 'ts_paint', 18:18:31 INFO - 'tpaint', 18:18:31 INFO - 'sessionrestore', 18:18:31 INFO - 'sessionrestore_no_auto_restore', 18:18:31 INFO - 'tabpaint']}, 18:18:31 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:18:31 INFO - 'ts_paint', 18:18:31 INFO - 'tpaint', 18:18:31 INFO - 'sessionrestore', 18:18:31 INFO - 'sessionrestore_no_auto_restore', 18:18:31 INFO - 'tabpaint']}, 18:18:31 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:18:31 INFO - 'ts_paint', 18:18:31 INFO - 'tpaint', 18:18:31 INFO - 'sessionrestore', 18:18:31 INFO - 'sessionrestore_no_auto_restore', 18:18:31 INFO - 'tabpaint']}, 18:18:31 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:18:31 INFO - 'tests': ['a11yr', 18:18:31 INFO - 'ts_paint', 18:18:31 INFO - 'tpaint', 18:18:31 INFO - 'sessionrestore', 18:18:31 INFO - 'sessionrestore_no_auto_restore', 18:18:31 INFO - 'tabpaint']}, 18:18:31 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:18:31 INFO - 'tests': ['a11yr', 18:18:31 INFO - 'ts_paint', 18:18:31 INFO - 'tpaint', 18:18:31 INFO - 'sessionrestore', 18:18:31 INFO - 'sessionrestore_no_auto_restore', 18:18:31 INFO - 'tabpaint']}, 18:18:31 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:18:31 INFO - 'tests': ['tsvgx', 18:18:31 INFO - 'tsvgr_opacity', 18:18:31 INFO - 'tart', 18:18:31 INFO - 'tscrollx', 18:18:31 INFO - 'cart']}, 18:18:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:18:31 INFO - 'tsvgr_opacity', 18:18:31 INFO - 'tart', 18:18:31 INFO - 'tscrollx', 18:18:31 INFO - 'cart']}, 18:18:31 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:18:31 INFO - 'talos_options': ['--disable-e10s'], 18:18:31 INFO - 'tests': ['tp5o']}, 18:18:31 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:18:31 INFO - 'tests': ['tp5o']}, 18:18:31 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:18:31 INFO - 'talos_options': ['--disable-e10s', 18:18:31 INFO - '--xperf_path', 18:18:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:18:31 INFO - 'tests': ['tp5n']}, 18:18:31 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:18:31 INFO - 'talos_options': ['--xperf_path', 18:18:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:18:31 INFO - 'tests': ['tp5n']}}, 18:18:31 INFO - 'talos.zip': {'path': '', 18:18:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:18:31 INFO - Running post-action listener: _resource_record_post_action 18:18:31 INFO - [mozharness: 2016-09-20 01:18:31.393453Z] Finished populate-webroot step (success) 18:18:31 INFO - [mozharness: 2016-09-20 01:18:31.393543Z] Running create-virtualenv step. 18:18:31 INFO - Running pre-action listener: _resource_record_pre_action 18:18:31 INFO - Running main action method: create_virtualenv 18:18:31 INFO - Creating virtualenv /builds/slave/test/build/venv 18:18:31 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 18:18:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:18:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:18:31 INFO - Using real prefix '/usr' 18:18:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:18:31 INFO - Installing distribute.............................................................................................................................................................................................done. 18:18:33 INFO - Installing pip.................done. 18:18:33 INFO - Return code: 0 18:18:33 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:18: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')]} 18:18:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18: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')]} 18:18:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18: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 0x137f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14e28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1640180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x163e3b0>, '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 0x163f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x163f740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1474333849.873723-1928949858', '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 18:18: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 18:18: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 18:18:33 INFO - Using env: {'DISPLAY': ':0', 18:18:33 INFO - 'HOME': '/home/cltbld', 18:18:33 INFO - 'LANG': 'en_US.UTF-8', 18:18:33 INFO - 'LANGUAGE': 'en_US:en', 18:18:33 INFO - 'LOGNAME': 'cltbld', 18:18:33 INFO - 'MAIL': '/var/mail/cltbld', 18:18:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:33 INFO - 'MOZ_NO_REMOTE': '1', 18:18:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:18:33 INFO - 'NO_EM_RESTART': '1', 18:18:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:18:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:33 INFO - 'PWD': '/builds/slave/test', 18:18:33 INFO - 'SHELL': '/bin/bash', 18:18:33 INFO - 'SHLVL': '1', 18:18:33 INFO - 'TERM': 'linux', 18:18:33 INFO - 'TMOUT': '86400', 18:18:33 INFO - 'USER': 'cltbld', 18:18:33 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1474333849.873723-1928949858', 18:18:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:33 INFO - '_': '/tools/buildbot/bin/python'} 18:18:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:34 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 18:18:34 INFO - Cleaning up... 18:18:34 INFO - Return code: 0 18:18:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:18:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:18:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:18:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:34 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 0x137f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14e28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1640180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x163e3b0>, '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 0x163f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x163f740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1474333849.873723-1928949858', '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 18:18:34 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 18:18:34 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 18:18:34 INFO - Using env: {'DISPLAY': ':0', 18:18:34 INFO - 'HOME': '/home/cltbld', 18:18:34 INFO - 'LANG': 'en_US.UTF-8', 18:18:34 INFO - 'LANGUAGE': 'en_US:en', 18:18:34 INFO - 'LOGNAME': 'cltbld', 18:18:34 INFO - 'MAIL': '/var/mail/cltbld', 18:18:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:34 INFO - 'MOZ_NO_REMOTE': '1', 18:18:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:18:34 INFO - 'NO_EM_RESTART': '1', 18:18:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:18:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:34 INFO - 'PWD': '/builds/slave/test', 18:18:34 INFO - 'SHELL': '/bin/bash', 18:18:34 INFO - 'SHLVL': '1', 18:18:34 INFO - 'TERM': 'linux', 18:18:34 INFO - 'TMOUT': '86400', 18:18:34 INFO - 'USER': 'cltbld', 18:18:34 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1474333849.873723-1928949858', 18:18:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:34 INFO - '_': '/tools/buildbot/bin/python'} 18:18:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:34 INFO - Downloading/unpacking psutil>=3.1.1 18:18: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 18:18: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 18:18: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 18:18: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 18:18: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 18:18: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 18:18:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:18:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:18:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:18:36 INFO - Installing collected packages: psutil 18:18:36 INFO - Running setup.py install for psutil 18:18:36 INFO - building 'psutil._psutil_linux' extension 18:18: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 18:18:36 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 18:18:36 INFO - building 'psutil._psutil_posix' extension 18:18:36 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 18:18:36 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 18:18:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:18:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:18:36 INFO - Successfully installed psutil 18:18:36 INFO - Cleaning up... 18:18:36 INFO - Return code: 0 18:18:36 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:18:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:18:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:18:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:36 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 0x137f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14e28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1640180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x163e3b0>, '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 0x163f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x163f740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1474333849.873723-1928949858', '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 18:18:36 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 18:18:36 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 18:18:36 INFO - Using env: {'DISPLAY': ':0', 18:18:36 INFO - 'HOME': '/home/cltbld', 18:18:36 INFO - 'LANG': 'en_US.UTF-8', 18:18:36 INFO - 'LANGUAGE': 'en_US:en', 18:18:36 INFO - 'LOGNAME': 'cltbld', 18:18:36 INFO - 'MAIL': '/var/mail/cltbld', 18:18:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:36 INFO - 'MOZ_NO_REMOTE': '1', 18:18:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:18:36 INFO - 'NO_EM_RESTART': '1', 18:18:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:18:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:36 INFO - 'PWD': '/builds/slave/test', 18:18:36 INFO - 'SHELL': '/bin/bash', 18:18:36 INFO - 'SHLVL': '1', 18:18:36 INFO - 'TERM': 'linux', 18:18:36 INFO - 'TMOUT': '86400', 18:18:36 INFO - 'USER': 'cltbld', 18:18:36 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1474333849.873723-1928949858', 18:18:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:36 INFO - '_': '/tools/buildbot/bin/python'} 18:18:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:18: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 18:18: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 18:18: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 18:18: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 18:18: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 18:18: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 18:18:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:18:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:18:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:18:39 INFO - Installing collected packages: mozsystemmonitor 18:18:39 INFO - Running setup.py install for mozsystemmonitor 18:18:39 INFO - Successfully installed mozsystemmonitor 18:18:39 INFO - Cleaning up... 18:18:39 INFO - Return code: 0 18:18:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:18:39 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')]} 18:18:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:39 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')]} 18:18:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:39 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 0x137f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14e28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1640180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x163e3b0>, '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 0x163f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x163f740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1474333849.873723-1928949858', '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 18:18:39 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 18:18:39 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 18:18:39 INFO - Using env: {'DISPLAY': ':0', 18:18:39 INFO - 'HOME': '/home/cltbld', 18:18:39 INFO - 'LANG': 'en_US.UTF-8', 18:18:39 INFO - 'LANGUAGE': 'en_US:en', 18:18:39 INFO - 'LOGNAME': 'cltbld', 18:18:39 INFO - 'MAIL': '/var/mail/cltbld', 18:18:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:39 INFO - 'MOZ_NO_REMOTE': '1', 18:18:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:18:39 INFO - 'NO_EM_RESTART': '1', 18:18:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:18:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:39 INFO - 'PWD': '/builds/slave/test', 18:18:39 INFO - 'SHELL': '/bin/bash', 18:18:39 INFO - 'SHLVL': '1', 18:18:39 INFO - 'TERM': 'linux', 18:18:39 INFO - 'TMOUT': '86400', 18:18:39 INFO - 'USER': 'cltbld', 18:18:39 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1474333849.873723-1928949858', 18:18:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:39 INFO - '_': '/tools/buildbot/bin/python'} 18:18:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:39 INFO - Downloading/unpacking jsonschema==2.5.1 18:18:39 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 18:18:39 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 18:18:39 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 18:18:39 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 18:18:39 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 18:18:39 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 18:18:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:18:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:18:41 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 18:18:41 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 18:18:41 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 18:18:41 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 18:18:41 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 18:18:41 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 18:18:41 INFO - Downloading functools32-3.2.3-2.tar.gz 18:18:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:18:41 INFO - warning: no files found matching '*.txt' 18:18:41 INFO - no previously-included directories found matching 'build' 18:18:41 INFO - no previously-included directories found matching 'dist' 18:18:41 INFO - no previously-included directories found matching '.git*' 18:18:41 INFO - Installing collected packages: jsonschema, functools32 18:18:41 INFO - Running setup.py install for functools32 18:18:42 INFO - warning: no files found matching '*.txt' 18:18:42 INFO - no previously-included directories found matching 'build' 18:18:42 INFO - no previously-included directories found matching 'dist' 18:18:42 INFO - no previously-included directories found matching '.git*' 18:18:42 INFO - Successfully installed jsonschema functools32 18:18:42 INFO - Cleaning up... 18:18:42 INFO - Return code: 0 18:18:42 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:18: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')]} 18:18:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18: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')]} 18:18:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18: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 0x137f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14e28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1640180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x163e3b0>, '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 0x163f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x163f740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1474333849.873723-1928949858', '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 18:18: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 18:18: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 18:18:42 INFO - Using env: {'DISPLAY': ':0', 18:18:42 INFO - 'HOME': '/home/cltbld', 18:18:42 INFO - 'LANG': 'en_US.UTF-8', 18:18:42 INFO - 'LANGUAGE': 'en_US:en', 18:18:42 INFO - 'LOGNAME': 'cltbld', 18:18:42 INFO - 'MAIL': '/var/mail/cltbld', 18:18:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:42 INFO - 'MOZ_NO_REMOTE': '1', 18:18:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:18:42 INFO - 'NO_EM_RESTART': '1', 18:18:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:18:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:42 INFO - 'PWD': '/builds/slave/test', 18:18:42 INFO - 'SHELL': '/bin/bash', 18:18:42 INFO - 'SHLVL': '1', 18:18:42 INFO - 'TERM': 'linux', 18:18:42 INFO - 'TMOUT': '86400', 18:18:42 INFO - 'USER': 'cltbld', 18:18:42 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1474333849.873723-1928949858', 18:18:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:42 INFO - '_': '/tools/buildbot/bin/python'} 18:18:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:42 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:18:42 INFO - Cleaning up... 18:18:42 INFO - Return code: 0 18:18:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:18: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')]} 18:18:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18: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')]} 18:18:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18: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 0x137f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14e28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1640180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x163e3b0>, '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 0x163f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x163f740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1474333849.873723-1928949858', '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 18:18: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 18:18: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 18:18:42 INFO - Using env: {'DISPLAY': ':0', 18:18:42 INFO - 'HOME': '/home/cltbld', 18:18:42 INFO - 'LANG': 'en_US.UTF-8', 18:18:42 INFO - 'LANGUAGE': 'en_US:en', 18:18:42 INFO - 'LOGNAME': 'cltbld', 18:18:42 INFO - 'MAIL': '/var/mail/cltbld', 18:18:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:42 INFO - 'MOZ_NO_REMOTE': '1', 18:18:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:18:42 INFO - 'NO_EM_RESTART': '1', 18:18:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:18:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:42 INFO - 'PWD': '/builds/slave/test', 18:18:42 INFO - 'SHELL': '/bin/bash', 18:18:42 INFO - 'SHLVL': '1', 18:18:42 INFO - 'TERM': 'linux', 18:18:42 INFO - 'TMOUT': '86400', 18:18:42 INFO - 'USER': 'cltbld', 18:18:42 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1474333849.873723-1928949858', 18:18:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:42 INFO - '_': '/tools/buildbot/bin/python'} 18:18:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:42 INFO - Downloading/unpacking blobuploader==1.2.4 18:18: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 18:18: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 18:18: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 18:18: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 18:18: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 18:18: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 18:18:44 INFO - Downloading blobuploader-1.2.4.tar.gz 18:18:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:18:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:18:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:18:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:18:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:18:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:18:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:18:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:18:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:18:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:18:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:18:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:18:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:18:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:18:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:18:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:18:45 INFO - Downloading docopt-0.6.1.tar.gz 18:18:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:18:45 INFO - Installing collected packages: blobuploader, requests, docopt 18:18:45 INFO - Running setup.py install for blobuploader 18:18:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:18:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:18:45 INFO - Running setup.py install for requests 18:18:45 INFO - Running setup.py install for docopt 18:18:45 INFO - Successfully installed blobuploader requests docopt 18:18:45 INFO - Cleaning up... 18:18:45 INFO - Return code: 0 18:18:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:18:45 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')]} 18:18:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:45 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')]} 18:18:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:45 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 0x137f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14e28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1640180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x163e3b0>, '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 0x163f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x163f740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1474333849.873723-1928949858', '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 18:18:45 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 18:18:45 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 18:18:45 INFO - Using env: {'DISPLAY': ':0', 18:18:45 INFO - 'HOME': '/home/cltbld', 18:18:45 INFO - 'LANG': 'en_US.UTF-8', 18:18:45 INFO - 'LANGUAGE': 'en_US:en', 18:18:45 INFO - 'LOGNAME': 'cltbld', 18:18:45 INFO - 'MAIL': '/var/mail/cltbld', 18:18:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:45 INFO - 'MOZ_NO_REMOTE': '1', 18:18:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:18:45 INFO - 'NO_EM_RESTART': '1', 18:18:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:18:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:45 INFO - 'PWD': '/builds/slave/test', 18:18:45 INFO - 'SHELL': '/bin/bash', 18:18:45 INFO - 'SHLVL': '1', 18:18:45 INFO - 'TERM': 'linux', 18:18:45 INFO - 'TMOUT': '86400', 18:18:45 INFO - 'USER': 'cltbld', 18:18:45 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1474333849.873723-1928949858', 18:18:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:45 INFO - '_': '/tools/buildbot/bin/python'} 18:18:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:18:46 INFO - Running setup.py (path:/tmp/pip-mF1FGt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:18:46 INFO - Running setup.py (path:/tmp/pip-WLLtly-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:18:46 INFO - Running setup.py (path:/tmp/pip-ZSZ6Er-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:18:46 INFO - Running setup.py (path:/tmp/pip-oWZd8v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:18:46 INFO - Running setup.py (path:/tmp/pip-phM7n7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:18:46 INFO - Running setup.py (path:/tmp/pip-FzyC2s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:18:46 INFO - Running setup.py (path:/tmp/pip-24dHLV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:18:46 INFO - Running setup.py (path:/tmp/pip-tLvv3k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:18:46 INFO - Running setup.py (path:/tmp/pip-oyN22W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:18:46 INFO - Running setup.py (path:/tmp/pip-06paVe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:18:46 INFO - Running setup.py (path:/tmp/pip-e3kyu1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:18:46 INFO - Running setup.py (path:/tmp/pip-zg7GIC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:18:46 INFO - Running setup.py (path:/tmp/pip-y0uaqn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:18:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:18:47 INFO - Running setup.py (path:/tmp/pip-phMYXu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:18:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:18:47 INFO - Running setup.py (path:/tmp/pip-vIDnqZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:18:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:18:47 INFO - Running setup.py (path:/tmp/pip-48XAgm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:18:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:18:47 INFO - Running setup.py (path:/tmp/pip-8TMI2W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:18:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:18:47 INFO - Running setup.py install for manifestparser 18:18:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:18:47 INFO - Running setup.py install for mozcrash 18:18:47 INFO - Running setup.py install for mozdebug 18:18:47 INFO - Running setup.py install for mozdevice 18:18:47 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:18:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:18:47 INFO - Running setup.py install for mozfile 18:18:47 INFO - Running setup.py install for mozhttpd 18:18:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:18:47 INFO - Running setup.py install for mozinfo 18:18:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:18:48 INFO - Running setup.py install for mozInstall 18:18:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:18:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:18:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:18:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:18:48 INFO - Running setup.py install for mozleak 18:18:48 INFO - Running setup.py install for mozlog 18:18:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:18:48 INFO - Running setup.py install for moznetwork 18:18:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:18:48 INFO - Running setup.py install for mozprocess 18:18:48 INFO - Running setup.py install for mozprofile 18:18:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:18:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:18:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:18:48 INFO - Running setup.py install for mozrunner 18:18:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:18:48 INFO - Running setup.py install for mozscreenshot 18:18:48 INFO - Running setup.py install for moztest 18:18:48 INFO - Running setup.py install for mozversion 18:18:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:18:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:18:49 INFO - Cleaning up... 18:18:49 INFO - Return code: 0 18:18:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:18: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')]} 18:18:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18: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')]} 18:18:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18: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 0x137f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14e28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1640180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x163e3b0>, '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 0x163f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x163f740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1474333849.873723-1928949858', '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 18:18: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 18:18: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 18:18:49 INFO - Using env: {'DISPLAY': ':0', 18:18:49 INFO - 'HOME': '/home/cltbld', 18:18:49 INFO - 'LANG': 'en_US.UTF-8', 18:18:49 INFO - 'LANGUAGE': 'en_US:en', 18:18:49 INFO - 'LOGNAME': 'cltbld', 18:18:49 INFO - 'MAIL': '/var/mail/cltbld', 18:18:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:49 INFO - 'MOZ_NO_REMOTE': '1', 18:18:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:18:49 INFO - 'NO_EM_RESTART': '1', 18:18:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:18:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:49 INFO - 'PWD': '/builds/slave/test', 18:18:49 INFO - 'SHELL': '/bin/bash', 18:18:49 INFO - 'SHLVL': '1', 18:18:49 INFO - 'TERM': 'linux', 18:18:49 INFO - 'TMOUT': '86400', 18:18:49 INFO - 'USER': 'cltbld', 18:18:49 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1474333849.873723-1928949858', 18:18:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:49 INFO - '_': '/tools/buildbot/bin/python'} 18:18:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:18:49 INFO - Running setup.py (path:/tmp/pip-BjwzvS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:18: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)) 18:18:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:18:49 INFO - Running setup.py (path:/tmp/pip-urReRP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:18: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)) 18:18:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:18:49 INFO - Running setup.py (path:/tmp/pip-Qe991d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:18:49 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)) 18:18:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:18:49 INFO - Running setup.py (path:/tmp/pip-NLEwzZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:18:49 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)) 18:18:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:18:49 INFO - Running setup.py (path:/tmp/pip-V_wWrc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:18:49 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)) 18:18:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:18:49 INFO - Running setup.py (path:/tmp/pip-U37uCB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:18:49 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)) 18:18:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:18:49 INFO - Running setup.py (path:/tmp/pip-Vg4C1j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:18:49 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)) 18:18:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:18:49 INFO - Running setup.py (path:/tmp/pip-ia2NjO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:18:49 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)) 18:18:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:18:49 INFO - Running setup.py (path:/tmp/pip-RCgiKb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:18:49 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)) 18:18:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:18:49 INFO - Running setup.py (path:/tmp/pip-vWi5lW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:18: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)) 18:18:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:18:50 INFO - Running setup.py (path:/tmp/pip-qaSYaf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:18: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)) 18:18:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:18:50 INFO - Running setup.py (path:/tmp/pip-jllCWv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:18: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)) 18:18:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:18:50 INFO - Running setup.py (path:/tmp/pip-bjAXRr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:18: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)) 18:18:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:18:50 INFO - Running setup.py (path:/tmp/pip-bi9r5M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:18: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)) 18:18:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:18:50 INFO - Running setup.py (path:/tmp/pip-m8rP9X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:18: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)) 18:18:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:18:50 INFO - Running setup.py (path:/tmp/pip-HmnsHW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:18:50 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)) 18:18:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:18:50 INFO - Running setup.py (path:/tmp/pip-1xfXuq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:18:50 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)) 18:18:50 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)) 18:18:50 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)) 18:18:50 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)) 18:18:50 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)) 18:18:50 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)) 18:18:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:18:50 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 18:18:50 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 18:18:50 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 18:18:50 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 18:18:50 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 18:18:50 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 18:18:52 INFO - Downloading blessings-1.6.tar.gz 18:18:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:18:52 INFO - Installing collected packages: blessings 18:18:52 INFO - Running setup.py install for blessings 18:18:52 INFO - Successfully installed blessings 18:18:52 INFO - Cleaning up... 18:18:52 INFO - Return code: 0 18:18:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:18:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:18:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:18:52 INFO - Reading from file tmpfile_stdout 18:18:52 INFO - Current package versions: 18:18:52 INFO - argparse == 1.2.1 18:18:52 INFO - blessings == 1.6 18:18:52 INFO - blobuploader == 1.2.4 18:18:52 INFO - docopt == 0.6.1 18:18:52 INFO - functools32 == 3.2.3-2 18:18:52 INFO - jsonschema == 2.5.1 18:18:52 INFO - manifestparser == 1.1 18:18:52 INFO - mozInstall == 1.12 18:18:52 INFO - mozcrash == 0.17 18:18:52 INFO - mozdebug == 0.1 18:18:52 INFO - mozdevice == 0.48 18:18:52 INFO - mozfile == 1.2 18:18:52 INFO - mozhttpd == 0.7 18:18:52 INFO - mozinfo == 0.9 18:18:52 INFO - mozleak == 0.1 18:18:52 INFO - mozlog == 3.3 18:18:52 INFO - moznetwork == 0.27 18:18:52 INFO - mozprocess == 0.23 18:18:52 INFO - mozprofile == 0.28 18:18:52 INFO - mozrunner == 6.12 18:18:52 INFO - mozscreenshot == 0.1 18:18:52 INFO - mozsystemmonitor == 0.3 18:18:52 INFO - moztest == 0.7 18:18:52 INFO - mozversion == 1.4 18:18:52 INFO - psutil == 3.1.1 18:18:52 INFO - requests == 1.2.3 18:18:52 INFO - wsgiref == 0.1.2 18:18:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:18: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')]} 18:18:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18: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')]} 18:18:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:52 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 0x137f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14e28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1640180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x163e3b0>, '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 0x163f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x163f740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1474333849.873723-1928949858', '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 18:18:52 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 18:18:52 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 18:18:52 INFO - Using env: {'DISPLAY': ':0', 18:18:52 INFO - 'HOME': '/home/cltbld', 18:18:52 INFO - 'LANG': 'en_US.UTF-8', 18:18:52 INFO - 'LANGUAGE': 'en_US:en', 18:18:52 INFO - 'LOGNAME': 'cltbld', 18:18:52 INFO - 'MAIL': '/var/mail/cltbld', 18:18:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:52 INFO - 'MOZ_NO_REMOTE': '1', 18:18:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:18:52 INFO - 'NO_EM_RESTART': '1', 18:18:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:18:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:52 INFO - 'PWD': '/builds/slave/test', 18:18:52 INFO - 'SHELL': '/bin/bash', 18:18:52 INFO - 'SHLVL': '1', 18:18:52 INFO - 'TERM': 'linux', 18:18:52 INFO - 'TMOUT': '86400', 18:18:52 INFO - 'USER': 'cltbld', 18:18:52 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1474333849.873723-1928949858', 18:18:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:52 INFO - '_': '/tools/buildbot/bin/python'} 18:18:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18: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)) 18:18: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)) 18:18: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)) 18:18: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)) 18:18: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)) 18:18: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)) 18:18: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)) 18:18: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)) 18:18: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)) 18:18: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)) 18:18: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)) 18:18:53 INFO - Cleaning up... 18:18:53 INFO - Return code: 0 18:18:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:18: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')]} 18:18:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18: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')]} 18:18:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18: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 0x137f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14e28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1640180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x163e3b0>, '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 0x163f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x163f740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1474333849.873723-1928949858', '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 18:18: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 18:18: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 18:18:53 INFO - Using env: {'DISPLAY': ':0', 18:18:53 INFO - 'HOME': '/home/cltbld', 18:18:53 INFO - 'LANG': 'en_US.UTF-8', 18:18:53 INFO - 'LANGUAGE': 'en_US:en', 18:18:53 INFO - 'LOGNAME': 'cltbld', 18:18:53 INFO - 'MAIL': '/var/mail/cltbld', 18:18:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:53 INFO - 'MOZ_NO_REMOTE': '1', 18:18:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:18:53 INFO - 'NO_EM_RESTART': '1', 18:18:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:18:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:53 INFO - 'PWD': '/builds/slave/test', 18:18:53 INFO - 'SHELL': '/bin/bash', 18:18:53 INFO - 'SHLVL': '1', 18:18:53 INFO - 'TERM': 'linux', 18:18:53 INFO - 'TMOUT': '86400', 18:18:53 INFO - 'USER': 'cltbld', 18:18:53 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1474333849.873723-1928949858', 18:18:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:53 INFO - '_': '/tools/buildbot/bin/python'} 18:18:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:53 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:18:53 INFO - Cleaning up... 18:18:53 INFO - Return code: 0 18:18:53 INFO - Running post-action listener: _resource_record_post_action 18:18:53 INFO - Running post-action listener: _start_resource_monitoring 18:18:53 INFO - Starting resource monitoring. 18:18:53 INFO - [mozharness: 2016-09-20 01:18:53.412948Z] Finished create-virtualenv step (success) 18:18:53 INFO - [mozharness: 2016-09-20 01:18:53.413525Z] Running install step. 18:18:53 INFO - Running pre-action listener: _resource_record_pre_action 18:18:53 INFO - Running main action method: install 18:18:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:18:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:18:53 INFO - Reading from file tmpfile_stdout 18:18:53 INFO - Detecting whether we're running mozinstall >=1.0... 18:18:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:18:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:18:53 INFO - Reading from file tmpfile_stdout 18:18:53 INFO - Output received: 18:18:53 INFO - Usage: mozinstall [options] installer 18:18:53 INFO - Options: 18:18:53 INFO - -h, --help show this help message and exit 18:18:53 INFO - -d DEST, --destination=DEST 18:18:53 INFO - Directory to install application into. [default: 18:18:53 INFO - "/builds/slave/test"] 18:18:53 INFO - --app=APP Application being installed. [default: firefox] 18:18:53 INFO - mkdir: /builds/slave/test/build/application 18:18:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 18:18:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 18:19:07 INFO - Reading from file tmpfile_stdout 18:19:07 INFO - Output received: 18:19:07 INFO - /builds/slave/test/build/application/firefox/firefox 18:19:07 INFO - Running post-action listener: _resource_record_post_action 18:19:07 INFO - [mozharness: 2016-09-20 01:19:07.201196Z] Finished install step (success) 18:19:07 INFO - [mozharness: 2016-09-20 01:19:07.201533Z] Running run-tests step. 18:19:07 INFO - Running pre-action listener: _resource_record_pre_action 18:19:07 INFO - Running main action method: run_tests 18:19:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:19:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:19:07 INFO - Python 2.7.3 18:19:07 INFO - Return code: 0 18:19:07 INFO - grabbing minidump binary from tooltool 18:19:07 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')]} 18:19:07 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 0x163e3b0>, '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 0x163f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x163f740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:19:07 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 18:19:07 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 18:19:07 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 18:19:07 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:19:07 INFO - Return code: 0 18:19:07 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:19:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:19:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:19:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:19:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:19:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-012', '--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 18:19:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-012 --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 18:19:07 INFO - Using env: {'DISPLAY': ':0', 18:19:07 INFO - 'HOME': '/home/cltbld', 18:19:07 INFO - 'LANG': 'en_US.UTF-8', 18:19:07 INFO - 'LANGUAGE': 'en_US:en', 18:19:07 INFO - 'LOGNAME': 'cltbld', 18:19:07 INFO - 'MAIL': '/var/mail/cltbld', 18:19:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:19:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:19:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:19:07 INFO - 'MOZ_NO_REMOTE': '1', 18:19:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:19:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:19:07 INFO - 'NO_EM_RESTART': '1', 18:19:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:19:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:19:07 INFO - 'PWD': '/builds/slave/test', 18:19:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:19:07 INFO - 'SHELL': '/bin/bash', 18:19:07 INFO - 'SHLVL': '1', 18:19:07 INFO - 'TERM': 'linux', 18:19:07 INFO - 'TMOUT': '86400', 18:19:07 INFO - 'USER': 'cltbld', 18:19:07 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1474333849.873723-1928949858', 18:19:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:19:07 INFO - '_': '/tools/buildbot/bin/python'} 18:19:07 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-012', '--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 18:19:07 INFO - mozversion application_buildid: 20160919151517 18:19:07 INFO - mozversion application_changeset: 4376f2389699910ebe72e39977d563c77a094a1d 18:19:07 INFO - mozversion application_display_name: Firefox 18:19:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:19:07 INFO - mozversion application_name: Firefox 18:19:07 INFO - mozversion application_remotingname: firefox 18:19:07 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 18:19:07 INFO - mozversion application_vendor: Mozilla 18:19:07 INFO - mozversion application_version: 50.0 18:19:07 INFO - mozversion platform_buildid: 20160919151517 18:19:07 INFO - mozversion platform_changeset: 4376f2389699910ebe72e39977d563c77a094a1d 18:19:07 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 18:19:07 INFO - mozversion platform_version: 50.0 18:19:07 INFO - using testdate: 1474334347 18:19:07 INFO - actual date: 1474334347 18:19:07 INFO - starting webserver on 'localhost:42414' 18:19:07 INFO - SUITE-START | Running 2 tests 18:19:07 INFO - TEST-START | dromaeo_css 18:19:07 INFO - operating with platform_type : linux_ 18:19:07 INFO - Initialising browser for dromaeo_css test... 18:19:07 INFO - TEST-INFO | started process 13326 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpp_kTAa/profile http://localhost:42414/getInfo.html) 18:19:10 INFO - PROCESS | 13326 | __metrics Screen width/height:1600/1200 18:19:10 INFO - PROCESS | 13326 | colorDepth:24 18:19:10 INFO - PROCESS | 13326 | Browser inner width/height: 1024/697 18:19:10 INFO - PROCESS | 13326 | __metrics 18:19:11 INFO - PROCESS | 13326 | 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" 18:19:11 INFO - PROCESS | 13326 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:19:12 INFO - TEST-INFO | 13326: exit 0 18:19:12 INFO - Browser initialized. 18:19:12 INFO - Running cycle 1/1 for dromaeo_css test... 18:19:12 INFO - TEST-INFO | started process 13412 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpp_kTAa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:19:24 INFO - PROCESS | 13412 | RSS: Main: 143142912 18:19:24 INFO - PROCESS | 13412 | 18:20:15 INFO - PROCESS | 13412 | 1474334415146 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] 18:22:24 INFO - PROCESS | 13412 | Cycle 1(1): loaded http://localhost:42414/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42414/tests/dromaeo/cssquery-ext.html) 18:22:25 INFO - PROCESS | 13412 | RSS: Main: 218861568 18:22:25 INFO - PROCESS | 13412 | 18:25:27 INFO - PROCESS | 13412 | Cycle 1(1): loaded http://localhost:42414/tests/dromaeo/cssquery-ext.html (next: http://localhost:42414/tests/dromaeo/cssquery-jquery.html) 18:25:28 INFO - PROCESS | 13412 | RSS: Main: 229560320 18:25:28 INFO - PROCESS | 13412 | 18:28:30 INFO - PROCESS | 13412 | Cycle 1(1): loaded http://localhost:42414/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42414/tests/dromaeo/cssquery-mootools.html) 18:28:30 INFO - PROCESS | 13412 | RSS: Main: 174759936 18:28:30 INFO - PROCESS | 13412 | 18:31:32 INFO - PROCESS | 13412 | Cycle 1(1): loaded http://localhost:42414/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42414/tests/dromaeo/cssquery-prototype.html) 18:31:33 INFO - PROCESS | 13412 | RSS: Main: 173965312 18:31:33 INFO - PROCESS | 13412 | 18:34:35 INFO - PROCESS | 13412 | Cycle 1(1): loaded http://localhost:42414/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42414/tests/dromaeo/cssquery-yui.html) 18:34:36 INFO - PROCESS | 13412 | RSS: Main: 173633536 18:34:36 INFO - PROCESS | 13412 | 18:37:38 INFO - PROCESS | 13412 | Cycle 1(1): loaded http://localhost:42414/tests/dromaeo/cssquery-yui.html (next: http://localhost:42414/tests/dromaeo/cssquery-dojo.html) 18:37:39 INFO - PROCESS | 13412 | RSS: Main: 266371072 18:37:39 INFO - PROCESS | 13412 | 18:37:39 INFO - PROCESS | 13412 | __start_tp_report 18:37:39 INFO - PROCESS | 13412 | _x_x_mozilla_page_load 18:37:39 INFO - PROCESS | 13412 | _x_x_mozilla_page_load_details 18:37:39 INFO - PROCESS | 13412 | |i|pagename|runs| 18:37:39 INFO - PROCESS | 13412 | |0;dojo.html;3199.520071989202;3217.420864244435;3221.951670724938;3245.318483118545;3260.8206548639832;5555.888882222358;5658.236138121352;5661.179129026289;5674.574406919487;5683.971580142103;5650.463205995434;5720.599558030939;5721.828345149642;5723.284589426322;5738.7991420300195;5046.949530504685;5117.206832940881;5203.219517072441;5203.791848326063;5265.473452654742;5118;5184.559312458446;5207.166853303473;5252.448492908247;5255.947440525584;5080.085584594772;5115;5159.097157997354;5161.561267292284;5177.534021938025;5217.843464696065;5232.398274198478;5240.135377662681;5243.580513558906;5264.447233040514;5012.04771093491;5044.924326135111;5101.642884998052;5140.228965655144;5141.76862041209;5553.944460555424;5577.609567330289;5581.107022876361;5597.132444471114;5634.0703783875615;6014.8496287592725;6067.696615169224;6074;6083;6090.36051214625;5937.346891841894;5979.342272350038;6002.8499287517725;6051.122587224828;6172.814815555541;5633.126865335879;5664.631798933056;5668.121441176624;5681.147827825859;5690.829275121753;6202.81391558254;6211.534134939898;6218.471429928416;6221.502279817604;6226.968865155645;5722.713864306768;5942.286925568959;6017.819465416044;6018.187544681412;6081.118237855575;6131.417515336036;6131.570790044654;6137.41694539018;6138.907916381258;6157.261128664589;6076.21009268801;6103.739685257231;6117.431594453911;6118.253719382578;6130.612163469723;5899.203607513016;5946.702664866826;5974.761009559656;6007.969960150172;6084.665343406155;4604.079184163181;4630.652701047435;4635.466921304026;4637.049404871993;4669.369635099218;4133.359329303963;4145.564715704868;4149.937750933738;4161.2509748245;4170.249355116048;5529.474699903502;5676.744546495418;5743.052396354638;5744.195812586241;5800.854978625568;6123.601965872204;6125.540584456184;6133.478654314344;6136.938630613637;6137.171481849893;5686.772529098873;5764.913526297109;5769.653820770767;5772.307323121252;5775.9133612995;5098.949010509921;5172.922406163911;5206.947930520722;5217.556507696812;5274.7098909560345;6247.187865577446;6248.625082495065;6251.874962500725;6259.96870015647;6264.718087685974;6010.459058684739;6016.157737916783;6066.1507388966465;6075.665838378754;6076.058210977306;6088.51291896656;6152.230971128609;6155.599886007395;6173.197484327009;6243.406876346739;5933.495652869382;5934.940650593611;5963.671998039976;5998.160257563857;6001.669908154919;6095.75616975316;6103.298120716175;6108.450239478469;6110.26676798787;6110.969445152746;5697.116946873241;5914.34942156371;5952.73212705438;5974.790882319098;6063.696305294383;5962.582619216613;6000.939990600038;6016.368281330398;6016.729247183975;6017.608855424383;5933.554983376178;5982.670953097621;5991.580589358703;5993.880122397615;5996.220491336273;5747.626404283708;5819.534437244925;5933.821985340447;5943.286805583358;5984.790532331347;5764.711764411846;5783.826485205451;5796.536277097906;5800.6809625471005;5836.766529338779 18:37:39 INFO - PROCESS | 13412 | |1;ext.html;598.0879159282097;619.5167769140071;632.260255501064;657.132584987816;664.7872680742158;35970.46044309334;39692;47346;48474.27288590665;48593.27110093351;50157.749211253984;50509.74745126278;50577.24134137991;50614.24078638823;50688.493115068835;9436.528173591327;9591.184749296319;9595.520223988773;9595.568199431042;9596.808063838718;8590.570471476432;8827.911720882805;9316.394434361779;11130.554777808878;11382.829257561143;11649.592264270752;11724.765504689902;11725.355105469196;11729.882701173006;11737.237079589842;11391.829122563167;11446.198766086378;11449.59926402572;11467.655970320862;11474.426278686075;10902.836457453144;10910.345379277269;10933.453327333602;10940.617078402218;10970.232083754141;47481.28778068332;47622.28566571504;47725;47900.76049619765;47932.52067479315;3840.8847734568008;3964.207158568298;4068.3694027425795;4232.3016702244095;4619.099275641252;36809.44785828215;36827;36915.63084369175;37100;37425.43861842075;4704.670499735043;4732.124556956974;4768.856934291976;4791.784369703371;4834.129856625806;403621;405496;408585;409690.99999999703;411416;39172.412413813814;40320.39519407211;67449.32550674528;67958;68048.6597567009;41627.37558936619;42012.789936050125;42015.57984420178;42017.15965680731;42034.78982605068;17742.822571774374;17878.463646090637;17883.910580447275;17891.821081789014;17894.28422863071;15021.248937553079;15089.320980555902;15106.39574417011;15109.848901511064;15126.168060756543;8950.507722075348;9172.541372931326;9447.055294470498;9452.10205030521;9597.952010240044;3558.5551806024246;3585.372559802024;3602.7117830573493;3616.240589476187;3657.250263695936;1049.8295892728283;1111.8665760108677;1113.521185890075;1122.646366394583;1148.471703016605;36223;36458.63541364606;36950.63049369472;37017;37244.25511489809;1192.522990803668;1208.510553225945;1281.9615411537668;1307.9084464087614;1321.491225878044;15613.921930390503;19254.133563989653;19415.708764368545;19431.708524372145;20402.48993775138;656768;662809;664292;666168;666343;68792;69048.6547567269;69327.3067269331;69339;69484.999999999;1385.2796545796332;1448.4351103069812;1469.0744830756555;1509.7735339699134;1524.6569521857493;35209;37377.813110935356;37765.24469510539;38030.42954355632;38150.23699526049;58581.4141858576;59061.40938590731;59227;59349;59358;454315;455384;455707;457539;457887;28792.136235912956;31533.684663153992;32677.34645307128;32745.34509309848;34154;55180.44819551753;55417;55723;56048.15927761005;56181.71909140428;45279.7736011331;46977.530224697315;47096;48214.27678584894;48813.75593122012;47289.763551182026;47341.76329118332;47623.761881190374;48084.27873581829;48785;45745.77127114343;46395.53604463912;46663.30005049859;46727.766361167975;46975.29537056878;38669.41995870008;41307.586924131574;41329.380059298535;41525.37711934263;41853.79073104615;30716;31062.689373106885;31424.214394640316;31589;31804.204894876886 18:37:39 INFO - PROCESS | 13412 | |2;jquery.html;385.4237914318095;399.53454225826914;420.57311828494085;421.111454830308;427.578834847675;19627.411177664675;21035.36893893184;21551;21763.891180544095;22092.447688807788;20337.49156271094;20729.481762955933;20872.165113395484;20920.37238882832;21018.369448916557;5788.276465441831;6021.5182785377065;6030.457258846703;6030.63816171029;6036.969815150918;5816.854578635547;5862.06207006879;5876.471117599415;5903.409659034105;5907.379725128864;5859.091840764689;5860.062390017598;5862.706864656771;5864.120381942721;5865.090910908794;5798.304203495586;5847.502962248214;6004.039353703386;6050.818475445743;6139.109829074804;5751.741171647244;5947.434993675594;5954.880902381971;5957.463828255478;5962.5528085393335;14643.70712585753;14840.035397699223;14855.702885942328;14971.326290316962;20813.271535496337;2354.882255887199;2538.4034751833306;2575.266049175995;2605.2444791010585;3012.8945486908074;10854;11040.944795275973;11048.723781905517;11064.944675276573;11112.611058612909;2032.2379107834563;2046.1917542570602;2096.695979083024;2103.085157956294;2107.557412943284;83260.58369708173;84691;85493.57253213756;90208;92550.537247314;11331.490082946288;11637.661668908015;11710.414479276002;11813.999999999827;12064.879351206551;16773.832261677228;16900.915495422443;16907.915460422864;16933.15334233308;16950.40673576419;9071.546422678839;9136.086391360943;9150;9258.953705231566;9327.580258888367;8900.554972251362;8945.37382383227;9012.369134160677;9110.316726245557;9140.771480712903;4985.875353116201;5049.495050494995;5062.7468626568525;5127;5216.478352164829;1931.5653977855152;1958.2558627721376;1964.1161477335256;1978.3570339639386;2055.147113947725;959.7792507723262;1009.1724785675822;1045.1951996962296;1072.453048945028;1108.534415545473;20795.480112997295;21301;21356.145754169658;21439.78560214409;21574.892125539587;1060.1942523682058;1063.9308444951052;1064.1752641702742;1073.812082885492;1082.7996820588216;10039.247056470647;11720.531178752924;11942.582009629621;11955.462004209832;11999.1600587958;547862;550612.5874681548;554893;555647;562668;78482;84368;88394;95993.52003239939;96302;977.9315447918719;982.474376208725;998.7652901568271;1009.3287963504188;1015.3197357770312;18581.72127418063;19065.90467047656;19157.329493467663;19199.52001199925;19268.073192681182;43148.56851431446;43488.13023739444;44165.337519936584;45158.32262515999;45779.31331029971;59665;60560.697196513735;61175;61638.383616163264;63768.68115659392;7879.9606001971915;8153.755387338152;8193.50838949665;8224.259816616293;8815.382923195571;14317.928410357881;14405.207713575854;14623.634409139857;14737.263136842901;15121.848781512044;12561.434735436744;12799.680007999874;12819.807702884278;12864.22814631125;12875.484980601046;11327.376994265394;11575.768484630093;11824.349660768741;12620;12623.30571818522;8717.738467845975;12624.684382890502;12633.513265189016;13045.152065115737;13342.533011344556;8224.712135075482;8397.538135402612;9326.207272381653;9386.108319709616;9647.372920760128;9289.349745518019;11968;12600.495980161058;12625;12709.300988445355 18:37:39 INFO - PROCESS | 13412 | |3;mootools.html;1561.0945651522118;1598.896071755336;1735.9739603905944;1843.8617103717208;1856.8421684156854;8987.281017518599;9775.120239178472;9785.510724463784;9794;9816.950915245414;8185.017797864265;8415.747527574167;9097.317701172422;9139.95430022849;9575;2505.5490011797824;2547.9617805732837;2553.0808908792915;2553.4637726077453;2553.7190908999996;2467.235157101304;2498.912538061168;2549.247971848309;2550.795936325099;2551.260134560975;2478.529079474903;2486.4529803443215;2490.7011158660894;2545.4018305698146;2549.5028469448466;2417.105670901769;2418.8306818522624;2419.842710223839;2422.2975337152207;2480.7643273888953;2416.1784993102256;2435.5494233566847;2445.5475736988715;2447.0334217983973;2447.49826285611;9469.289803264714;9492.95253523728;9561.808763824685;9607.951960240223;9609.759756006086;5602.38373778884;5713.342965558931;5733.885322293572;5744.023516002248;5756.079027355603;3622.112582417298;3669.5229620149476;3694.11341278094;3702.8148592570265;3706.5552133743854;1256.2901960392348;1267.6260503151593;1275.2783741948388;1288.52968666437;1291.4317700211877;13564.050516463753;13571.728565428637;13572;13584;13588.728225435534;4864.343313652612;4906.975465122651;4949.604031677457;4999.0501804657;5135.948640513622;11126;11127.33236005826;11127.554897804159;11138.49876755548;11144.609938652271;5691.9715401423555;5702.7433765480655;5714.371419143891;5720.8283751487525;5723.8569035774435;4388.4953230378915;5136.178211486144;5191.9480805192225;5209.921851172236;5267.183586544092;4347.5217726050105;4357.912841743211;4392.582704643054;4403.801828917706;4413.602775750198;1362.5912226332061;1394.7001394700187;1397.4969011155977;1410.8377536583218;1411.9011669183265;1007.445904752398;1019.2304809868501;1070.769784496337;1076.7361996310797;1092.857928469294;6206.658633775095;6453.838654033593;6496.967515162489;6563.50773691975;6573.408393244631;470.78343961777966;477.1768698994207;479.12559578768884;480.4426864836773;482.37773272478813;4140.585941405835;4194.874153775391;4230.915381692349;4257.233697934402;4268.658507319407;11619;11944.522219111312;11989.460474278678;11992.580259690869;12030.578929737418;11881.88118118825;11882.643520694393;11906.166568340133;11916.761664766656;11920.046396288277;8459.703910363107;8825.69110081144;8839.51382673959;8900.332475064397;8985;6162.137300777984;6321.936780632135;6413.967930160319;6416.197975253093;6511.218653761579;10898.073663738354;10904.672859814218;11136.832947505956;11155.721106972065;11281.548738050715;10978.341299522055;11150.10799136051;11333.376664283544;11348.546058157912;11352.262102963281;7696.499727517694;7937.920620793718;7940.444168908352;8457.661693532189;8602.91397086021;8633.438826476258;9043.59303831316;9187.402818816756;9225;9482.57328420209;8178.895849060538;8286.58567071656;8376.664933402595;8439.07170211264;8711.869321960303;8224.588770561568;8238.917610824055;8293.585320733819;8343.123971982854;8533.104024077382;6796.864062718817;6856.280090590616;6882.346177113166;6976.825579360557;7064.32888875556;5392.325959255093;5428.70142142186;5460.726963651881;5596.384397716167;5605.691686957093;5566.4990150886615;5802.5357971361345;5823.970880145572;5925.674087925206;5944.9405505946115 18:37:39 INFO - PROCESS | 13412 | |4;prototype.html;334.93971085204663;337.6370401818046;340.7308226501064;341.47583459389836;342.03034397483134;5560.666360018402;5619.634723742954;5629.127485239784;5630.296212973379;5643.8024669136585;5507;5512.669239845602;5561.63849349793;5562.666240025591;5606.243157173781;4138.58614138586;4206.390073439349;4344.348347747831;4389.561043895601;4391.121775644868;4342.630876375496;4378.846740364088;4381.72929850343;4388.122375524892;4389.188000219953;4326.199653064177;4335.263005289093;4349.456317960255;4353;4375.818528997169;4201.432806571104;4297.290946993742;4319.632831209319;4319.784010799479;4324.956750432519;3824.6366595173413;3826.942595861064;3833.7316387852584;3840.385538313856;3845.6731177849915;5379.81170659029;5387.164989426645;5420.91868621971;5429.131338985783;5451.727413629303;4778.354922085508;4899.412070551521;4938.419705694179;4955.868976340208;4984.750762461862;5727.3699893011735;5730.538712628253;5732.885342293131;5736.368999410061;5736.655800651933;4203.5376108628025;4203.852865149735;4206.432131662217;4209.379388935251;4209.957900420987;5679.4036626154075;5924.6149000314845;5977.701114944279;5992.460678538952;6038.547108966801;4230.513490948519;4241.2365774160335;4241.4698162729655;4252.277112890816;4256.829726810893;4300.634446072057;4320.611144996966;4333.1766964276685;4338.392625032498;4350.956490435118;4792.472827988919;4913.1893237616105;4946.233333833262;4971.080350135237;4994.775235114428;5767.798127065533;5776.942230577641;5787.305523337226;5807.622504537276;5816.796412125555;1461.1598330959741;1475.3508456279203;1480.1193289992436;1493.873020793223;1497.6854860479204;954.374884450686;958.9136977672043;961.7547525381123;967.579103090158;979.0014185530719;4964;4964.702117872939;4968.279599458058;4969.304297398367;4985.46032317059;5116.872078198075;5168.147255702767;5245.291885595472;5259;5264.973675131677;4664.883377915579;4899.6570240083565;4956.355673762388;4963.337282010567;4975.4527002029745;2788.5778253090666;2795.4409118176445;2812.2688101093863;2814.2542226310043;2815.6062748939407;5320.467953204726;5333.12011513786;5405.945940540544;5426.8371948841595;5441.29263195782;5316.1494160934835;5623.212750214973;6081.300650425258;6090.725917333731;6098.32918378978;6097.939020609737;6145.4161854623735;6149.292831324366;6161.4146656067605;6162;5740.770369185186;5899.911501327398;5991.681829997394;6056.121862330026;6056.8182954509675;5888.852778680567;5944.5838791284195;5946;5961.254843144607;5979.641221526722;6076.57463977535;6156.630602163885;6205.47253483459;6222.688865556614;6269.962262264873;4219.599138081878;4237.766922819274;4257;4265.424167737316;4268.722533035343;5789.363170051207;6030.155778191144;6042.939570604413;6057.848553786191;6118.143459915705;5720.3707592164;5818.650880947157;5846.473817356458;5848.2689663792025;5852.239208902815;5830.338353569213;5836.182934389274;5873.324567674772;5882.764689412375;5909.793157239476;5699.373068962494;5830.318053695178;5853.882922341614;5875.500582450369;5880.852978675567;5834.212381328636;5906.26171728534;5919.260092488439;5925.792597259076;5927.170196172453;5407.7025763581805;5610.242617246619;5742.913856292075;5829.309500245051;5853.1220316952795 18:37:39 INFO - PROCESS | 13412 | |5;yui.html;838.1283465196196;847.233253905216;872.8516152254116;891.3448615267778;917.3120159880087;16707.081110538915;17130.914345428286;17215.56961075972;17242.137893105326;17290.2219400127;14543.490977815778;14670.04644698094;14707.264636768146;14713.264336783144;15301.387944482236;889.4129874282975;911.5305617606938;913.8492148795441;918.8024574716435;919.6827094652367;925.1118925831177;928.6239073175341;928.7724507495673;931.2177770672633;931.5063016601213;951.3293128344535;952.1811242331589;961.9134277914986;965.0879918477042;965.4738167698621;947.5641205045653;957.6121670723368;959.8752162218901;959.9520023998807;960.2078285414561;697.3619138488277;702.6346299924062;704.1303989572873;704.2499737779249;704.6054209642616;15252.237388130548;15322.310496027705;15329.923350383287;15341.309641066178;15368.462103826312;15531;16207.756883646754;16333.34666613334;16454;16695.666086678197;13607.079716731449;13979;14002.669746374078;14217;14221.288935553284;4116.897077573055;4193.643540299047;4208.936865947013;4292.720973136736;4325.978370108161;88946;90587.09412905852;91892.54053729755;93360.99999999932;93393;12322.753544929052;12454.065945054157;12480.957399517525;12562.81155782664;12585.685357866127;25097.62353564698;25241.62137567938;25293.114740984347;25299.494010119695;25412.618810717853;8695.956520217358;8716.520591367536;8827.602757875911;8934.329925255632;8949.552522373855;4633.582977532038;4707.599854012379;4860.95139048605;4903.436104847988;4994.700317980933;3845.576986531479;3954.9011274718364;3999.280129576704;4003.159336539359;4006.5192176938954;686.8385929306609;705.0165019797354;749.1684230504136;774.2644487736673;776.1501156233915;706.7667669668997;758.2341834746945;799.8240387114826;803.0844836885955;824.8350329934037;10474.947625261977;10678.412687302272;10707;10953.288036277776;11258.718532036764;763.2215140556601;767.8809784483414;775.4688038693513;808.175660825966;814.1288820961514;5281.785189406458;5306.548943339782;5330.840074797762;5358.571314294847;5415.810446634349;100101.49949250353;133876;138388;141669;142933.28533357475;67608;87965;101440;116180;118784;62471.375286246555;79427;96184.51907740417;98203.50898245463;100318.49840750749;10048.196144308291;10135.898641013495;10153.746156345855;10169.338992965433;10218.284720069523;51201;59794.402055978884;60098.09852852298;60556;60860.391396087245;64930;74121;74765.252347478;78845;82239.5888020556;14367.417764060365;14382.712345753236;14549.272536373352;14601.780973285622;14834.554963351116;15019.324130414376;15160;15270;15706.293216805043;15946.043237405793;14467.49363772263;14573.781393279321;14650.34073466718;15755.291011904721;15960.20198990069;14613.19627420502;14891.627709307353;15712.450064247696;15791.68416631684;16041.197940102715;13133.802992954923;13397.66505837362;13553.25457099869;13807.723845523235;14066.788998164831;11629.24409913353;11695.298282103102;11945;12267.325297108388;12386.256824590553;11563.364014979257;11781.175317728019;11807.763844722886;12101;12162.635120946386 18:37:39 INFO - PROCESS | 13412 | __end_tp_report 18:37:39 INFO - PROCESS | 13412 | __start_cc_report 18:37:39 INFO - PROCESS | 13412 | _x_x_mozilla_cycle_collect,1027 18:37:39 INFO - PROCESS | 13412 | __end_cc_report 18:37:39 INFO - PROCESS | 13412 | __startTimestamp1474335459015__endTimestamp 18:37:39 INFO - PROCESS | 13412 | 18:37:39 INFO - PROCESS | 13412 | ------- Summary: start ------- 18:37:39 INFO - PROCESS | 13412 | Number of tests: 6 18:37:39 INFO - PROCESS | 13412 | 18:37:39 INFO - PROCESS | 13412 | [#0] dojo.html Cycles:165 Average:5632.37 Median:5819.53 stddev:644.39 (11.1%) stddev-sans-first:617.46 18:37:39 INFO - PROCESS | 13412 | Values: 3199.5 3217.4 3222.0 3245.3 3260.8 5555.9 5658.2 5661.2 5674.6 5684.0 5650.5 5720.6 5721.8 5723.3 5738.8 5046.9 5117.2 5203.2 5203.8 5265.5 5118.0 5184.6 5207.2 5252.4 5255.9 5080.1 5115.0 5159.1 5161.6 5177.5 5217.8 5232.4 5240.1 5243.6 5264.4 5012.0 5044.9 5101.6 5140.2 5141.8 5553.9 5577.6 5581.1 5597.1 5634.1 6014.8 6067.7 6074.0 6083.0 6090.4 5937.3 5979.3 6002.8 6051.1 6172.8 5633.1 5664.6 5668.1 5681.1 5690.8 6202.8 6211.5 6218.5 6221.5 6227.0 5722.7 5942.3 6017.8 6018.2 6081.1 6131.4 6131.6 6137.4 6138.9 6157.3 6076.2 6103.7 6117.4 6118.3 6130.6 5899.2 5946.7 5974.8 6008.0 6084.7 4604.1 4630.7 4635.5 4637.0 4669.4 4133.4 4145.6 4149.9 4161.3 4170.2 5529.5 5676.7 5743.1 5744.2 5800.9 6123.6 6125.5 6133.5 6136.9 6137.2 5686.8 5764.9 5769.7 5772.3 5775.9 5098.9 5172.9 5206.9 5217.6 5274.7 6247.2 6248.6 6251.9 6260.0 6264.7 6010.5 6016.2 6066.2 6075.7 6076.1 6088.5 6152.2 6155.6 6173.2 6243.4 5933.5 5934.9 5963.7 5998.2 6001.7 6095.8 6103.3 6108.5 6110.3 6111.0 5697.1 5914.3 5952.7 5974.8 6063.7 5962.6 6000.9 6016.4 6016.7 6017.6 5933.6 5982.7 5991.6 5993.9 5996.2 5747.6 5819.5 5933.8 5943.3 5984.8 5764.7 5783.8 5796.5 5800.7 5836.8 18:37:39 INFO - PROCESS | 13412 | 18:37:39 INFO - PROCESS | 13412 | [#1] ext.html Cycles:180 Average:67792.93 Median:41318.48 stddev:138659.60 (335.6%) stddev-sans-first:138956.79 18:37:39 INFO - PROCESS | 13412 | Values: 598.1 619.5 632.3 657.1 664.8 35970.5 39692.0 47346.0 48474.3 48593.3 50157.7 50509.7 50577.2 50614.2 50688.5 9436.5 9591.2 9595.5 9595.6 9596.8 8590.6 8827.9 9316.4 11130.6 11382.8 11649.6 11724.8 11725.4 11729.9 11737.2 11391.8 11446.2 11449.6 11467.7 11474.4 10902.8 10910.3 10933.5 10940.6 10970.2 47481.3 47622.3 47725.0 47900.8 47932.5 3840.9 3964.2 4068.4 4232.3 4619.1 36809.4 36827.0 36915.6 37100.0 37425.4 4704.7 4732.1 4768.9 4791.8 4834.1 403621.0 405496.0 408585.0 409691.0 411416.0 39172.4 40320.4 67449.3 67958.0 68048.7 41627.4 42012.8 42015.6 42017.2 42034.8 17742.8 17878.5 17883.9 17891.8 17894.3 15021.2 15089.3 15106.4 15109.8 15126.2 8950.5 9172.5 9447.1 9452.1 9598.0 3558.6 3585.4 3602.7 3616.2 3657.3 1049.8 1111.9 1113.5 1122.6 1148.5 36223.0 36458.6 36950.6 37017.0 37244.3 1192.5 1208.5 1282.0 1307.9 1321.5 15613.9 19254.1 19415.7 19431.7 20402.5 656768.0 662809.0 664292.0 666168.0 666343.0 68792.0 69048.7 69327.3 69339.0 69485.0 1385.3 1448.4 1469.1 1509.8 1524.7 35209.0 37377.8 37765.2 38030.4 38150.2 58581.4 59061.4 59227.0 59349.0 59358.0 454315.0 455384.0 455707.0 457539.0 457887.0 28792.1 31533.7 32677.3 32745.3 34154.0 55180.4 55417.0 55723.0 56048.2 56181.7 45279.8 46977.5 47096.0 48214.3 48813.8 47289.8 47341.8 47623.8 48084.3 48785.0 45745.8 46395.5 46663.3 46727.8 46975.3 38669.4 41307.6 41329.4 41525.4 41853.8 30716.0 31062.7 31424.2 31589.0 31804.2 18:37:39 INFO - PROCESS | 13412 | 18:37:39 INFO - PROCESS | 13412 | [#2] jquery.html Cycles:180 Average:31366.23 Median:2556.83 stddev:91106.97 (3563.3%) stddev-sans-first:91332.85 18:37:39 INFO - PROCESS | 13412 | Values: 385.4 399.5 420.6 421.1 427.6 19627.4 21035.4 21551.0 21763.9 22092.4 20337.5 20729.5 20872.2 20920.4 21018.4 5788.3 6021.5 6030.5 6030.6 6037.0 5816.9 5862.1 5876.5 5903.4 5907.4 5859.1 5860.1 5862.7 5864.1 5865.1 5798.3 5847.5 6004.0 6050.8 6139.1 5751.7 5947.4 5954.9 5957.5 5962.6 14643.7 14840.0 14855.7 14971.3 20813.3 2354.9 2538.4 2575.3 2605.2 3012.9 10854.0 11040.9 11048.7 11064.9 11112.6 2032.2 2046.2 2096.7 2103.1 2107.6 83260.6 84691.0 85493.6 90208.0 92550.5 11331.5 11637.7 11710.4 11814.0 12064.9 16773.8 16900.9 16907.9 16933.2 16950.4 9071.5 9136.1 9150.0 9259.0 9327.6 8900.6 8945.4 9012.4 9110.3 9140.8 4985.9 5049.5 5062.7 5127.0 5216.5 1931.6 1958.3 1964.1 1978.4 2055.1 959.8 1009.2 1045.2 1072.5 1108.5 20795.5 21301.0 21356.1 21439.8 21574.9 1060.2 1063.9 1064.2 1073.8 1082.8 10039.2 11720.5 11942.6 11955.5 11999.2 547862.0 550612.6 554893.0 555647.0 562668.0 78482.0 84368.0 88394.0 95993.5 96302.0 977.9 982.5 998.8 1009.3 1015.3 18581.7 19065.9 19157.3 19199.5 19268.1 43148.6 43488.1 44165.3 45158.3 45779.3 59665.0 60560.7 61175.0 61638.4 63768.7 7880.0 8153.8 8193.5 8224.3 8815.4 14317.9 14405.2 14623.6 14737.3 15121.8 12561.4 12799.7 12819.8 12864.2 12875.5 11327.4 11575.8 11824.3 12620.0 12623.3 8717.7 12624.7 12633.5 13045.2 13342.5 8224.7 8397.5 9326.2 9386.1 9647.4 9289.3 11968.0 12600.5 12625.0 12709.3 18:37:39 INFO - PROCESS | 13412 | 18:37:39 INFO - PROCESS | 13412 | [#3] mootools.html Cycles:180 Average:6244.27 Median:2442.19 stddev:3608.02 (147.7%) stddev-sans-first:3600.97 18:37:39 INFO - PROCESS | 13412 | Values: 1561.1 1598.9 1736.0 1843.9 1856.8 8987.3 9775.1 9785.5 9794.0 9817.0 8185.0 8415.7 9097.3 9140.0 9575.0 2505.5 2548.0 2553.1 2553.5 2553.7 2467.2 2498.9 2549.2 2550.8 2551.3 2478.5 2486.5 2490.7 2545.4 2549.5 2417.1 2418.8 2419.8 2422.3 2480.8 2416.2 2435.5 2445.5 2447.0 2447.5 9469.3 9493.0 9561.8 9608.0 9609.8 5602.4 5713.3 5733.9 5744.0 5756.1 3622.1 3669.5 3694.1 3702.8 3706.6 1256.3 1267.6 1275.3 1288.5 1291.4 13564.1 13571.7 13572.0 13584.0 13588.7 4864.3 4907.0 4949.6 4999.1 5135.9 11126.0 11127.3 11127.6 11138.5 11144.6 5692.0 5702.7 5714.4 5720.8 5723.9 4388.5 5136.2 5191.9 5209.9 5267.2 4347.5 4357.9 4392.6 4403.8 4413.6 1362.6 1394.7 1397.5 1410.8 1411.9 1007.4 1019.2 1070.8 1076.7 1092.9 6206.7 6453.8 6497.0 6563.5 6573.4 470.8 477.2 479.1 480.4 482.4 4140.6 4194.9 4230.9 4257.2 4268.7 11619.0 11944.5 11989.5 11992.6 12030.6 11881.9 11882.6 11906.2 11916.8 11920.0 8459.7 8825.7 8839.5 8900.3 8985.0 6162.1 6321.9 6414.0 6416.2 6511.2 10898.1 10904.7 11136.8 11155.7 11281.5 10978.3 11150.1 11333.4 11348.5 11352.3 7696.5 7937.9 7940.4 8457.7 8602.9 8633.4 9043.6 9187.4 9225.0 9482.6 8178.9 8286.6 8376.7 8439.1 8711.9 8224.6 8238.9 8293.6 8343.1 8533.1 6796.9 6856.3 6882.3 6976.8 7064.3 5392.3 5428.7 5460.7 5596.4 5605.7 5566.5 5802.5 5824.0 5925.7 5944.9 18:37:39 INFO - PROCESS | 13412 | 18:37:39 INFO - PROCESS | 13412 | [#4] prototype.html Cycles:180 Average:4812.80 Median:5406.82 stddev:1420.85 (26.3%) stddev-sans-first:1384.52 18:37:39 INFO - PROCESS | 13412 | Values: 334.9 337.6 340.7 341.5 342.0 5560.7 5619.6 5629.1 5630.3 5643.8 5507.0 5512.7 5561.6 5562.7 5606.2 4138.6 4206.4 4344.3 4389.6 4391.1 4342.6 4378.8 4381.7 4388.1 4389.2 4326.2 4335.3 4349.5 4353.0 4375.8 4201.4 4297.3 4319.6 4319.8 4325.0 3824.6 3826.9 3833.7 3840.4 3845.7 5379.8 5387.2 5420.9 5429.1 5451.7 4778.4 4899.4 4938.4 4955.9 4984.8 5727.4 5730.5 5732.9 5736.4 5736.7 4203.5 4203.9 4206.4 4209.4 4210.0 5679.4 5924.6 5977.7 5992.5 6038.5 4230.5 4241.2 4241.5 4252.3 4256.8 4300.6 4320.6 4333.2 4338.4 4351.0 4792.5 4913.2 4946.2 4971.1 4994.8 5767.8 5776.9 5787.3 5807.6 5816.8 1461.2 1475.4 1480.1 1493.9 1497.7 954.4 958.9 961.8 967.6 979.0 4964.0 4964.7 4968.3 4969.3 4985.5 5116.9 5168.1 5245.3 5259.0 5265.0 4664.9 4899.7 4956.4 4963.3 4975.5 2788.6 2795.4 2812.3 2814.3 2815.6 5320.5 5333.1 5405.9 5426.8 5441.3 5316.1 5623.2 6081.3 6090.7 6098.3 6097.9 6145.4 6149.3 6161.4 6162.0 5740.8 5899.9 5991.7 6056.1 6056.8 5888.9 5944.6 5946.0 5961.3 5979.6 6076.6 6156.6 6205.5 6222.7 6270.0 4219.6 4237.8 4257.0 4265.4 4268.7 5789.4 6030.2 6042.9 6057.8 6118.1 5720.4 5818.7 5846.5 5848.3 5852.2 5830.3 5836.2 5873.3 5882.8 5909.8 5699.4 5830.3 5853.9 5875.5 5880.9 5834.2 5906.3 5919.3 5925.8 5927.2 5407.7 5610.2 5742.9 5829.3 5853.1 18:37:39 INFO - PROCESS | 13412 | 18:37:39 INFO - PROCESS | 13412 | [#5] yui.html Cycles:180 Average:22531.69 Median:3977.09 stddev:32744.82 (823.3%) stddev-sans-first:32796.16 18:37:39 INFO - PROCESS | 13412 | Values: 838.1 847.2 872.9 891.3 917.3 16707.1 17130.9 17215.6 17242.1 17290.2 14543.5 14670.0 14707.3 14713.3 15301.4 889.4 911.5 913.8 918.8 919.7 925.1 928.6 928.8 931.2 931.5 951.3 952.2 961.9 965.1 965.5 947.6 957.6 959.9 960.0 960.2 697.4 702.6 704.1 704.2 704.6 15252.2 15322.3 15329.9 15341.3 15368.5 15531.0 16207.8 16333.3 16454.0 16695.7 13607.1 13979.0 14002.7 14217.0 14221.3 4116.9 4193.6 4208.9 4292.7 4326.0 88946.0 90587.1 91892.5 93361.0 93393.0 12322.8 12454.1 12481.0 12562.8 12585.7 25097.6 25241.6 25293.1 25299.5 25412.6 8696.0 8716.5 8827.6 8934.3 8949.6 4633.6 4707.6 4861.0 4903.4 4994.7 3845.6 3954.9 3999.3 4003.2 4006.5 686.8 705.0 749.2 774.3 776.2 706.8 758.2 799.8 803.1 824.8 10474.9 10678.4 10707.0 10953.3 11258.7 763.2 767.9 775.5 808.2 814.1 5281.8 5306.5 5330.8 5358.6 5415.8 100101.5 133876.0 138388.0 141669.0 142933.3 67608.0 87965.0 101440.0 116180.0 118784.0 62471.4 79427.0 96184.5 98203.5 100318.5 10048.2 10135.9 10153.7 10169.3 10218.3 51201.0 59794.4 60098.1 60556.0 60860.4 64930.0 74121.0 74765.3 78845.0 82239.6 14367.4 14382.7 14549.3 14601.8 14834.6 15019.3 15160.0 15270.0 15706.3 15946.0 14467.5 14573.8 14650.3 15755.3 15960.2 14613.2 14891.6 15712.5 15791.7 16041.2 13133.8 13397.7 13553.3 13807.7 14066.8 11629.2 11695.3 11945.0 12267.3 12386.3 11563.4 11781.2 11807.8 12101.0 12162.6 18:37:39 INFO - PROCESS | 13412 | -------- Summary: end -------- 18:37:39 INFO - PROCESS | 13412 | 18:37:39 INFO - TEST-INFO | 13412: exit 0 18:37:39 INFO - TEST-OK | dromaeo_css | took 1112291ms 18:37:39 INFO - TEST-START | kraken 18:37:39 INFO - operating with platform_type : linux_ 18:37:39 INFO - Initialising browser for kraken test... 18:37:39 INFO - TEST-INFO | started process 13602 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZj3qHi/profile http://localhost:42414/getInfo.html) 18:37:43 INFO - PROCESS | 13602 | __metrics Screen width/height:1600/1200 18:37:43 INFO - PROCESS | 13602 | colorDepth:24 18:37:43 INFO - PROCESS | 13602 | Browser inner width/height: 1024/697 18:37:43 INFO - PROCESS | 13602 | __metrics 18:37:43 INFO - PROCESS | 13602 | 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" 18:37:43 INFO - PROCESS | 13602 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:37:44 INFO - TEST-INFO | 13602: exit 0 18:37:44 INFO - Browser initialized. 18:37:44 INFO - Running cycle 1/1 for kraken test... 18:37:44 INFO - TEST-INFO | started process 13691 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZj3qHi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:37:56 INFO - PROCESS | 13691 | RSS: Main: 143409152 18:37:56 INFO - PROCESS | 13691 | 18:37:58 INFO - PROCESS | 13691 | Cycle 1(1): loaded http://localhost:42414/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42414/tests/kraken/driver.html?testName=audio-beat-detection) 18:37:59 INFO - PROCESS | 13691 | RSS: Main: 198823936 18:37:59 INFO - PROCESS | 13691 | 18:38:02 INFO - PROCESS | 13691 | Cycle 1(1): loaded http://localhost:42414/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42414/tests/kraken/driver.html?testName=audio-dft) 18:38:02 INFO - PROCESS | 13691 | RSS: Main: 183525376 18:38:02 INFO - PROCESS | 13691 | 18:38:05 INFO - PROCESS | 13691 | Cycle 1(1): loaded http://localhost:42414/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42414/tests/kraken/driver.html?testName=audio-fft) 18:38:06 INFO - PROCESS | 13691 | RSS: Main: 273494016 18:38:06 INFO - PROCESS | 13691 | 18:38:08 INFO - PROCESS | 13691 | Cycle 1(1): loaded http://localhost:42414/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42414/tests/kraken/driver.html?testName=audio-oscillator) 18:38:08 INFO - PROCESS | 13691 | RSS: Main: 179838976 18:38:08 INFO - PROCESS | 13691 | 18:38:11 INFO - PROCESS | 13691 | Cycle 1(1): loaded http://localhost:42414/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42414/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:38:11 INFO - PROCESS | 13691 | RSS: Main: 178999296 18:38:11 INFO - PROCESS | 13691 | 18:38:14 INFO - PROCESS | 13691 | Cycle 1(1): loaded http://localhost:42414/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42414/tests/kraken/driver.html?testName=imaging-darkroom) 18:38:15 INFO - PROCESS | 13691 | RSS: Main: 214310912 18:38:15 INFO - PROCESS | 13691 | 18:38:18 INFO - PROCESS | 13691 | Cycle 1(1): loaded http://localhost:42414/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42414/tests/kraken/driver.html?testName=imaging-desaturate) 18:38:19 INFO - PROCESS | 13691 | RSS: Main: 213417984 18:38:19 INFO - PROCESS | 13691 | 18:38:21 INFO - PROCESS | 13691 | Cycle 1(1): loaded http://localhost:42414/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42414/tests/kraken/driver.html?testName=json-parse-financial) 18:38:22 INFO - PROCESS | 13691 | RSS: Main: 213356544 18:38:22 INFO - PROCESS | 13691 | 18:38:24 INFO - PROCESS | 13691 | Cycle 1(1): loaded http://localhost:42414/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42414/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:38:24 INFO - PROCESS | 13691 | RSS: Main: 175616000 18:38:24 INFO - PROCESS | 13691 | 18:38:26 INFO - PROCESS | 13691 | Cycle 1(1): loaded http://localhost:42414/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42414/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:38:26 INFO - PROCESS | 13691 | RSS: Main: 183443456 18:38:26 INFO - PROCESS | 13691 | 18:38:28 INFO - PROCESS | 13691 | Cycle 1(1): loaded http://localhost:42414/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42414/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:38:29 INFO - PROCESS | 13691 | RSS: Main: 184352768 18:38:29 INFO - PROCESS | 13691 | 18:38:32 INFO - PROCESS | 13691 | Cycle 1(1): loaded http://localhost:42414/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42414/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:38:32 INFO - PROCESS | 13691 | RSS: Main: 185327616 18:38:32 INFO - PROCESS | 13691 | 18:38:35 INFO - PROCESS | 13691 | Cycle 1(1): loaded http://localhost:42414/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42414/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:38:35 INFO - PROCESS | 13691 | RSS: Main: 182726656 18:38:35 INFO - PROCESS | 13691 | 18:38:37 INFO - PROCESS | 13691 | Cycle 1(1): loaded http://localhost:42414/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42414/tests/kraken/driver.html?testName=ai-astar) 18:38:37 INFO - PROCESS | 13691 | RSS: Main: 233295872 18:38:37 INFO - PROCESS | 13691 | 18:38:37 INFO - PROCESS | 13691 | __start_tp_report 18:38:37 INFO - PROCESS | 13691 | _x_x_mozilla_page_load 18:38:37 INFO - PROCESS | 13691 | _x_x_mozilla_page_load_details 18:38:37 INFO - PROCESS | 13691 | |i|pagename|runs| 18:38:37 INFO - PROCESS | 13691 | |0;ai-astar;111;110;120;110;109;110;110;111;110;112 18:38:37 INFO - PROCESS | 13691 | |1;audio-beat-detection;141;116;142;165;115;143;136;115;184;115 18:38:37 INFO - PROCESS | 13691 | |2;audio-dft;161;160;160;160;160;169;159;162;160;160 18:38:37 INFO - PROCESS | 13691 | |3;audio-fft;74;75;75;166;75;76;75;75;160;75 18:38:37 INFO - PROCESS | 13691 | |4;audio-oscillator;83;82;162;82;81;83;147;82;83;145 18:38:37 INFO - PROCESS | 13691 | |5;imaging-gaussian-blur;117;117;117;117;117;118;117;117;117;117 18:38:37 INFO - PROCESS | 13691 | |6;imaging-darkroom;145;145;146;147;148;145;145;147;146;146 18:38:37 INFO - PROCESS | 13691 | |7;imaging-desaturate;94;95;94;95;95;95;95;95;102;95 18:38:37 INFO - PROCESS | 13691 | |8;json-parse-financial;52;51;51;54;51;52;51;51;50;50 18:38:37 INFO - PROCESS | 13691 | |9;json-stringify-tinderbox;59;60;60;59;59;59;59;60;59;59 18:38:37 INFO - PROCESS | 13691 | |10;stanford-crypto-aes;71;72;72;72;76;72;73;79;73;73 18:38:37 INFO - PROCESS | 13691 | |11;stanford-crypto-ccm;120;122;124;130;119;124;121;115;151;121 18:38:37 INFO - PROCESS | 13691 | |12;stanford-crypto-pbkdf2;159;150;154;153;149;154;149;152;149;150 18:38:37 INFO - PROCESS | 13691 | |13;stanford-crypto-sha256-iterative;62;61;61;60;61;61;60;61;60;59 18:38:37 INFO - PROCESS | 13691 | __end_tp_report 18:38:37 INFO - PROCESS | 13691 | __start_cc_report 18:38:37 INFO - PROCESS | 13691 | _x_x_mozilla_cycle_collect,1648 18:38:37 INFO - PROCESS | 13691 | __end_cc_report 18:38:37 INFO - PROCESS | 13691 | __startTimestamp1474335517699__endTimestamp 18:38:37 INFO - PROCESS | 13691 | 18:38:37 INFO - PROCESS | 13691 | ------- Summary: start ------- 18:38:37 INFO - PROCESS | 13691 | Number of tests: 14 18:38:37 INFO - PROCESS | 13691 | 18:38:37 INFO - PROCESS | 13691 | [#0] ai-astar Cycles:10 Average:111.30 Median:110.50 stddev:3.16 (2.9%) stddev-sans-first:3.35 18:38:37 INFO - PROCESS | 13691 | Values: 111.0 110.0 120.0 110.0 109.0 110.0 110.0 111.0 110.0 112.0 18:38:37 INFO - PROCESS | 13691 | 18:38:37 INFO - PROCESS | 13691 | [#1] audio-beat-detection Cycles:10 Average:137.20 Median:141.50 stddev:23.48 (16.6%) stddev-sans-first:24.87 18:38:37 INFO - PROCESS | 13691 | Values: 141.0 116.0 142.0 165.0 115.0 143.0 136.0 115.0 184.0 115.0 18:38:37 INFO - PROCESS | 13691 | 18:38:37 INFO - PROCESS | 13691 | [#2] audio-dft Cycles:10 Average:161.10 Median:160.00 stddev:2.88 (1.8%) stddev-sans-first:3.06 18:38:37 INFO - PROCESS | 13691 | Values: 161.0 160.0 160.0 160.0 160.0 169.0 159.0 162.0 160.0 160.0 18:38:37 INFO - PROCESS | 13691 | 18:38:37 INFO - PROCESS | 13691 | [#3] audio-fft Cycles:10 Average:92.60 Median:75.00 stddev:37.13 (49.5%) stddev-sans-first:38.77 18:38:37 INFO - PROCESS | 13691 | Values: 74.0 75.0 75.0 166.0 75.0 76.0 75.0 75.0 160.0 75.0 18:38:37 INFO - PROCESS | 13691 | 18:38:37 INFO - PROCESS | 13691 | [#4] audio-oscillator Cycles:10 Average:103.00 Median:82.00 stddev:33.65 (41.0%) stddev-sans-first:34.90 18:38:37 INFO - PROCESS | 13691 | Values: 83.0 82.0 162.0 82.0 81.0 83.0 147.0 82.0 83.0 145.0 18:38:37 INFO - PROCESS | 13691 | 18:38:37 INFO - PROCESS | 13691 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 18:38:37 INFO - PROCESS | 13691 | Values: 117.0 117.0 117.0 117.0 117.0 118.0 117.0 117.0 117.0 117.0 18:38:37 INFO - PROCESS | 13691 | 18:38:37 INFO - PROCESS | 13691 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:1.05 (0.7%) stddev-sans-first:1.05 18:38:37 INFO - PROCESS | 13691 | Values: 145.0 145.0 146.0 147.0 148.0 145.0 145.0 147.0 146.0 146.0 18:38:37 INFO - PROCESS | 13691 | 18:38:37 INFO - PROCESS | 13691 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:95.00 stddev:2.32 (2.4%) stddev-sans-first:2.40 18:38:37 INFO - PROCESS | 13691 | Values: 94.0 95.0 94.0 95.0 95.0 95.0 95.0 95.0 102.0 95.0 18:38:37 INFO - PROCESS | 13691 | 18:38:37 INFO - PROCESS | 13691 | [#8] json-parse-financial Cycles:10 Average:51.30 Median:51.00 stddev:1.16 (2.3%) stddev-sans-first:1.20 18:38:37 INFO - PROCESS | 13691 | Values: 52.0 51.0 51.0 54.0 51.0 52.0 51.0 51.0 50.0 50.0 18:38:37 INFO - PROCESS | 13691 | 18:38:37 INFO - PROCESS | 13691 | [#9] json-stringify-tinderbox Cycles:10 Average:59.30 Median:59.00 stddev:0.48 (0.8%) stddev-sans-first:0.50 18:38:37 INFO - PROCESS | 13691 | Values: 59.0 60.0 60.0 59.0 59.0 59.0 59.0 60.0 59.0 59.0 18:38:37 INFO - PROCESS | 13691 | 18:38:37 INFO - PROCESS | 13691 | [#10] stanford-crypto-aes Cycles:10 Average:73.30 Median:73.00 stddev:2.41 (3.3%) stddev-sans-first:2.40 18:38:37 INFO - PROCESS | 13691 | Values: 71.0 72.0 72.0 72.0 76.0 72.0 73.0 79.0 73.0 73.0 18:38:37 INFO - PROCESS | 13691 | 18:38:37 INFO - PROCESS | 13691 | [#11] stanford-crypto-ccm Cycles:10 Average:124.70 Median:123.00 stddev:10.02 (8.1%) stddev-sans-first:10.49 18:38:37 INFO - PROCESS | 13691 | Values: 120.0 122.0 124.0 130.0 119.0 124.0 121.0 115.0 151.0 121.0 18:38:37 INFO - PROCESS | 13691 | 18:38:37 INFO - PROCESS | 13691 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.90 Median:152.50 stddev:3.21 (2.1%) stddev-sans-first:2.15 18:38:37 INFO - PROCESS | 13691 | Values: 159.0 150.0 154.0 153.0 149.0 154.0 149.0 152.0 149.0 150.0 18:38:37 INFO - PROCESS | 13691 | 18:38:37 INFO - PROCESS | 13691 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.60 Median:61.00 stddev:0.84 (1.4%) stddev-sans-first:0.73 18:38:37 INFO - PROCESS | 13691 | Values: 62.0 61.0 61.0 60.0 61.0 61.0 60.0 61.0 60.0 59.0 18:38:37 INFO - PROCESS | 13691 | -------- Summary: end -------- 18:38:37 INFO - PROCESS | 13691 | 18:38:38 INFO - TEST-INFO | 13691: exit 0 18:38:38 INFO - TEST-OK | kraken | took 58918ms 18:38:38 INFO - SUITE-END | took 1171s 18:38:38 INFO - Completed test suite (00:19:31) 18:38:38 INFO - javascript benchmark 18:38:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3199.520071989202, 3217.420864244435, 3221.951670724938, 3245.318483118545, 3260.8206548639832, 5555.888882222358, 5658.236138121352, 5661.179129026289, 5674.574406919487, 5683.971580142103, 5650.463205995434, 5720.599558030939, 5721.828345149642, 5723.284589426322, 5738.7991420300195, 5046.949530504685, 5117.206832940881, 5203.219517072441, 5203.791848326063, 5265.473452654742, 5118.0, 5184.559312458446, 5207.166853303473, 5252.448492908247, 5255.947440525584, 5080.085584594772, 5115.0, 5159.097157997354, 5161.561267292284, 5177.534021938025, 5217.843464696065, 5232.398274198478, 5240.135377662681, 5243.580513558906, 5264.447233040514, 5012.04771093491, 5044.924326135111, 5101.642884998052, 5140.228965655144, 5141.76862041209, 5553.944460555424, 5577.609567330289, 5581.107022876361, 5597.132444471114, 5634.0703783875615, 6014.8496287592725, 6067.696615169224, 6074.0, 6083.0, 6090.36051214625, 5937.346891841894, 5979.342272350038, 6002.8499287517725, 6051.122587224828, 6172.814815555541, 5633.126865335879, 5664.631798933056, 5668.121441176624, 5681.147827825859, 5690.829275121753, 6202.81391558254, 6211.534134939898, 6218.471429928416, 6221.502279817604, 6226.968865155645, 5722.713864306768, 5942.286925568959, 6017.819465416044, 6018.187544681412, 6081.118237855575, 6131.417515336036, 6131.570790044654, 6137.41694539018, 6138.907916381258, 6157.261128664589, 6076.21009268801, 6103.739685257231, 6117.431594453911, 6118.253719382578, 6130.612163469723, 5899.203607513016, 5946.702664866826, 5974.761009559656, 6007.969960150172, 6084.665343406155, 4604.079184163181, 4630.652701047435, 4635.466921304026, 4637.049404871993, 4669.369635099218, 4133.359329303963, 4145.564715704868, 4149.937750933738, 4161.2509748245, 4170.249355116048, 5529.474699903502, 5676.744546495418, 5743.052396354638, 5744.195812586241, 5800.854978625568, 6123.601965872204, 6125.540584456184, 6133.478654314344, 6136.938630613637, 6137.171481849893, 5686.772529098873, 5764.913526297109, 5769.653820770767, 5772.307323121252, 5775.9133612995, 5098.949010509921, 5172.922406163911, 5206.947930520722, 5217.556507696812, 5274.7098909560345, 6247.187865577446, 6248.625082495065, 6251.874962500725, 6259.96870015647, 6264.718087685974, 6010.459058684739, 6016.157737916783, 6066.1507388966465, 6075.665838378754, 6076.058210977306, 6088.51291896656, 6152.230971128609, 6155.599886007395, 6173.197484327009, 6243.406876346739, 5933.495652869382, 5934.940650593611, 5963.671998039976, 5998.160257563857, 6001.669908154919, 6095.75616975316, 6103.298120716175, 6108.450239478469, 6110.26676798787, 6110.969445152746, 5697.116946873241, 5914.34942156371, 5952.73212705438, 5974.790882319098, 6063.696305294383, 5962.582619216613, 6000.939990600038, 6016.368281330398, 6016.729247183975, 6017.608855424383, 5933.554983376178, 5982.670953097621, 5991.580589358703, 5993.880122397615, 5996.220491336273, 5747.626404283708, 5819.534437244925, 5933.821985340447, 5943.286805583358, 5984.790532331347, 5764.711764411846, 5783.826485205451, 5796.536277097906, 5800.6809625471005, 5836.766529338779], "value": 5588.1553582904735, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [598.0879159282097, 619.5167769140071, 632.260255501064, 657.132584987816, 664.7872680742158, 35970.46044309334, 39692.0, 47346.0, 48474.27288590665, 48593.27110093351, 50157.749211253984, 50509.74745126278, 50577.24134137991, 50614.24078638823, 50688.493115068835, 9436.528173591327, 9591.184749296319, 9595.520223988773, 9595.568199431042, 9596.808063838718, 8590.570471476432, 8827.911720882805, 9316.394434361779, 11130.554777808878, 11382.829257561143, 11649.592264270752, 11724.765504689902, 11725.355105469196, 11729.882701173006, 11737.237079589842, 11391.829122563167, 11446.198766086378, 11449.59926402572, 11467.655970320862, 11474.426278686075, 10902.836457453144, 10910.345379277269, 10933.453327333602, 10940.617078402218, 10970.232083754141, 47481.28778068332, 47622.28566571504, 47725.0, 47900.76049619765, 47932.52067479315, 3840.8847734568008, 3964.207158568298, 4068.3694027425795, 4232.3016702244095, 4619.099275641252, 36809.44785828215, 36827.0, 36915.63084369175, 37100.0, 37425.43861842075, 4704.670499735043, 4732.124556956974, 4768.856934291976, 4791.784369703371, 4834.129856625806, 403621.0, 405496.0, 408585.0, 409690.99999999703, 411416.0, 39172.412413813814, 40320.39519407211, 67449.32550674528, 67958.0, 68048.6597567009, 41627.37558936619, 42012.789936050125, 42015.57984420178, 42017.15965680731, 42034.78982605068, 17742.822571774374, 17878.463646090637, 17883.910580447275, 17891.821081789014, 17894.28422863071, 15021.248937553079, 15089.320980555902, 15106.39574417011, 15109.848901511064, 15126.168060756543, 8950.507722075348, 9172.541372931326, 9447.055294470498, 9452.10205030521, 9597.952010240044, 3558.5551806024246, 3585.372559802024, 3602.7117830573493, 3616.240589476187, 3657.250263695936, 1049.8295892728283, 1111.8665760108677, 1113.521185890075, 1122.646366394583, 1148.471703016605, 36223.0, 36458.63541364606, 36950.63049369472, 37017.0, 37244.25511489809, 1192.522990803668, 1208.510553225945, 1281.9615411537668, 1307.9084464087614, 1321.491225878044, 15613.921930390503, 19254.133563989653, 19415.708764368545, 19431.708524372145, 20402.48993775138, 656768.0, 662809.0, 664292.0, 666168.0, 666343.0, 68792.0, 69048.6547567269, 69327.3067269331, 69339.0, 69484.999999999, 1385.2796545796332, 1448.4351103069812, 1469.0744830756555, 1509.7735339699134, 1524.6569521857493, 35209.0, 37377.813110935356, 37765.24469510539, 38030.42954355632, 38150.23699526049, 58581.4141858576, 59061.40938590731, 59227.0, 59349.0, 59358.0, 454315.0, 455384.0, 455707.0, 457539.0, 457887.0, 28792.136235912956, 31533.684663153992, 32677.34645307128, 32745.34509309848, 34154.0, 55180.44819551753, 55417.0, 55723.0, 56048.15927761005, 56181.71909140428, 45279.7736011331, 46977.530224697315, 47096.0, 48214.27678584894, 48813.75593122012, 47289.763551182026, 47341.76329118332, 47623.761881190374, 48084.27873581829, 48785.0, 45745.77127114343, 46395.53604463912, 46663.30005049859, 46727.766361167975, 46975.29537056878, 38669.41995870008, 41307.586924131574, 41329.380059298535, 41525.37711934263, 41853.79073104615, 30716.0, 31062.689373106885, 31424.214394640316, 31589.0, 31804.204894876886], "value": 21509.654161671704, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [385.4237914318095, 399.53454225826914, 420.57311828494085, 421.111454830308, 427.578834847675, 19627.411177664675, 21035.36893893184, 21551.0, 21763.891180544095, 22092.447688807788, 20337.49156271094, 20729.481762955933, 20872.165113395484, 20920.37238882832, 21018.369448916557, 5788.276465441831, 6021.5182785377065, 6030.457258846703, 6030.63816171029, 6036.969815150918, 5816.854578635547, 5862.06207006879, 5876.471117599415, 5903.409659034105, 5907.379725128864, 5859.091840764689, 5860.062390017598, 5862.706864656771, 5864.120381942721, 5865.090910908794, 5798.304203495586, 5847.502962248214, 6004.039353703386, 6050.818475445743, 6139.109829074804, 5751.741171647244, 5947.434993675594, 5954.880902381971, 5957.463828255478, 5962.5528085393335, 14643.70712585753, 14840.035397699223, 14855.702885942328, 14971.326290316962, 20813.271535496337, 2354.882255887199, 2538.4034751833306, 2575.266049175995, 2605.2444791010585, 3012.8945486908074, 10854.0, 11040.944795275973, 11048.723781905517, 11064.944675276573, 11112.611058612909, 2032.2379107834563, 2046.1917542570602, 2096.695979083024, 2103.085157956294, 2107.557412943284, 83260.58369708173, 84691.0, 85493.57253213756, 90208.0, 92550.537247314, 11331.490082946288, 11637.661668908015, 11710.414479276002, 11813.999999999827, 12064.879351206551, 16773.832261677228, 16900.915495422443, 16907.915460422864, 16933.15334233308, 16950.40673576419, 9071.546422678839, 9136.086391360943, 9150.0, 9258.953705231566, 9327.580258888367, 8900.554972251362, 8945.37382383227, 9012.369134160677, 9110.316726245557, 9140.771480712903, 4985.875353116201, 5049.495050494995, 5062.7468626568525, 5127.0, 5216.478352164829, 1931.5653977855152, 1958.2558627721376, 1964.1161477335256, 1978.3570339639386, 2055.147113947725, 959.7792507723262, 1009.1724785675822, 1045.1951996962296, 1072.453048945028, 1108.534415545473, 20795.480112997295, 21301.0, 21356.145754169658, 21439.78560214409, 21574.892125539587, 1060.1942523682058, 1063.9308444951052, 1064.1752641702742, 1073.812082885492, 1082.7996820588216, 10039.247056470647, 11720.531178752924, 11942.582009629621, 11955.462004209832, 11999.1600587958, 547862.0, 550612.5874681548, 554893.0, 555647.0, 562668.0, 78482.0, 84368.0, 88394.0, 95993.52003239939, 96302.0, 977.9315447918719, 982.474376208725, 998.7652901568271, 1009.3287963504188, 1015.3197357770312, 18581.72127418063, 19065.90467047656, 19157.329493467663, 19199.52001199925, 19268.073192681182, 43148.56851431446, 43488.13023739444, 44165.337519936584, 45158.32262515999, 45779.31331029971, 59665.0, 60560.697196513735, 61175.0, 61638.383616163264, 63768.68115659392, 7879.9606001971915, 8153.755387338152, 8193.50838949665, 8224.259816616293, 8815.382923195571, 14317.928410357881, 14405.207713575854, 14623.634409139857, 14737.263136842901, 15121.848781512044, 12561.434735436744, 12799.680007999874, 12819.807702884278, 12864.22814631125, 12875.484980601046, 11327.376994265394, 11575.768484630093, 11824.349660768741, 12620.0, 12623.30571818522, 8717.738467845975, 12624.684382890502, 12633.513265189016, 13045.152065115737, 13342.533011344556, 8224.712135075482, 8397.538135402612, 9326.207272381653, 9386.108319709616, 9647.372920760128, 9289.349745518019, 11968.0, 12600.495980161058, 12625.0, 12709.300988445355], "value": 9673.10512824339, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1561.0945651522118, 1598.896071755336, 1735.9739603905944, 1843.8617103717208, 1856.8421684156854, 8987.281017518599, 9775.120239178472, 9785.510724463784, 9794.0, 9816.950915245414, 8185.017797864265, 8415.747527574167, 9097.317701172422, 9139.95430022849, 9575.0, 2505.5490011797824, 2547.9617805732837, 2553.0808908792915, 2553.4637726077453, 2553.7190908999996, 2467.235157101304, 2498.912538061168, 2549.247971848309, 2550.795936325099, 2551.260134560975, 2478.529079474903, 2486.4529803443215, 2490.7011158660894, 2545.4018305698146, 2549.5028469448466, 2417.105670901769, 2418.8306818522624, 2419.842710223839, 2422.2975337152207, 2480.7643273888953, 2416.1784993102256, 2435.5494233566847, 2445.5475736988715, 2447.0334217983973, 2447.49826285611, 9469.289803264714, 9492.95253523728, 9561.808763824685, 9607.951960240223, 9609.759756006086, 5602.38373778884, 5713.342965558931, 5733.885322293572, 5744.023516002248, 5756.079027355603, 3622.112582417298, 3669.5229620149476, 3694.11341278094, 3702.8148592570265, 3706.5552133743854, 1256.2901960392348, 1267.6260503151593, 1275.2783741948388, 1288.52968666437, 1291.4317700211877, 13564.050516463753, 13571.728565428637, 13572.0, 13584.0, 13588.728225435534, 4864.343313652612, 4906.975465122651, 4949.604031677457, 4999.0501804657, 5135.948640513622, 11126.0, 11127.33236005826, 11127.554897804159, 11138.49876755548, 11144.609938652271, 5691.9715401423555, 5702.7433765480655, 5714.371419143891, 5720.8283751487525, 5723.8569035774435, 4388.4953230378915, 5136.178211486144, 5191.9480805192225, 5209.921851172236, 5267.183586544092, 4347.5217726050105, 4357.912841743211, 4392.582704643054, 4403.801828917706, 4413.602775750198, 1362.5912226332061, 1394.7001394700187, 1397.4969011155977, 1410.8377536583218, 1411.9011669183265, 1007.445904752398, 1019.2304809868501, 1070.769784496337, 1076.7361996310797, 1092.857928469294, 6206.658633775095, 6453.838654033593, 6496.967515162489, 6563.50773691975, 6573.408393244631, 470.78343961777966, 477.1768698994207, 479.12559578768884, 480.4426864836773, 482.37773272478813, 4140.585941405835, 4194.874153775391, 4230.915381692349, 4257.233697934402, 4268.658507319407, 11619.0, 11944.522219111312, 11989.460474278678, 11992.580259690869, 12030.578929737418, 11881.88118118825, 11882.643520694393, 11906.166568340133, 11916.761664766656, 11920.046396288277, 8459.703910363107, 8825.69110081144, 8839.51382673959, 8900.332475064397, 8985.0, 6162.137300777984, 6321.936780632135, 6413.967930160319, 6416.197975253093, 6511.218653761579, 10898.073663738354, 10904.672859814218, 11136.832947505956, 11155.721106972065, 11281.548738050715, 10978.341299522055, 11150.10799136051, 11333.376664283544, 11348.546058157912, 11352.262102963281, 7696.499727517694, 7937.920620793718, 7940.444168908352, 8457.661693532189, 8602.91397086021, 8633.438826476258, 9043.59303831316, 9187.402818816756, 9225.0, 9482.57328420209, 8178.895849060538, 8286.58567071656, 8376.664933402595, 8439.07170211264, 8711.869321960303, 8224.588770561568, 8238.917610824055, 8293.585320733819, 8343.123971982854, 8533.104024077382, 6796.864062718817, 6856.280090590616, 6882.346177113166, 6976.825579360557, 7064.32888875556, 5392.325959255093, 5428.70142142186, 5460.726963651881, 5596.384397716167, 5605.691686957093, 5566.4990150886615, 5802.5357971361345, 5823.970880145572, 5925.674087925206, 5944.9405505946115], "value": 4907.0354519415105, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [334.93971085204663, 337.6370401818046, 340.7308226501064, 341.47583459389836, 342.03034397483134, 5560.666360018402, 5619.634723742954, 5629.127485239784, 5630.296212973379, 5643.8024669136585, 5507.0, 5512.669239845602, 5561.63849349793, 5562.666240025591, 5606.243157173781, 4138.58614138586, 4206.390073439349, 4344.348347747831, 4389.561043895601, 4391.121775644868, 4342.630876375496, 4378.846740364088, 4381.72929850343, 4388.122375524892, 4389.188000219953, 4326.199653064177, 4335.263005289093, 4349.456317960255, 4353.0, 4375.818528997169, 4201.432806571104, 4297.290946993742, 4319.632831209319, 4319.784010799479, 4324.956750432519, 3824.6366595173413, 3826.942595861064, 3833.7316387852584, 3840.385538313856, 3845.6731177849915, 5379.81170659029, 5387.164989426645, 5420.91868621971, 5429.131338985783, 5451.727413629303, 4778.354922085508, 4899.412070551521, 4938.419705694179, 4955.868976340208, 4984.750762461862, 5727.3699893011735, 5730.538712628253, 5732.885342293131, 5736.368999410061, 5736.655800651933, 4203.5376108628025, 4203.852865149735, 4206.432131662217, 4209.379388935251, 4209.957900420987, 5679.4036626154075, 5924.6149000314845, 5977.701114944279, 5992.460678538952, 6038.547108966801, 4230.513490948519, 4241.2365774160335, 4241.4698162729655, 4252.277112890816, 4256.829726810893, 4300.634446072057, 4320.611144996966, 4333.1766964276685, 4338.392625032498, 4350.956490435118, 4792.472827988919, 4913.1893237616105, 4946.233333833262, 4971.080350135237, 4994.775235114428, 5767.798127065533, 5776.942230577641, 5787.305523337226, 5807.622504537276, 5816.796412125555, 1461.1598330959741, 1475.3508456279203, 1480.1193289992436, 1493.873020793223, 1497.6854860479204, 954.374884450686, 958.9136977672043, 961.7547525381123, 967.579103090158, 979.0014185530719, 4964.0, 4964.702117872939, 4968.279599458058, 4969.304297398367, 4985.46032317059, 5116.872078198075, 5168.147255702767, 5245.291885595472, 5259.0, 5264.973675131677, 4664.883377915579, 4899.6570240083565, 4956.355673762388, 4963.337282010567, 4975.4527002029745, 2788.5778253090666, 2795.4409118176445, 2812.2688101093863, 2814.2542226310043, 2815.6062748939407, 5320.467953204726, 5333.12011513786, 5405.945940540544, 5426.8371948841595, 5441.29263195782, 5316.1494160934835, 5623.212750214973, 6081.300650425258, 6090.725917333731, 6098.32918378978, 6097.939020609737, 6145.4161854623735, 6149.292831324366, 6161.4146656067605, 6162.0, 5740.770369185186, 5899.911501327398, 5991.681829997394, 6056.121862330026, 6056.8182954509675, 5888.852778680567, 5944.5838791284195, 5946.0, 5961.254843144607, 5979.641221526722, 6076.57463977535, 6156.630602163885, 6205.47253483459, 6222.688865556614, 6269.962262264873, 4219.599138081878, 4237.766922819274, 4257.0, 4265.424167737316, 4268.722533035343, 5789.363170051207, 6030.155778191144, 6042.939570604413, 6057.848553786191, 6118.143459915705, 5720.3707592164, 5818.650880947157, 5846.473817356458, 5848.2689663792025, 5852.239208902815, 5830.338353569213, 5836.182934389274, 5873.324567674772, 5882.764689412375, 5909.793157239476, 5699.373068962494, 5830.318053695178, 5853.882922341614, 5875.500582450369, 5880.852978675567, 5834.212381328636, 5906.26171728534, 5919.260092488439, 5925.792597259076, 5927.170196172453, 5407.7025763581805, 5610.242617246619, 5742.913856292075, 5829.309500245051, 5853.1220316952795], "value": 4358.658302507962, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [838.1283465196196, 847.233253905216, 872.8516152254116, 891.3448615267778, 917.3120159880087, 16707.081110538915, 17130.914345428286, 17215.56961075972, 17242.137893105326, 17290.2219400127, 14543.490977815778, 14670.04644698094, 14707.264636768146, 14713.264336783144, 15301.387944482236, 889.4129874282975, 911.5305617606938, 913.8492148795441, 918.8024574716435, 919.6827094652367, 925.1118925831177, 928.6239073175341, 928.7724507495673, 931.2177770672633, 931.5063016601213, 951.3293128344535, 952.1811242331589, 961.9134277914986, 965.0879918477042, 965.4738167698621, 947.5641205045653, 957.6121670723368, 959.8752162218901, 959.9520023998807, 960.2078285414561, 697.3619138488277, 702.6346299924062, 704.1303989572873, 704.2499737779249, 704.6054209642616, 15252.237388130548, 15322.310496027705, 15329.923350383287, 15341.309641066178, 15368.462103826312, 15531.0, 16207.756883646754, 16333.34666613334, 16454.0, 16695.666086678197, 13607.079716731449, 13979.0, 14002.669746374078, 14217.0, 14221.288935553284, 4116.897077573055, 4193.643540299047, 4208.936865947013, 4292.720973136736, 4325.978370108161, 88946.0, 90587.09412905852, 91892.54053729755, 93360.99999999932, 93393.0, 12322.753544929052, 12454.065945054157, 12480.957399517525, 12562.81155782664, 12585.685357866127, 25097.62353564698, 25241.62137567938, 25293.114740984347, 25299.494010119695, 25412.618810717853, 8695.956520217358, 8716.520591367536, 8827.602757875911, 8934.329925255632, 8949.552522373855, 4633.582977532038, 4707.599854012379, 4860.95139048605, 4903.436104847988, 4994.700317980933, 3845.576986531479, 3954.9011274718364, 3999.280129576704, 4003.159336539359, 4006.5192176938954, 686.8385929306609, 705.0165019797354, 749.1684230504136, 774.2644487736673, 776.1501156233915, 706.7667669668997, 758.2341834746945, 799.8240387114826, 803.0844836885955, 824.8350329934037, 10474.947625261977, 10678.412687302272, 10707.0, 10953.288036277776, 11258.718532036764, 763.2215140556601, 767.8809784483414, 775.4688038693513, 808.175660825966, 814.1288820961514, 5281.785189406458, 5306.548943339782, 5330.840074797762, 5358.571314294847, 5415.810446634349, 100101.49949250353, 133876.0, 138388.0, 141669.0, 142933.28533357475, 67608.0, 87965.0, 101440.0, 116180.0, 118784.0, 62471.375286246555, 79427.0, 96184.51907740417, 98203.50898245463, 100318.49840750749, 10048.196144308291, 10135.898641013495, 10153.746156345855, 10169.338992965433, 10218.284720069523, 51201.0, 59794.402055978884, 60098.09852852298, 60556.0, 60860.391396087245, 64930.0, 74121.0, 74765.252347478, 78845.0, 82239.5888020556, 14367.417764060365, 14382.712345753236, 14549.272536373352, 14601.780973285622, 14834.554963351116, 15019.324130414376, 15160.0, 15270.0, 15706.293216805043, 15946.043237405793, 14467.49363772263, 14573.781393279321, 14650.34073466718, 15755.291011904721, 15960.20198990069, 14613.19627420502, 14891.627709307353, 15712.450064247696, 15791.68416631684, 16041.197940102715, 13133.802992954923, 13397.66505837362, 13553.25457099869, 13807.723845523235, 14066.788998164831, 11629.24409913353, 11695.298282103102, 11945.0, 12267.325297108388, 12386.256824590553, 11563.364014979257, 11781.175317728019, 11807.763844722886, 12101.0, 12162.635120946386], "value": 8287.069912111085, "unit": "score"}], "name": "dromaeo_css", "value": 7685.654458322266, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 110.0, 120.0, 110.0, 109.0, 110.0, 110.0, 111.0, 110.0, 112.0], "value": 111.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 116.0, 142.0, 165.0, 115.0, 143.0, 136.0, 115.0, 184.0, 115.0], "value": 137.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 160.0, 160.0, 160.0, 169.0, 159.0, 162.0, 160.0, 160.0], "value": 161.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 75.0, 166.0, 75.0, 76.0, 75.0, 75.0, 160.0, 75.0], "value": 92.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 162.0, 82.0, 81.0, 83.0, 147.0, 82.0, 83.0, 145.0], "value": 103.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 118.0, 117.0, 117.0, 117.0, 117.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 146.0, 147.0, 148.0, 145.0, 145.0, 147.0, 146.0, 146.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 102.0, 95.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 51.0, 54.0, 51.0, 52.0, 51.0, 51.0, 50.0, 50.0], "value": 51.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 60.0, 59.0, 59.0, 59.0, 59.0, 60.0, 59.0, 59.0], "value": 59.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 72.0, 72.0, 72.0, 76.0, 72.0, 73.0, 79.0, 73.0, 73.0], "value": 73.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 122.0, 124.0, 130.0, 119.0, 124.0, 121.0, 115.0, 151.0, 121.0], "value": 124.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 150.0, 154.0, 153.0, 149.0, 154.0, 149.0, 152.0, 149.0, 150.0], "value": 151.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 61.0, 61.0, 60.0, 61.0, 61.0, 60.0, 61.0, 60.0, 59.0], "value": 60.6, "unit": "score"}], "name": "kraken", "value": 1484.9, "alertThreshold": 2.0}]} 18:38:38 INFO - Return code: 0 18:38:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:38:38 INFO - # TBPL SUCCESS # 18:38:38 INFO - Running post-action listener: _resource_record_post_action 18:38:38 INFO - [mozharness: 2016-09-20 01:38:38.978045Z] Finished run-tests step (success) 18:38:38 INFO - Running post-run listener: _resource_record_post_run 18:38:39 INFO - Total resource usage - Wall time: 1185s; CPU: 13.0%; Read bytes: 4096; Write bytes: 280539136; Read time: 8; Write time: 176580 18:38:39 INFO - TinderboxPrint: CPU usage
12.9% 18:38:39 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 18:38:39 INFO - TinderboxPrint: I/O write bytes / time
280,539,136 / 176,580 18:38:39 INFO - TinderboxPrint: CPU idle
8,237.7 (87.1%) 18:38:39 INFO - TinderboxPrint: CPU user
1,181.5 (12.5%) 18:38:39 INFO - TinderboxPrint: Swap in / out
0 / 0 18:38:39 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 70119424; Read time: 0; Write time: 66404 18:38:39 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 4096; Write bytes: 210419712; Read time: 8; Write time: 110176 18:38:39 INFO - Running post-run listener: _upload_blobber_files 18:38:39 INFO - Blob upload gear active. 18:38:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:38:39 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 18:38:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:38:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:38:40 INFO - (blobuploader) - INFO - Open directory for files ... 18:38:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:38:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:38:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:38:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:38:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:38:41 INFO - (blobuploader) - INFO - Done attempting. 18:38:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:38:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:38:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:38:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:38:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:38:44 INFO - (blobuploader) - INFO - Done attempting. 18:38:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:38:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:38:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:38:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:38:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:38:45 INFO - (blobuploader) - INFO - Done attempting. 18:38:45 INFO - (blobuploader) - INFO - Iteration through files over. 18:38:45 INFO - Return code: 0 18:38:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:38:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:38:45 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cca2ca7d69e3af3dd9e391084cf896e0907bb07cbcd55902c17c7f767516a157c63fcb447d6ce26f3c55a4d5afac364bed4435dd9cc578ad7c1840a6ed9155c7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/81c1ce667e9086be17633ed6865f00730479b302542595f834a57db333df7ac59b6389d03f8caea9450c9ed84513680a8b915f350e60b5f3eabab317e0cfc5dd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/472a4e00ab6db14b38a66e769bc71490ca485ec5f62a894cf66f265262499e8a34f45582827a3cef091173ee007ab86869d52607fb1497455a0f1ad65d5029ba"} 18:38:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:38:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:38:45 INFO - Contents: 18:38:45 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cca2ca7d69e3af3dd9e391084cf896e0907bb07cbcd55902c17c7f767516a157c63fcb447d6ce26f3c55a4d5afac364bed4435dd9cc578ad7c1840a6ed9155c7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/81c1ce667e9086be17633ed6865f00730479b302542595f834a57db333df7ac59b6389d03f8caea9450c9ed84513680a8b915f350e60b5f3eabab317e0cfc5dd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/472a4e00ab6db14b38a66e769bc71490ca485ec5f62a894cf66f265262499e8a34f45582827a3cef091173ee007ab86869d52607fb1497455a0f1ad65d5029ba"} 18:38:45 INFO - Running post-run listener: copy_logs_to_upload_dir 18:38:45 INFO - Copying logs to upload dir... 18:38:45 INFO - mkdir: /builds/slave/test/build/upload/logs 18:38:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1673.118497 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 53 secs) (at 2016-09-19 18:38:45.432619) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-19 18:38:45.436036) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1474333849.873723-1928949858 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cca2ca7d69e3af3dd9e391084cf896e0907bb07cbcd55902c17c7f767516a157c63fcb447d6ce26f3c55a4d5afac364bed4435dd9cc578ad7c1840a6ed9155c7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/81c1ce667e9086be17633ed6865f00730479b302542595f834a57db333df7ac59b6389d03f8caea9450c9ed84513680a8b915f350e60b5f3eabab317e0cfc5dd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/472a4e00ab6db14b38a66e769bc71490ca485ec5f62a894cf66f265262499e8a34f45582827a3cef091173ee007ab86869d52607fb1497455a0f1ad65d5029ba"} build_url:https://queue.taskcluster.net/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011517 build_url: 'https://queue.taskcluster.net/v1/task/fML5kVeNQturN_z_p8Mgsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cca2ca7d69e3af3dd9e391084cf896e0907bb07cbcd55902c17c7f767516a157c63fcb447d6ce26f3c55a4d5afac364bed4435dd9cc578ad7c1840a6ed9155c7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/81c1ce667e9086be17633ed6865f00730479b302542595f834a57db333df7ac59b6389d03f8caea9450c9ed84513680a8b915f350e60b5f3eabab317e0cfc5dd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/472a4e00ab6db14b38a66e769bc71490ca485ec5f62a894cf66f265262499e8a34f45582827a3cef091173ee007ab86869d52607fb1497455a0f1ad65d5029ba"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-19 18:38:45.460900) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 18:38:45.461187) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1474333849.873723-1928949858 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004496 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 18:38:45.545339) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-19 18:38:45.545636) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-19 18:38:45.545931) ========= ========= Total master_lag: 0.17 =========