builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-050 starttime: 1474360014.57 results: success (0) buildid: 20160920012100 builduid: e0e52b2c0e454af88bcd1bfe5a0c1c82 revision: 4376f2389699910ebe72e39977d563c77a094a1d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-20 01:26:54.568122) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-20 01:26:54.568650) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-20 01:26:54.568974) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-20 01:26:54.583840) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 01:26:54.584166) ========= 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=6dbc10da6d83fee962c624be000001a8-1474360014.376953-62072691 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-20 01:26:55-- 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% 221M=0s 2016-09-20 01:26:55 (221 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.072132 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 01:26:54.670657) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 01:26:54.671021) ========= 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=6dbc10da6d83fee962c624be000001a8-1474360014.376953-62072691 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020135 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 01:26:54.720783) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 01:26:54.721176) ========= 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=6dbc10da6d83fee962c624be000001a8-1474360014.376953-62072691 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-20 01:26:55,304 truncating revision to first 12 chars 2016-09-20 01:26:55,304 Setting DEBUG logging. 2016-09-20 01:26:55,304 attempt 1/10 2016-09-20 01:26:55,304 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-20 01:26:55,615 unpacking tar archive at: mozilla-beta-4376f2389699/testing/mozharness/ program finished with exit code 0 elapsedTime=0.647789 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 01:26:55.379968) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-20 01:26:55.380332) ========= script_repo_revision: 4376f2389699910ebe72e39977d563c77a094a1d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-20 01:26:55.380800) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-20 01:26:55.381196) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-20 01:26:55.388801) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 19 secs) (at 2016-09-20 01:26:55.389108) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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=6dbc10da6d83fee962c624be000001a8-1474360014.376953-62072691 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 01:26:56 INFO - MultiFileLogger online at 20160920 01:26:56 in /builds/slave/test 01:26:56 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 01:26:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:26:56 INFO - {'append_to_log': False, 01:26:56 INFO - 'base_work_dir': '/builds/slave/test', 01:26:56 INFO - 'blob_upload_branch': 'Mozilla-Beta', 01:26:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:26:56 INFO - 'branch': 'Mozilla-Beta', 01:26:56 INFO - 'buildbot_json_path': 'buildprops.json', 01:26:56 INFO - 'config_files': ('talos/linux_config.py',), 01:26:56 INFO - 'default_actions': ('clobber', 01:26:56 INFO - 'read-buildbot-config', 01:26:56 INFO - 'download-and-extract', 01:26:56 INFO - 'populate-webroot', 01:26:56 INFO - 'create-virtualenv', 01:26:56 INFO - 'install', 01:26:56 INFO - 'run-tests'), 01:26:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:26:56 INFO - 'download_minidump_stackwalk': True, 01:26:56 INFO - 'download_symbols': 'ondemand', 01:26:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:26:56 INFO - 'tooltool.py': '/tools/tooltool.py', 01:26:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:26:56 INFO - '/tools/misc-python/virtualenv.py')}, 01:26:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:26:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:26:56 INFO - 'installer_path': 'installer.exe', 01:26:56 INFO - 'log_level': 'info', 01:26:56 INFO - 'log_name': 'talos', 01:26:56 INFO - 'log_to_console': True, 01:26:56 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 01:26:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 01:26:56 INFO - 'opt_config_files': (), 01:26:56 INFO - 'pip_index': False, 01:26:56 INFO - 'sps_profile': False, 01:26:56 INFO - 'sps_profile_interval': 0, 01:26:56 INFO - 'suite': 'dromaeojs-e10s', 01:26:56 INFO - 'system_bits': '32', 01:26:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:26:56 INFO - 'title': 'talos-linux64-ix-050', 01:26:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:26:56 INFO - 'use_talos_json': True, 01:26:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:26:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:26:56 INFO - 'work_dir': 'build'} 01:26:56 INFO - [mozharness: 2016-09-20 08:26:56.060062Z] Running clobber step. 01:26:56 INFO - Running pre-action listener: _resource_record_pre_action 01:26:56 INFO - Running main action method: clobber 01:26:56 INFO - rmtree: /builds/slave/test/build 01:26:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:26:57 INFO - Running post-action listener: _resource_record_post_action 01:26:57 INFO - [mozharness: 2016-09-20 08:26:57.075289Z] Finished clobber step (success) 01:26:57 INFO - [mozharness: 2016-09-20 08:26:57.075391Z] Running read-buildbot-config step. 01:26:57 INFO - Running pre-action listener: _resource_record_pre_action 01:26:57 INFO - Running main action method: read_buildbot_config 01:26:57 INFO - Using buildbot properties: 01:26:57 INFO - { 01:26:57 INFO - "project": "", 01:26:57 INFO - "product": "firefox", 01:26:57 INFO - "script_repo_revision": "production", 01:26:57 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s", 01:26:57 INFO - "repository": "", 01:26:57 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs-e10s", 01:26:57 INFO - "buildid": "20160920012100", 01:26:57 INFO - "stage_platform": "linux64-pgo", 01:26:57 INFO - "basedir": "/builds/slave/test", 01:26:57 INFO - "buildnumber": 240, 01:26:57 INFO - "platform": "ubuntu64_hw", 01:26:57 INFO - "mozci_request": { 01:26:57 INFO - "priority": -1, 01:26:57 INFO - "type": "trigger_all_talos_jobs", 01:26:57 INFO - "times": 6 01:26:57 INFO - }, 01:26:57 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 01:26:57 INFO - "slavebuilddir": "test", 01:26:57 INFO - "branch": "mozilla-beta", 01:26:57 INFO - "revision": "4376f2389699910ebe72e39977d563c77a094a1d", 01:26:57 INFO - "slavename": "talos-linux64-ix-050", 01:26:57 INFO - "builduid": "e0e52b2c0e454af88bcd1bfe5a0c1c82", 01:26:57 INFO - "repo_path": "releases/mozilla-beta" 01:26:57 INFO - } 01:26:57 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474323317/firefox-50.0.en-US.linux-x86_64.tar.bz2. 01:26:57 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474323317/firefox-50.0.en-US.linux-x86_64.test_packages.json. 01:26:57 INFO - Running post-action listener: _resource_record_post_action 01:26:57 INFO - [mozharness: 2016-09-20 08:26:57.078602Z] Finished read-buildbot-config step (success) 01:26:57 INFO - [mozharness: 2016-09-20 08:26:57.078690Z] Running download-and-extract step. 01:26:57 INFO - Running pre-action listener: _resource_record_pre_action 01:26:57 INFO - Running main action method: download_and_extract 01:26:57 INFO - mkdir: /builds/slave/test/build/tests 01:26:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:26:57 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474323317/firefox-50.0.en-US.linux-x86_64.test_packages.json 01:26:57 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474323317/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 01:26:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474323317/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 01:26:57 INFO - Downloaded 1327 bytes. 01:26:57 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 01:26:57 INFO - Using the following test package requirements: 01:26:57 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 01:26:57 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 01:26:57 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 01:26:57 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 01:26:57 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 01:26:57 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 01:26:57 INFO - u'jsshell-linux-x86_64.zip'], 01:26:57 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 01:26:57 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 01:26:57 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 01:26:57 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 01:26:57 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 01:26:57 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 01:26:57 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 01:26:57 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 01:26:57 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 01:26:57 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 01:26:57 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 01:26:57 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 01:26:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:26:57 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474323317/firefox-50.0.en-US.linux-x86_64.common.tests.zip 01:26:57 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474323317/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 01:26:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474323317/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 01:26:57 INFO - Downloaded 21771354 bytes. 01:26:57 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'] 01:26:57 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 01:26:58 INFO - Return code: 0 01:26:58 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 01:26:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:26:58 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474323317/firefox-50.0.en-US.linux-x86_64.common.tests.zip 01:26:58 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474323317/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 01:26:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474323317/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 01:26:58 INFO - Downloaded 21771354 bytes. 01:26:58 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'] 01:26:58 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 01:27:00 INFO - Return code: 0 01:27:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:27:00 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474323317/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 01:27:00 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474323317/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 01:27:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474323317/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 01:27:00 INFO - Downloaded 14413740 bytes. 01:27:00 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'] 01:27:00 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 01:27:00 INFO - Return code: 0 01:27:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:27:00 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474323317/firefox-50.0.en-US.linux-x86_64.tar.bz2 01:27:00 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474323317/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 01:27:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474323317/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 01:27:01 INFO - Downloaded 56770127 bytes. 01:27:01 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474323317/firefox-50.0.en-US.linux-x86_64.tar.bz2 01:27:01 INFO - mkdir: /builds/slave/test/properties 01:27:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:27:01 INFO - Writing to file /builds/slave/test/properties/build_url 01:27:01 INFO - Contents: 01:27:01 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474323317/firefox-50.0.en-US.linux-x86_64.tar.bz2 01:27:02 INFO - Running post-action listener: _resource_record_post_action 01:27:02 INFO - Running post-action listener: set_extra_try_arguments 01:27:02 INFO - [mozharness: 2016-09-20 08:27:02.254401Z] Finished download-and-extract step (success) 01:27:02 INFO - [mozharness: 2016-09-20 08:27:02.254634Z] Running populate-webroot step. 01:27:02 INFO - Running pre-action listener: _resource_record_pre_action 01:27:02 INFO - Running main action method: populate_webroot 01:27:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:27:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:27:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:27:02 INFO - '--tppagecycles', 01:27:02 INFO - '7'], 01:27:02 INFO - 'tests': ['tsvgm']}}, 01:27:02 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:27:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:27:02 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:27:02 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:27:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:27:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:27:02 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:27:02 INFO - 'talos_options': ['--disable-e10s'], 01:27:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:27:02 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:27:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:27:02 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:27:02 INFO - 'talos_options': ['--disable-e10s'], 01:27:02 INFO - 'tests': ['damp', 'tps']}, 01:27:02 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:27:02 INFO - 'tests': ['damp', 'tps']}, 01:27:02 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:27:02 INFO - 'tests': ['dromaeo_dom']}, 01:27:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:27:02 INFO - 'g4': {'talos_options': ['--disable-e10s'], 01:27:02 INFO - 'tests': ['basic_compositor_video']}, 01:27:02 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 01:27:02 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:27:02 INFO - 'tests': ['a11yr', 01:27:02 INFO - 'ts_paint', 01:27:02 INFO - 'tpaint', 01:27:02 INFO - 'sessionrestore', 01:27:02 INFO - 'sessionrestore_no_auto_restore', 01:27:02 INFO - 'tabpaint']}, 01:27:02 INFO - 'other-e10s': {'tests': ['a11yr', 01:27:02 INFO - 'ts_paint', 01:27:02 INFO - 'tpaint', 01:27:02 INFO - 'sessionrestore', 01:27:02 INFO - 'sessionrestore_no_auto_restore', 01:27:02 INFO - 'tabpaint']}, 01:27:02 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:27:02 INFO - 'ts_paint', 01:27:02 INFO - 'tpaint', 01:27:02 INFO - 'sessionrestore', 01:27:02 INFO - 'sessionrestore_no_auto_restore', 01:27:02 INFO - 'tabpaint']}, 01:27:02 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:27:02 INFO - 'ts_paint', 01:27:02 INFO - 'tpaint', 01:27:02 INFO - 'sessionrestore', 01:27:02 INFO - 'sessionrestore_no_auto_restore', 01:27:02 INFO - 'tabpaint']}, 01:27:02 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:27:02 INFO - 'tests': ['a11yr', 01:27:02 INFO - 'ts_paint', 01:27:02 INFO - 'tpaint', 01:27:02 INFO - 'sessionrestore', 01:27:02 INFO - 'sessionrestore_no_auto_restore', 01:27:02 INFO - 'tabpaint']}, 01:27:02 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:27:02 INFO - 'tests': ['a11yr', 01:27:02 INFO - 'ts_paint', 01:27:02 INFO - 'tpaint', 01:27:02 INFO - 'sessionrestore', 01:27:02 INFO - 'sessionrestore_no_auto_restore', 01:27:02 INFO - 'tabpaint']}, 01:27:02 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:27:02 INFO - 'tests': ['tsvgx', 01:27:02 INFO - 'tsvgr_opacity', 01:27:02 INFO - 'tart', 01:27:02 INFO - 'tscrollx', 01:27:02 INFO - 'cart']}, 01:27:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:27:02 INFO - 'tsvgr_opacity', 01:27:02 INFO - 'tart', 01:27:02 INFO - 'tscrollx', 01:27:02 INFO - 'cart']}, 01:27:02 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:27:02 INFO - 'talos_options': ['--disable-e10s'], 01:27:02 INFO - 'tests': ['tp5o']}, 01:27:02 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:27:02 INFO - 'tests': ['tp5o']}, 01:27:02 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:27:02 INFO - 'talos_options': ['--disable-e10s', 01:27:02 INFO - '--xperf_path', 01:27:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:27:02 INFO - 'tests': ['tp5n']}, 01:27:02 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:27:02 INFO - 'talos_options': ['--xperf_path', 01:27:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:27:02 INFO - 'tests': ['tp5n']}}, 01:27:02 INFO - 'talos.zip': {'path': '', 01:27:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:27:02 INFO - Running post-action listener: _resource_record_post_action 01:27:02 INFO - [mozharness: 2016-09-20 08:27:02.272016Z] Finished populate-webroot step (success) 01:27:02 INFO - [mozharness: 2016-09-20 08:27:02.272106Z] Running create-virtualenv step. 01:27:02 INFO - Running pre-action listener: _resource_record_pre_action 01:27:02 INFO - Running main action method: create_virtualenv 01:27:02 INFO - Creating virtualenv /builds/slave/test/build/venv 01:27:02 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 01:27:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:27:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:27:02 INFO - Using real prefix '/usr' 01:27:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:27:02 INFO - Installing distribute.............................................................................................................................................................................................done. 01:27:04 INFO - Installing pip.................done. 01:27:04 INFO - Return code: 0 01:27:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:27:04 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')]} 01:27:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:27:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:04 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')]} 01:27:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:27:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:04 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 0x1c09030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d6c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e38400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ec7570>, '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 0x1ec8470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ec8900>, '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': '6dbc10da6d83fee962c624be000001a8-1474360014.376953-62072691', '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 01:27:04 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 01:27:04 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 01:27:04 INFO - Using env: {'DISPLAY': ':0', 01:27:04 INFO - 'HOME': '/home/cltbld', 01:27:04 INFO - 'LANG': 'en_US.UTF-8', 01:27:04 INFO - 'LANGUAGE': 'en_US:en', 01:27:04 INFO - 'LOGNAME': 'cltbld', 01:27:04 INFO - 'MAIL': '/var/mail/cltbld', 01:27:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:27:04 INFO - 'MOZ_NO_REMOTE': '1', 01:27:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:27:04 INFO - 'NO_EM_RESTART': '1', 01:27:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:27:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:27:04 INFO - 'PWD': '/builds/slave/test', 01:27:04 INFO - 'SHELL': '/bin/bash', 01:27:04 INFO - 'SHLVL': '1', 01:27:04 INFO - 'TERM': 'linux', 01:27:04 INFO - 'TMOUT': '86400', 01:27:04 INFO - 'USER': 'cltbld', 01:27:04 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1474360014.376953-62072691', 01:27:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:27:04 INFO - '_': '/tools/buildbot/bin/python'} 01:27:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:27:05 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 01:27:05 INFO - Cleaning up... 01:27:05 INFO - Return code: 0 01:27:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:27:05 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')]} 01:27:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:27:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:05 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')]} 01:27:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:27:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:05 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 0x1c09030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d6c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e38400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ec7570>, '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 0x1ec8470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ec8900>, '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': '6dbc10da6d83fee962c624be000001a8-1474360014.376953-62072691', '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 01:27:05 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 01:27:05 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 01:27:05 INFO - Using env: {'DISPLAY': ':0', 01:27:05 INFO - 'HOME': '/home/cltbld', 01:27:05 INFO - 'LANG': 'en_US.UTF-8', 01:27:05 INFO - 'LANGUAGE': 'en_US:en', 01:27:05 INFO - 'LOGNAME': 'cltbld', 01:27:05 INFO - 'MAIL': '/var/mail/cltbld', 01:27:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:27:05 INFO - 'MOZ_NO_REMOTE': '1', 01:27:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:27:05 INFO - 'NO_EM_RESTART': '1', 01:27:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:27:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:27:05 INFO - 'PWD': '/builds/slave/test', 01:27:05 INFO - 'SHELL': '/bin/bash', 01:27:05 INFO - 'SHLVL': '1', 01:27:05 INFO - 'TERM': 'linux', 01:27:05 INFO - 'TMOUT': '86400', 01:27:05 INFO - 'USER': 'cltbld', 01:27:05 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1474360014.376953-62072691', 01:27:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:27:05 INFO - '_': '/tools/buildbot/bin/python'} 01:27:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:27:05 INFO - Downloading/unpacking psutil>=3.1.1 01:27:05 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 01:27:05 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 01:27:05 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 01:27:05 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 01:27:05 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 01:27:05 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 01:27:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:27:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:27:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:27:07 INFO - Installing collected packages: psutil 01:27:07 INFO - Running setup.py install for psutil 01:27:07 INFO - building 'psutil._psutil_linux' extension 01:27:07 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 01:27:07 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 01:27:07 INFO - building 'psutil._psutil_posix' extension 01:27:07 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 01:27:07 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 01:27:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:27:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:27:07 INFO - Successfully installed psutil 01:27:07 INFO - Cleaning up... 01:27:07 INFO - Return code: 0 01:27:07 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:27: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')]} 01:27:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:27:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27: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')]} 01:27:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:27:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:07 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 0x1c09030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d6c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e38400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ec7570>, '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 0x1ec8470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ec8900>, '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': '6dbc10da6d83fee962c624be000001a8-1474360014.376953-62072691', '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 01:27:07 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 01:27:07 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 01:27:07 INFO - Using env: {'DISPLAY': ':0', 01:27:07 INFO - 'HOME': '/home/cltbld', 01:27:07 INFO - 'LANG': 'en_US.UTF-8', 01:27:07 INFO - 'LANGUAGE': 'en_US:en', 01:27:07 INFO - 'LOGNAME': 'cltbld', 01:27:07 INFO - 'MAIL': '/var/mail/cltbld', 01:27:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:27:07 INFO - 'MOZ_NO_REMOTE': '1', 01:27:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:27:07 INFO - 'NO_EM_RESTART': '1', 01:27:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:27:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:27:07 INFO - 'PWD': '/builds/slave/test', 01:27:07 INFO - 'SHELL': '/bin/bash', 01:27:07 INFO - 'SHLVL': '1', 01:27:07 INFO - 'TERM': 'linux', 01:27:07 INFO - 'TMOUT': '86400', 01:27:07 INFO - 'USER': 'cltbld', 01:27:07 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1474360014.376953-62072691', 01:27:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:27:07 INFO - '_': '/tools/buildbot/bin/python'} 01:27:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:27:08 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:27:08 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 01:27:08 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 01:27:08 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 01:27:08 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 01:27:08 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 01:27:08 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 01:27:09 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:27:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:27:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:27:09 INFO - Installing collected packages: mozsystemmonitor 01:27:09 INFO - Running setup.py install for mozsystemmonitor 01:27:10 INFO - Successfully installed mozsystemmonitor 01:27:10 INFO - Cleaning up... 01:27:10 INFO - Return code: 0 01:27:10 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 01:27:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:27:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:27:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:27:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:27:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:10 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 0x1c09030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d6c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e38400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ec7570>, '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 0x1ec8470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ec8900>, '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': '6dbc10da6d83fee962c624be000001a8-1474360014.376953-62072691', '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 01:27:10 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 01:27:10 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 01:27:10 INFO - Using env: {'DISPLAY': ':0', 01:27:10 INFO - 'HOME': '/home/cltbld', 01:27:10 INFO - 'LANG': 'en_US.UTF-8', 01:27:10 INFO - 'LANGUAGE': 'en_US:en', 01:27:10 INFO - 'LOGNAME': 'cltbld', 01:27:10 INFO - 'MAIL': '/var/mail/cltbld', 01:27:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:27:10 INFO - 'MOZ_NO_REMOTE': '1', 01:27:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:27:10 INFO - 'NO_EM_RESTART': '1', 01:27:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:27:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:27:10 INFO - 'PWD': '/builds/slave/test', 01:27:10 INFO - 'SHELL': '/bin/bash', 01:27:10 INFO - 'SHLVL': '1', 01:27:10 INFO - 'TERM': 'linux', 01:27:10 INFO - 'TMOUT': '86400', 01:27:10 INFO - 'USER': 'cltbld', 01:27:10 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1474360014.376953-62072691', 01:27:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:27:10 INFO - '_': '/tools/buildbot/bin/python'} 01:27:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:27:10 INFO - Downloading/unpacking jsonschema==2.5.1 01:27:10 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 01:27:10 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 01:27:10 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 01:27:10 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 01:27:10 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 01:27:10 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 01:27:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:27:12 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:27:12 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 01:27:12 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 01:27:12 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 01:27:12 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 01:27:12 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 01:27:12 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 01:27:12 INFO - Downloading functools32-3.2.3-2.tar.gz 01:27:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:27:12 INFO - warning: no files found matching '*.txt' 01:27:12 INFO - no previously-included directories found matching 'build' 01:27:12 INFO - no previously-included directories found matching 'dist' 01:27:12 INFO - no previously-included directories found matching '.git*' 01:27:12 INFO - Installing collected packages: jsonschema, functools32 01:27:12 INFO - Running setup.py install for functools32 01:27:12 INFO - warning: no files found matching '*.txt' 01:27:12 INFO - no previously-included directories found matching 'build' 01:27:12 INFO - no previously-included directories found matching 'dist' 01:27:12 INFO - no previously-included directories found matching '.git*' 01:27:12 INFO - Successfully installed jsonschema functools32 01:27:12 INFO - Cleaning up... 01:27:12 INFO - Return code: 0 01:27:12 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 01:27:12 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')]} 01:27:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:27:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:12 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')]} 01:27:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:27:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:12 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 0x1c09030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d6c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e38400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ec7570>, '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 0x1ec8470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ec8900>, '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': '6dbc10da6d83fee962c624be000001a8-1474360014.376953-62072691', '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 01:27:12 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 01:27:12 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 01:27:12 INFO - Using env: {'DISPLAY': ':0', 01:27:12 INFO - 'HOME': '/home/cltbld', 01:27:12 INFO - 'LANG': 'en_US.UTF-8', 01:27:12 INFO - 'LANGUAGE': 'en_US:en', 01:27:12 INFO - 'LOGNAME': 'cltbld', 01:27:12 INFO - 'MAIL': '/var/mail/cltbld', 01:27:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:27:12 INFO - 'MOZ_NO_REMOTE': '1', 01:27:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:27:12 INFO - 'NO_EM_RESTART': '1', 01:27:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:27:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:27:12 INFO - 'PWD': '/builds/slave/test', 01:27:12 INFO - 'SHELL': '/bin/bash', 01:27:12 INFO - 'SHLVL': '1', 01:27:12 INFO - 'TERM': 'linux', 01:27:12 INFO - 'TMOUT': '86400', 01:27:12 INFO - 'USER': 'cltbld', 01:27:12 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1474360014.376953-62072691', 01:27:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:27:12 INFO - '_': '/tools/buildbot/bin/python'} 01:27:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:27:12 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 01:27:12 INFO - Cleaning up... 01:27:12 INFO - Return code: 0 01:27:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:27:12 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')]} 01:27:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:27:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:12 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')]} 01:27:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:27:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:12 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 0x1c09030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d6c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e38400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ec7570>, '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 0x1ec8470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ec8900>, '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': '6dbc10da6d83fee962c624be000001a8-1474360014.376953-62072691', '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 01:27:12 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 01:27:12 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 01:27:12 INFO - Using env: {'DISPLAY': ':0', 01:27:12 INFO - 'HOME': '/home/cltbld', 01:27:12 INFO - 'LANG': 'en_US.UTF-8', 01:27:12 INFO - 'LANGUAGE': 'en_US:en', 01:27:12 INFO - 'LOGNAME': 'cltbld', 01:27:12 INFO - 'MAIL': '/var/mail/cltbld', 01:27:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:27:12 INFO - 'MOZ_NO_REMOTE': '1', 01:27:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:27:12 INFO - 'NO_EM_RESTART': '1', 01:27:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:27:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:27:12 INFO - 'PWD': '/builds/slave/test', 01:27:12 INFO - 'SHELL': '/bin/bash', 01:27:12 INFO - 'SHLVL': '1', 01:27:12 INFO - 'TERM': 'linux', 01:27:12 INFO - 'TMOUT': '86400', 01:27:12 INFO - 'USER': 'cltbld', 01:27:12 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1474360014.376953-62072691', 01:27:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:27:12 INFO - '_': '/tools/buildbot/bin/python'} 01:27:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:27:13 INFO - Downloading/unpacking blobuploader==1.2.4 01:27:13 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 01:27:13 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 01:27:13 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 01:27:13 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 01:27:13 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 01:27:13 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 01:27:15 INFO - Downloading blobuploader-1.2.4.tar.gz 01:27:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:27:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:27:15 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 01:27:15 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 01:27:15 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 01:27:15 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 01:27:15 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 01:27:15 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 01:27:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:27:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:27:15 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 01:27:15 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 01:27:15 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 01:27:15 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 01:27:15 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 01:27:15 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 01:27:15 INFO - Downloading docopt-0.6.1.tar.gz 01:27:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:27:15 INFO - Installing collected packages: blobuploader, requests, docopt 01:27:15 INFO - Running setup.py install for blobuploader 01:27:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:27:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:27:15 INFO - Running setup.py install for requests 01:27:16 INFO - Running setup.py install for docopt 01:27:16 INFO - Successfully installed blobuploader requests docopt 01:27:16 INFO - Cleaning up... 01:27:16 INFO - Return code: 0 01:27:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:27:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:27:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:27:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:27:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:27:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:16 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 0x1c09030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d6c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e38400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ec7570>, '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 0x1ec8470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ec8900>, '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': '6dbc10da6d83fee962c624be000001a8-1474360014.376953-62072691', '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 01:27:16 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 01:27:16 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 01:27:16 INFO - Using env: {'DISPLAY': ':0', 01:27:16 INFO - 'HOME': '/home/cltbld', 01:27:16 INFO - 'LANG': 'en_US.UTF-8', 01:27:16 INFO - 'LANGUAGE': 'en_US:en', 01:27:16 INFO - 'LOGNAME': 'cltbld', 01:27:16 INFO - 'MAIL': '/var/mail/cltbld', 01:27:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:27:16 INFO - 'MOZ_NO_REMOTE': '1', 01:27:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:27:16 INFO - 'NO_EM_RESTART': '1', 01:27:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:27:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:27:16 INFO - 'PWD': '/builds/slave/test', 01:27:16 INFO - 'SHELL': '/bin/bash', 01:27:16 INFO - 'SHLVL': '1', 01:27:16 INFO - 'TERM': 'linux', 01:27:16 INFO - 'TMOUT': '86400', 01:27:16 INFO - 'USER': 'cltbld', 01:27:16 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1474360014.376953-62072691', 01:27:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:27:16 INFO - '_': '/tools/buildbot/bin/python'} 01:27:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:27:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:27:16 INFO - Running setup.py (path:/tmp/pip-BYqB2f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:27:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:27:16 INFO - Running setup.py (path:/tmp/pip-04gmf4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:27:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:27:16 INFO - Running setup.py (path:/tmp/pip-mxOU9V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:27:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:27:16 INFO - Running setup.py (path:/tmp/pip-_OnU_U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:27:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:27:16 INFO - Running setup.py (path:/tmp/pip-YoYqdf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:27:17 INFO - Running setup.py (path:/tmp/pip-4RPfVX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:27:17 INFO - Running setup.py (path:/tmp/pip-yloh2k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:27:17 INFO - Running setup.py (path:/tmp/pip-yabVqk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:27:17 INFO - Running setup.py (path:/tmp/pip-RlAZHM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:27:17 INFO - Running setup.py (path:/tmp/pip-uWQNJ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:27:17 INFO - Running setup.py (path:/tmp/pip-PHl93i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:27:17 INFO - Running setup.py (path:/tmp/pip-ReWfvw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:27:17 INFO - Running setup.py (path:/tmp/pip-VGXAWs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:27:17 INFO - Running setup.py (path:/tmp/pip-tZXZII-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:27:17 INFO - Running setup.py (path:/tmp/pip-L3aLxF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:27:17 INFO - Running setup.py (path:/tmp/pip-Nwlj9O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:27:17 INFO - Running setup.py (path:/tmp/pip-irinMs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:27:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:27:17 INFO - Running setup.py install for manifestparser 01:27:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:27:18 INFO - Running setup.py install for mozcrash 01:27:18 INFO - Running setup.py install for mozdebug 01:27:18 INFO - Running setup.py install for mozdevice 01:27:18 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:27:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:27:18 INFO - Running setup.py install for mozfile 01:27:18 INFO - Running setup.py install for mozhttpd 01:27:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:27:18 INFO - Running setup.py install for mozinfo 01:27:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:27:18 INFO - Running setup.py install for mozInstall 01:27:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:27:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:27:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:27:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:27:18 INFO - Running setup.py install for mozleak 01:27:18 INFO - Running setup.py install for mozlog 01:27:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:27:18 INFO - Running setup.py install for moznetwork 01:27:19 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:27:19 INFO - Running setup.py install for mozprocess 01:27:19 INFO - Running setup.py install for mozprofile 01:27:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:27:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:27:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:27:19 INFO - Running setup.py install for mozrunner 01:27:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:27:19 INFO - Running setup.py install for mozscreenshot 01:27:19 INFO - Running setup.py install for moztest 01:27:19 INFO - Running setup.py install for mozversion 01:27:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:27:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:27:19 INFO - Cleaning up... 01:27:19 INFO - Return code: 0 01:27:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:27:19 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')]} 01:27:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:27:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:19 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')]} 01:27:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:27:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:19 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 0x1c09030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d6c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e38400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ec7570>, '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 0x1ec8470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ec8900>, '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': '6dbc10da6d83fee962c624be000001a8-1474360014.376953-62072691', '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 01:27:19 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 01:27:19 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 01:27:19 INFO - Using env: {'DISPLAY': ':0', 01:27:19 INFO - 'HOME': '/home/cltbld', 01:27:19 INFO - 'LANG': 'en_US.UTF-8', 01:27:19 INFO - 'LANGUAGE': 'en_US:en', 01:27:19 INFO - 'LOGNAME': 'cltbld', 01:27:19 INFO - 'MAIL': '/var/mail/cltbld', 01:27:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:27:19 INFO - 'MOZ_NO_REMOTE': '1', 01:27:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:27:19 INFO - 'NO_EM_RESTART': '1', 01:27:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:27:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:27:19 INFO - 'PWD': '/builds/slave/test', 01:27:19 INFO - 'SHELL': '/bin/bash', 01:27:19 INFO - 'SHLVL': '1', 01:27:19 INFO - 'TERM': 'linux', 01:27:19 INFO - 'TMOUT': '86400', 01:27:19 INFO - 'USER': 'cltbld', 01:27:19 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1474360014.376953-62072691', 01:27:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:27:19 INFO - '_': '/tools/buildbot/bin/python'} 01:27:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:27:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:27:19 INFO - Running setup.py (path:/tmp/pip-p6t4iG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:27:19 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)) 01:27:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:27:19 INFO - Running setup.py (path:/tmp/pip-_nsc22-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:27:20 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)) 01:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:27:20 INFO - Running setup.py (path:/tmp/pip-LT_nGO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:27:20 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)) 01:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:27:20 INFO - Running setup.py (path:/tmp/pip-cUzNLo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:27:20 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)) 01:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:27:20 INFO - Running setup.py (path:/tmp/pip-M46WdY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:27:20 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)) 01:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:27:20 INFO - Running setup.py (path:/tmp/pip-_no3X4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:27:20 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)) 01:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:27:20 INFO - Running setup.py (path:/tmp/pip-9e5IIQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:27:20 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)) 01:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:27:20 INFO - Running setup.py (path:/tmp/pip-Jws1iP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:27:20 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)) 01:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:27:20 INFO - Running setup.py (path:/tmp/pip-0f_950-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:27:20 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)) 01:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:27:20 INFO - Running setup.py (path:/tmp/pip-s1aFK7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:27:20 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)) 01:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:27:20 INFO - Running setup.py (path:/tmp/pip-iGBpdw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:27:20 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)) 01:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:27:20 INFO - Running setup.py (path:/tmp/pip-G901Ob-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:27:20 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)) 01:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:27:20 INFO - Running setup.py (path:/tmp/pip-5zt_H0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:27:20 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)) 01:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:27:20 INFO - Running setup.py (path:/tmp/pip-IH5Nlo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:27:20 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)) 01:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:27:20 INFO - Running setup.py (path:/tmp/pip-zcWSXe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:27:21 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)) 01:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:27:21 INFO - Running setup.py (path:/tmp/pip-KfpyrG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:27:21 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)) 01:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:27:21 INFO - Running setup.py (path:/tmp/pip-aQ9tQb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:27:21 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)) 01:27:21 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)) 01:27:21 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)) 01:27:21 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)) 01:27:21 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)) 01:27:21 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)) 01:27:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:27:21 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 01:27:21 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 01:27:21 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 01:27:21 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 01:27:21 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 01:27:21 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 01:27:23 INFO - Downloading blessings-1.6.tar.gz 01:27:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:27:23 INFO - Installing collected packages: blessings 01:27:23 INFO - Running setup.py install for blessings 01:27:23 INFO - Successfully installed blessings 01:27:23 INFO - Cleaning up... 01:27:23 INFO - Return code: 0 01:27:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:27:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:27:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:27:23 INFO - Reading from file tmpfile_stdout 01:27:23 INFO - Current package versions: 01:27:23 INFO - argparse == 1.2.1 01:27:23 INFO - blessings == 1.6 01:27:23 INFO - blobuploader == 1.2.4 01:27:23 INFO - docopt == 0.6.1 01:27:23 INFO - functools32 == 3.2.3-2 01:27:23 INFO - jsonschema == 2.5.1 01:27:23 INFO - manifestparser == 1.1 01:27:23 INFO - mozInstall == 1.12 01:27:23 INFO - mozcrash == 0.17 01:27:23 INFO - mozdebug == 0.1 01:27:23 INFO - mozdevice == 0.48 01:27:23 INFO - mozfile == 1.2 01:27:23 INFO - mozhttpd == 0.7 01:27:23 INFO - mozinfo == 0.9 01:27:23 INFO - mozleak == 0.1 01:27:23 INFO - mozlog == 3.3 01:27:23 INFO - moznetwork == 0.27 01:27:23 INFO - mozprocess == 0.23 01:27:23 INFO - mozprofile == 0.28 01:27:23 INFO - mozrunner == 6.12 01:27:23 INFO - mozscreenshot == 0.1 01:27:23 INFO - mozsystemmonitor == 0.3 01:27:23 INFO - moztest == 0.7 01:27:23 INFO - mozversion == 1.4 01:27:23 INFO - psutil == 3.1.1 01:27:23 INFO - requests == 1.2.3 01:27:23 INFO - wsgiref == 0.1.2 01:27:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:27: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')]} 01:27:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:27:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27: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')]} 01:27:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:27:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:23 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 0x1c09030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d6c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e38400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ec7570>, '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 0x1ec8470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ec8900>, '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': '6dbc10da6d83fee962c624be000001a8-1474360014.376953-62072691', '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 01:27:23 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 01:27:23 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 01:27:23 INFO - Using env: {'DISPLAY': ':0', 01:27:23 INFO - 'HOME': '/home/cltbld', 01:27:23 INFO - 'LANG': 'en_US.UTF-8', 01:27:23 INFO - 'LANGUAGE': 'en_US:en', 01:27:23 INFO - 'LOGNAME': 'cltbld', 01:27:23 INFO - 'MAIL': '/var/mail/cltbld', 01:27:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:27:23 INFO - 'MOZ_NO_REMOTE': '1', 01:27:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:27:23 INFO - 'NO_EM_RESTART': '1', 01:27:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:27:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:27:23 INFO - 'PWD': '/builds/slave/test', 01:27:23 INFO - 'SHELL': '/bin/bash', 01:27:23 INFO - 'SHLVL': '1', 01:27:23 INFO - 'TERM': 'linux', 01:27:23 INFO - 'TMOUT': '86400', 01:27:23 INFO - 'USER': 'cltbld', 01:27:23 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1474360014.376953-62072691', 01:27:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:27:23 INFO - '_': '/tools/buildbot/bin/python'} 01:27:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:27:23 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)) 01:27:23 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)) 01:27:23 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)) 01:27:23 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)) 01:27:23 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)) 01:27:23 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)) 01:27:23 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)) 01:27:23 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)) 01:27:23 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)) 01:27:23 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)) 01:27:23 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)) 01:27:23 INFO - Cleaning up... 01:27:23 INFO - Return code: 0 01:27:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:27: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')]} 01:27:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:27:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27: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')]} 01:27:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:27:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:23 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 0x1c09030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d6c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e38400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ec7570>, '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 0x1ec8470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ec8900>, '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': '6dbc10da6d83fee962c624be000001a8-1474360014.376953-62072691', '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 01:27:23 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 01:27:23 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 01:27:23 INFO - Using env: {'DISPLAY': ':0', 01:27:23 INFO - 'HOME': '/home/cltbld', 01:27:23 INFO - 'LANG': 'en_US.UTF-8', 01:27:23 INFO - 'LANGUAGE': 'en_US:en', 01:27:23 INFO - 'LOGNAME': 'cltbld', 01:27:23 INFO - 'MAIL': '/var/mail/cltbld', 01:27:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:27:23 INFO - 'MOZ_NO_REMOTE': '1', 01:27:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:27:23 INFO - 'NO_EM_RESTART': '1', 01:27:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:27:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:27:23 INFO - 'PWD': '/builds/slave/test', 01:27:23 INFO - 'SHELL': '/bin/bash', 01:27:23 INFO - 'SHLVL': '1', 01:27:23 INFO - 'TERM': 'linux', 01:27:23 INFO - 'TMOUT': '86400', 01:27:23 INFO - 'USER': 'cltbld', 01:27:23 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1474360014.376953-62072691', 01:27:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:27:23 INFO - '_': '/tools/buildbot/bin/python'} 01:27:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:27:24 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 01:27:24 INFO - Cleaning up... 01:27:24 INFO - Return code: 0 01:27:24 INFO - Running post-action listener: _resource_record_post_action 01:27:24 INFO - Running post-action listener: _start_resource_monitoring 01:27:24 INFO - Starting resource monitoring. 01:27:24 INFO - [mozharness: 2016-09-20 08:27:24.141392Z] Finished create-virtualenv step (success) 01:27:24 INFO - [mozharness: 2016-09-20 08:27:24.141868Z] Running install step. 01:27:24 INFO - Running pre-action listener: _resource_record_pre_action 01:27:24 INFO - Running main action method: install 01:27:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:27:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:27:24 INFO - Reading from file tmpfile_stdout 01:27:24 INFO - Detecting whether we're running mozinstall >=1.0... 01:27:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:27:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:27:24 INFO - Reading from file tmpfile_stdout 01:27:24 INFO - Output received: 01:27:24 INFO - Usage: mozinstall [options] installer 01:27:24 INFO - Options: 01:27:24 INFO - -h, --help show this help message and exit 01:27:24 INFO - -d DEST, --destination=DEST 01:27:24 INFO - Directory to install application into. [default: 01:27:24 INFO - "/builds/slave/test"] 01:27:24 INFO - --app=APP Application being installed. [default: firefox] 01:27:24 INFO - mkdir: /builds/slave/test/build/application 01:27:24 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'] 01:27:24 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 01:27:37 INFO - Reading from file tmpfile_stdout 01:27:37 INFO - Output received: 01:27:37 INFO - /builds/slave/test/build/application/firefox/firefox 01:27:37 INFO - Running post-action listener: _resource_record_post_action 01:27:37 INFO - [mozharness: 2016-09-20 08:27:37.979734Z] Finished install step (success) 01:27:37 INFO - [mozharness: 2016-09-20 08:27:37.980010Z] Running run-tests step. 01:27:37 INFO - Running pre-action listener: _resource_record_pre_action 01:27:37 INFO - Running main action method: run_tests 01:27:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:27:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:27:37 INFO - Python 2.7.3 01:27:37 INFO - Return code: 0 01:27:37 INFO - grabbing minidump binary from tooltool 01:27:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:27:37 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 0x1ec7570>, '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 0x1ec8470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ec8900>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:27:37 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 01:27:37 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 01:27:37 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 01:27:38 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:27:38 INFO - Return code: 0 01:27:38 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 01:27:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:27:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:27:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 01:27:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:27:38 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-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474323317/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-050', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 01:27:38 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-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474323317/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-050 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 01:27:38 INFO - Using env: {'DISPLAY': ':0', 01:27:38 INFO - 'HOME': '/home/cltbld', 01:27:38 INFO - 'LANG': 'en_US.UTF-8', 01:27:38 INFO - 'LANGUAGE': 'en_US:en', 01:27:38 INFO - 'LOGNAME': 'cltbld', 01:27:38 INFO - 'MAIL': '/var/mail/cltbld', 01:27:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:27:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 01:27:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:27:38 INFO - 'MOZ_NO_REMOTE': '1', 01:27:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:27:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:27:38 INFO - 'NO_EM_RESTART': '1', 01:27:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:27:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:27:38 INFO - 'PWD': '/builds/slave/test', 01:27:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:27:38 INFO - 'SHELL': '/bin/bash', 01:27:38 INFO - 'SHLVL': '1', 01:27:38 INFO - 'TERM': 'linux', 01:27:38 INFO - 'TMOUT': '86400', 01:27:38 INFO - 'USER': 'cltbld', 01:27:38 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1474360014.376953-62072691', 01:27:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:27:38 INFO - '_': '/tools/buildbot/bin/python'} 01:27:38 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474323317/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-050', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 01:27:38 INFO - mozversion application_buildid: 20160919151517 01:27:38 INFO - mozversion application_changeset: 4376f2389699910ebe72e39977d563c77a094a1d 01:27:38 INFO - mozversion application_display_name: Firefox 01:27:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:27:38 INFO - mozversion application_name: Firefox 01:27:38 INFO - mozversion application_remotingname: firefox 01:27:38 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 01:27:38 INFO - mozversion application_vendor: Mozilla 01:27:38 INFO - mozversion application_version: 50.0 01:27:38 INFO - mozversion platform_buildid: 20160919151517 01:27:38 INFO - mozversion platform_changeset: 4376f2389699910ebe72e39977d563c77a094a1d 01:27:38 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 01:27:38 INFO - mozversion platform_version: 50.0 01:27:38 INFO - using testdate: 1474360058 01:27:38 INFO - actual date: 1474360058 01:27:38 INFO - starting webserver on 'localhost:50456' 01:27:38 INFO - SUITE-START | Running 2 tests 01:27:38 INFO - TEST-START | dromaeo_css 01:27:38 INFO - operating with platform_type : linux_ 01:27:38 INFO - Initialising browser for dromaeo_css test... 01:27:38 INFO - TEST-INFO | started process 8116 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoKSN2t/profile http://localhost:50456/getInfo.html) 01:27:42 INFO - PROCESS | 8116 | __metrics Screen width/height:1600/1200 01:27:42 INFO - PROCESS | 8116 | colorDepth:24 01:27:42 INFO - PROCESS | 8116 | Browser inner width/height: 1024/697 01:27:42 INFO - PROCESS | 8116 | __metrics 01:27:43 ERROR - PROCESS | 8116 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 01:27:43 INFO - TEST-INFO | 8116: exit 0 01:27:43 INFO - Browser initialized. 01:27:43 INFO - Running cycle 1/1 for dromaeo_css test... 01:27:43 INFO - TEST-INFO | started process 8239 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoKSN2t/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:27:55 INFO - PROCESS | 8239 | RSS: Main: 150142976 01:27:55 INFO - PROCESS | 8239 | 01:28:45 INFO - PROCESS | 8239 | 1474360125210 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] 01:30:53 INFO - PROCESS | 8239 | Cycle 1(1): loaded http://localhost:50456/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50456/tests/dromaeo/cssquery-ext.html) 01:30:55 INFO - PROCESS | 8239 | RSS: Main: 157749248 01:30:55 INFO - PROCESS | 8239 | 01:33:56 INFO - PROCESS | 8239 | Cycle 1(1): loaded http://localhost:50456/tests/dromaeo/cssquery-ext.html (next: http://localhost:50456/tests/dromaeo/cssquery-jquery.html) 01:33:57 INFO - PROCESS | 8239 | RSS: Main: 155648000 01:33:57 INFO - PROCESS | 8239 | 01:36:59 INFO - PROCESS | 8239 | Cycle 1(1): loaded http://localhost:50456/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50456/tests/dromaeo/cssquery-mootools.html) 01:36:59 INFO - PROCESS | 8239 | RSS: Main: 155242496 01:36:59 INFO - PROCESS | 8239 | 01:40:01 INFO - PROCESS | 8239 | Cycle 1(1): loaded http://localhost:50456/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50456/tests/dromaeo/cssquery-prototype.html) 01:40:02 INFO - PROCESS | 8239 | RSS: Main: 156712960 01:40:02 INFO - PROCESS | 8239 | 01:43:04 INFO - PROCESS | 8239 | Cycle 1(1): loaded http://localhost:50456/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50456/tests/dromaeo/cssquery-yui.html) 01:43:05 INFO - PROCESS | 8239 | RSS: Main: 157564928 01:43:05 INFO - PROCESS | 8239 | 01:46:07 INFO - PROCESS | 8239 | Cycle 1(1): loaded http://localhost:50456/tests/dromaeo/cssquery-yui.html (next: http://localhost:50456/tests/dromaeo/cssquery-dojo.html) 01:46:08 INFO - PROCESS | 8239 | RSS: Main: 156913664 01:46:08 INFO - PROCESS | 8239 | 01:46:08 INFO - PROCESS | 8239 | __start_tp_report 01:46:08 INFO - PROCESS | 8239 | _x_x_mozilla_page_load 01:46:08 INFO - PROCESS | 8239 | _x_x_mozilla_page_load_details 01:46:08 INFO - PROCESS | 8239 | |i|pagename|runs| 01:46:08 INFO - PROCESS | 8239 | |0;dojo.html;3263.8857639982602;3290.8683652653895;3294.654061323561;3305.6529064448246;3319.352726218387;5775.393583673712;5782.335031471372;5782.710864456782;5806.216160818289;5809.157672137538;5816.156657284691;5817.331006934193;5817.505512031483;5822.737976791044;5833.300003999515;5219.164933610633;5272.420033796279;5281.471852814707;5296.92054619181;5347.839564813062;5140.640155189139;5169.379674439073;5215.765290561933;5231.398389185254;5265.999460102587;5258.2638430619745;5263.26314315996;5264.736763161845;5327.573794096463;5361.865953351178;5311.654742441749;5314.282571852789;5329.813456529003;5333.439988801198;5364.731763411853;5024.371953505812;5096.770645320969;5209.557187639055;5228.189630607262;5290.68255904647;5678.858028549278;5739.253896993406;5739.3973632768375;5781.566382521328;5805.187273781632;6141.477974372184;6156.29202641693;6174.567780255384;6187.5668703190795;6199.62802231863;5994.670293133876;6081.148639190517;6236.220472440945;6245.46913512352;6263.404976527223;5710.486056254916;5774.595778295479;5802.651840889603;5808.3030036395485;5819.67991760453;6281.685915704288;6323.36766323364;6335.271443783931;6361.077643741724;6369.108324834527;5800.071988481823;6000.249968753906;6002.759889604455;6087.939120608826;6096.3903609639565;6140.232470941132;6254.124422580842;6255.937440625627;6257.499400047986;6274.937250627527;6184.5980011299125;6232.563720539519;6237.563370564108;6244.906326405107;6247.094171345129;6003.489703375175;6005.489533389624;6234.906476402857;6237.064440333896;6240.563160578807;4638.1883170445035;4654.138984287918;4660.533946605312;4671.0891376182;4704.553067458586;4186.330187170038;4187.413762073312;4201;4214.915701685949;4216.388623649554;5608.158776183605;5785.363610002896;5815;5877.647341159459;5878.059510478303;6128.564234752123;6278.560500764994;6300.086487459384;6302.716377763011;6308.873822523616;5891.9705401473575;5928.318243401977;5940.287165540163;5940.405959404025;5988.221531200916;5154.123798954187;5246;5360.115580929181;5364.865878353073;5376.086065368819;6311.621302721851;6459.09572659828;6470.6441145737435;6484.546081774325;6493.935060649427;6225.719842607185;6228.377162283736;6248.4064013918605;6265.216847894013;6271.780487683091;6100.359462256399;6256.687165641609;6372.394622510854;6425.678716064084;6485.351464853666;6061.848453788691;6098;6115.69342884025;6119.53301968977;6121.696863452088;6194.876102477835;6215.533834962305;6217.720202590804;6244.562880598496;6256.343083976299;5903.822885313448;6026.819195424145;6036.698165091816;6046.425589568984;6075.7569697213385;6077.027675571887;6169.660668663087;6171.537134714825;6175.228096487939;6189.442950134509;6081.847953801191;6110.175126358063;6147.600405973598;6166.445019948226;6179.474744646575;5853.795117170878;6025.66858822769;6153;6185.474234689922;6233.844153896008;5938.792142275;6007.339192688888;6027.547933905063;6043.123747056564;6060.727267272895 01:46:08 INFO - PROCESS | 8239 | |1;ext.html;580.4137820800989;621.2638023941629;653.7189008726248;677.8576498935223;712.7184762019;42189.57810421895;46126.7693661532;46129.308060379124;46215.76892115539;46475.30287045697;45814;46539.30191047137;46637.533624663745;46648.76675616617;46664.53335466644;9095.81808363834;9263.583138758739;9335.76660583484;9335.95332023339;9341.906580934172;9229.12323329286;9271;9329.906700933007;9337.346385753;9355.158035776778;9396.389234699722;9409.858852117224;9417.576209070574;9419.387739796926;9435.811283774321;9263.536823158815;9270.304727145458;9287.489188094652;9335.533223338805;9339.859902101474;9101.226395756368;9165.725028249164;9177.862332065024;9190.678326258616;9212.401193922373;43938.34092488615;44367;44377.55622443766;44422.33366499505;44543;3723.8696645617542;3853.9614603853884;4006.9198616027807;4196.790160491963;4251.298535741599;34774.47838282428;34903.825480872685;34999.475007874906;35130.47304290438;35382.8230858844;4673.299005149221;4726.976365118187;4738.810447582093;4742.5731684148595;4767.284907263904;267138;267922;269387;269556;270609;63902.36097639057;64114.67942660257;64300.35699642944;64369;64448;39769.60230397717;68285.31714682789;68457.65771171212;68491;68587;18539.35112271064;19525.80474195268;19642.508937276398;19654.115564799617;19671;15272.160031198391;15311.464098756713;15370.692586148438;15396.46112386061;15401.460948866736;21211.893940530197;21227.257046003317;21534.892325538272;21538.353849384544;21557.78442215558;7751.37988960893;7762.767116986386;7793.376529877596;7940.920590794133;7992.80017999543;1001.399160503692;1064.6220591689994;1073.0342691577644;1097.6981330134277;1126.3298337489182;33941.83029084839;34168;34382.312353752786;34390.48414273788;34602.48096278558;1178.7112157521462;1217.4886547650008;1234.6296111166614;1263.810428435742;1277.9424925878359;17947.102644867966;17961.37135200288;18123.275068997355;18128.546786330448;18246.817531824512;592235;593533;593571;594266;594681;64822.351776482574;65121.67439162774;65260.34739652638;65520.34479655143;65873;1456.7523521001244;1478.8742956848573;1484.8515148485064;1488.0655148307158;1518.1119045358369;31619.525707113953;32257;32420.513692294164;32543.51184732278;32992.1751956203;56038.71980640071;56040;56050.15924761214;56212.43787562072;56216.71891640679;472250;475172;476200;476418;478327;31030.22424439317;31293.374132517678;31373.529397058606;31456.213594660316;31686.68313316837;52355.476445236585;52608.73695631498;52614.47385526249;52635.73682131718;52838.47161528489;44387.778061109486;45052;45112.77443612761;45387.546124538334;46293.53706462892;43206.783966079965;44292.33561496516;44620.10759784851;45216;45931.54068459406;45043;45270.32094518519;46076.76961615305;46191;48242;38137;39268.21463570656;40221.79889100536;40466.59533404628;41088;29815.403691926473;30589;30669.159850409047;31094.689053110083;31222.843885780425 01:46:08 INFO - PROCESS | 8239 | |2;jquery.html;383.4822988964896;392.2566736035215;415.389377614906;418;418.372441337993;14630.926845365784;18885.71671424928;19670.11484483198;20610.690839637417;20640.48398790031;19852.900735496303;19948.70076948847;19999.400017999484;20139.194432222732;20180.495487612818;5774.16274640178;5929;5951.077582974646;5999.640021598696;6013.36859629737;5680.886382272352;5822.7088645567765;5877.7355019024035;5881.382454842244;5895.233619629442;5592.720363981805;5840.06558950568;5851.327097383792;5924.992751232301;5940.465358117768;5912.201852749867;5913.526917846562;5929.199558059693;5970.552208584373;5975.1037344398255;5919.526437884959;5970.044792833126;6010.639361638316;6045.244344456943;6077.36187700294;14579.781303280459;14715.484958026418;19402.514937126685;23139.421514462105;23930.401739956465;2511.824172307939;2640.7755340796057;2710.7560319571135;2997.985010074936;3046.375493023947;10303.845442318372;10355.223358248086;10606.78786424275;10743.892561074368;10827.945860270649;1954.7654281486314;2061.4228016155503;2082.822960048398;2107.1676687708423;2136.8931553422267;87025.56487217524;87361;87444;88989.55505222497;89284.5535772317;9341.766455838522;9878.555465004092;9925.65260215889;9959.452230127268;10307.793844123225;14003.159810411407;14081.084729492755;14093.15441073539;14120.71758564823;14155.150690958575;8456.281216096577;8565.657373705106;8695.260902823204;8695.99737988669;8716.869246961302;8823.161799629153;8837.823243535093;8869.246114080375;8991.460512369278;9055.59249833759;4286.485621725413;4300.89247768802;4311.676624253194;4339.674524410619;4382.298832186835;1967.9606407871763;2049.866758660682;2063.597598468314;2090.226616152003;2125.7342832145982;870.9825803483894;1003.2976916158718;1004.5780772075746;1021.4382089850552;1022.1618273016204;18070.186841591898;18621.162047707887;18696.252149914122;18890.055497225083;19103.713444298348;946.5645802930729;958.4105774948357;960.1406740966935;976.8485884687884;993.5479356892597;9836.21310295189;10356.430396328124;10399.636012739518;10633.73415664599;10901.509432075576;501893;539034;589813;600489;618629;59506.70246648739;99312;103867;104960.47519762353;108599;1101.4217535793773;1129.170060005895;1136.3693150301497;1150.3213104268523;1161.79668558002;18346.724799127758;18494;18777.248910043447;18837.90581047141;19033.90483047576;44875.775621121684;45234;46404;46944;47053.29420058634;66205.66897165483;66294.33705662882;67652.661736691;68217;68843.65578172076;7482.812929676802;8192.713255036046;8446.704365347183;9081.591328390106;9389.765255868659;12871.613851584467;13012.869871301165;13018.414171362121;13163.47346106145;13170.275634839792;11372.829407559058;11466.484008219484;11484.82772758426;11641.359725215194;11687.474063666985;11506.884931150582;11547.307161570332;11554.76890462203;11722.70693232676;11805.645830625095;11170.27393219438;11471.426428678365;11484.94257528707;11842.993345565723;11868;8362.205590468895;8429;8484;8514.10601886793;8679.652813887375;8967.551622418983;10999.395033273247;11154.944225279147;11330.263532870336;11642.126840486828 01:46:08 INFO - PROCESS | 8239 | |3;mootools.html;1595.6649103688226;1612.9354825806968;1808.8191180881913;1834.339637730416;1923.0865338963986;8462.069172391031;8968.775780605472;9200.907990920088;10073.496325183729;10082.899171008288;8949.328800339985;9276.397034192772;9286.582103805327;9297.25621950244;9301.116393942582;2496.41334286443;2530.02594001309;2554.8211625186245;2555.9488810223784;2556.1309154887335;2471.938201544958;2540.745925407454;2546.63073854291;2554.859482728449;2556.6292887531295;2490.340059884132;2491.215267190838;2526.873656317186;2526.873656317186;2531.4177739120014;2450.9387265318333;2457.066314800373;2471.851688898663;2474.0474917156844;2478.2689106713565;2449.35092200567;2464.581021226396;2483.9751602483925;2486.962695559568;2493.463905260378;9869.950650246774;9927.255455840768;9987.201023918069;9993.250506212064;10013.949930250375;5663.603547751659;5816.534677225812;5848.356680765112;5849.268841394825;5867.295924489089;3613.8074435436242;3685.1708365617787;3724.5902950675404;3726.757760745543;3778.2443511297574;1200.333814732823;1246.7818131826893;1272.8090786382024;1274.6048724895313;1285.395863943945;13745.9999999999;13781.379837907318;13831.723365532735;13847.238401887893;13900.652483687887;4776.188048031843;5074.67014644047;5079.111155547765;5102.540771330524;5122.871928201825;11677.941610291895;11684.591039313584;11688.766224675459;11705.063594912386;11736.295822250693;5803.129530570448;5810.6513609183585;5812.941870581325;5835.679037652885;5838.299404071538;4798.47216806151;5218.347706536683;5240.947590524046;5296.549793267615;5305.734713264322;4283.186194623012;4363.214621368184;4400.845970391021;4403.889902752393;4414.050979039522;1326.323574976769;1337.9866201338057;1346.1788309131423;1348.0496250143626;1360.816289800884;949.6866034208695;1061.5541472581535;1064.297563608015;1066.6320119558895;1073.2809017957986;6375.808725738235;6429.3570642936265;6486.124373209556;6528.412442880163;6541.640209788412;492.6354497671697;503.3205173016399;509.2768269057947;511.27654369068097;516.8449465160437;4430.180416622935;4457.840961350073;4462.174497717932;4466.151431228056;4503.617192538605;11829.585964491202;12086.395680215954;12184.75630487403;12207.816882746765;12213.389330533439;11135.33188008722;11606.709832254091;11726.589569365202;12108.334041627619;12135.939320303341;8896.644134234688;8944.060873608178;8983.281337492985;9072.04743501936;9092.909070909207;6433.228012638513;6723.697433615401;6742.561733487308;6744.156980377453;6859.657017149222;10545.472726363805;10893.346399216072;11143.219974602025;11176.720581985515;11177.329360238411;11070.889291106985;11088.223824332219;11247.831282530606;11257.268277561932;11308.529891114102;9058.637654493747;9060.54697265121;9141.680041198526;9175.174234318943;9331.766705832137;9580.808383832145;9605.711828645151;9613.279004074582;9622.230221582116;9635.710928672152;8302.87545686803;8366.288865446506;8549.059603443491;8688.652453902027;8761.255293299886;7811.414143939113;7968.521888686697;8043.316318112792;8200.26197642215;8206.630701618322;7218.927810722035;7322.670479828314;7383.150937642025;7572.697092116254;7603.809904752205;5637.049142868374;5788.884222315614;5815.883682326415;5855.711743416442;5916.142159386779;5830.854228644318;5865.384134665798;5911.083782013795;6023.969880150571;6067.4539291462215 01:46:08 INFO - PROCESS | 8239 | |4;prototype.html;330.3871318703804;334.3980834497904;340.7001838382223;341.528690407238;343.5791155834102;5909.645421274721;5954.70226488676;5971.4625683688455;5972.134040564116;5987.79042733505;5785.466851284413;5808.767649294034;5813.767449302033;5849.795257166;5869.794557190499;4335.544767799383;4465.173942820588;4538.7503687297185;4556.908861822778;4574.130915126132;4523.163214805255;4538.523455037223;4547.977260113711;4577.679562430631;4589.5410458954;4541.137183935059;4548.135854187711;4558.680892337537;4573.056451008309;4578.10726908253;4300.827966881321;4422.955770442302;4472.038511719996;4485.945802736354;4494;3872.7095467840027;3883.650471457554;3894.955148547007;3900.4344370066183;3913.3347330953807;5642.379338272786;5662.858428539278;5702.800401985952;5709.857253568653;5738.770449182028;5034.194528875399;5095.592352611782;5161.354830646169;5166.844994650167;5177.171652535614;6036.366181550918;6099.390060993909;6100.877982440371;6106.328303886569;6140;4213.820130363503;4323.524412314643;4345.67407444443;4351.238533256667;4367.279398899178;6235.625862448268;6285.465735412496;6313.116163737034;6324.272708638474;6346.809595712136;4399.956000439955;4412.867613971586;4421;4442.577955094261;4443.200223959719;4344.326629372453;4431.180231657089;4442.111577684412;4442.8667139985855;4463.754493502824;5091.287219789232;5128.410232823273;5132.204508301219;5143.665661731976;5159.864829737452;6075.3317135115985;6096.664683442365;6111.777644471034;6124.663143527059;6133.877322453526;1430.9426483339528;1461.167134733192;1487;1503.4437258214311;1508.9773653395207;962.1436921140191;970.4225985538593;1030.8917563655857;1043.1967385113421;1058.0107599394437;5895.734691938873;5898.94101058984;5903;5908.704564771744;5909.6158749681135;6222.782202622887;6227.626342419469;6228.190335256388;6281.434670879643;6287.937120628827;5669.234653321749;5810.912836307459;5866;5879.470847623735;5887.411258874164;3343.963371354888;3383.1392733996695;3408.9318213636084;3409.284050349405;3414.6097731587492;6278.874422511615;6447.548671592944;6526.967365163238;6585.209774827052;6597.010448432773;6509.2839787623325;6527.804165875032;6535.738570457129;6553.770618028346;6568.146141001735;6339.415146213447;6527.934720652733;6689.966550167218;6700.530962832555;6734.225564060098;6376.171097757262;6378.138951241523;6437.581557198767;6443.452306553995;6447;6127.84680382994;6346.174997250328;6367.93632063692;6369.267534233437;6395.904061439175;6562.5406221564035;6577.375149360803;6612.636305003271;6612.8346791330605;6615.37153970372;4463.665225108065;4477.567178502848;4488.663350248679;4489.91020179588;4493.865184044484;6313.273973493107;6346.143270658402;6361.268454127649;6385.23377194721;6475.287718350884;6189.752409903554;6202.627842329474;6224.692814509004;6246;6304.148939893055;6010.398960104129;6181.567290289817;6190.350013248544;6202.782902598387;6260.74957001733;6036.185115009417;6179.876402472015;6183.659898705614;6187.628742275478;6236.18929539157;6187.938120618916;6237.1891654084675;6250.1874756281395;6268.4671802897265;6332;5886.705664716833;6058.273007238983;6090.330063692902;6116.204893364013;6119.816405507842 01:46:08 INFO - PROCESS | 8239 | |5;yui.html;830.6926437218226;878.8681697745337;900.1718419054471;912.9406588571746;951.8001219743853;15747.212639368043;15759.448419305327;15798;15828.446004389849;15938.920305398471;16393;16447.342106315733;16587.087710175965;16611.41860034899;17218.139093045327;877.6182360673112;878.3587980774039;908.1826356279336;908.9000209976897;910.0535443139128;912.5619702542783;913.7304495173906;916.5463095767604;916.6425094213229;917.3670167584345;951.2532661860406;952.0859974424561;952.7951490429556;953.6805170267969;953.7520244736384;943.8395472769577;951.1629765806081;952.1097773581689;952.8951815300311;953.4946478366477;697.3026973026973;697.6755808548999;697.7767114523355;697.9895301570481;700.9369156775915;16676.24956876931;16718.832811671848;16761;16766.83233167677;16950.06774627395;15658.06051636894;15729.92135039329;16033.599160020976;16147;16458;13871.791923121162;13891.513797017156;13936.930315348358;14073.859261407357;14118.15291082528;4051.2910240707756;4077.55146933837;4098.262312783698;4109.691773116998;4163.5628259032665;91684.54157729236;91861.54069229611;92114.32799556085;92228;92463.53768231116;12704.174228675107;12872.420741066675;12892.742145157044;12899.161554499115;12920.870791292155;21273.468163296042;21600.891995540234;21670.34988950334;21686.783132168475;21815.67276490854;9257.95371023154;9266.536673166314;9340.813183736424;9401.482918439551;9510.334276600572;5117.616178786606;5205;5211.218317252366;5256.237845512379;5374.5700343972385;4162.146759914211;4242.257604919127;4261.339492378686;4290.785460726951;4306.784660766949;686.1868685607571;689.3175756001523;696.3523922751889;705.7071315404049;713.1905287498727;757.6742000939539;811.6996711216888;835.6573804740149;840.64272684109;841.9031811341774;10323.690289291333;10544.789104217873;10854.29447085937;11218.158638102175;11295.717607059725;720.8846584546448;759.654357267442;774.2721841469001;812.3582369927809;856.9442986205877;5055.848324550269;5280.630355875129;5285.207218917193;5377.085895397791;5494.36814766307;104075;115960;117209.4139529314;117219.41390292994;122495.38752306181;102912.97087029184;103223.48388258011;104209.43685844718;105702.4714876436;108579.45710271556;60479.09281360783;81214.59392702999;81887;93088.53455732678;93168.99999999865;10138.18894488454;10711.571537138427;11024.393658348868;11076.172431549026;11348.886511134782;54483.455165447835;55567.722161388934;55726.44273557213;57132.14301785394;57651;72244.27755722376;72292.63853680698;78928.60535697285;79288;79920.2007979936;14263.928680356532;14335.426582936983;14346.641333966734;14504.274786260856;14547.781783273047;16185.919070404572;16241.67516649684;16343.346266149221;16920.830791691926;17301.653966920843;14349.354279057676;14577;14814.25928703539;15718;15747.055176689435;14219.928900355431;14338.283085845875;14452.205128718022;14765.926170369079;15183.544493665207;14030.158190508602;14373.425062997363;14705.999403044389;15455.459058933333;16042.598935026252;11457.255278406878;11847.348395837967;12177.573784917486;13010.579519833202;13054.934725326313;11895.524179032742;12041.81937270924;12422.944049755872;12603.747925041265;12658.683532911751 01:46:08 INFO - PROCESS | 8239 | __end_tp_report 01:46:08 INFO - PROCESS | 8239 | __start_cc_report 01:46:08 INFO - PROCESS | 8239 | _x_x_mozilla_cycle_collect,333 01:46:08 INFO - PROCESS | 8239 | __end_cc_report 01:46:08 INFO - PROCESS | 8239 | __startTimestamp1474361168033__endTimestamp 01:46:08 INFO - PROCESS | 8239 | 01:46:08 INFO - PROCESS | 8239 | ------- Summary: start ------- 01:46:08 INFO - PROCESS | 8239 | Number of tests: 6 01:46:08 INFO - PROCESS | 8239 | 01:46:08 INFO - PROCESS | 8239 | [#0] dojo.html Cycles:165 Average:5755.44 Median:6003.49 stddev:672.70 (11.2%) stddev-sans-first:645.75 01:46:08 INFO - PROCESS | 8239 | Values: 3263.9 3290.9 3294.7 3305.7 3319.4 5775.4 5782.3 5782.7 5806.2 5809.2 5816.2 5817.3 5817.5 5822.7 5833.3 5219.2 5272.4 5281.5 5296.9 5347.8 5140.6 5169.4 5215.8 5231.4 5266.0 5258.3 5263.3 5264.7 5327.6 5361.9 5311.7 5314.3 5329.8 5333.4 5364.7 5024.4 5096.8 5209.6 5228.2 5290.7 5678.9 5739.3 5739.4 5781.6 5805.2 6141.5 6156.3 6174.6 6187.6 6199.6 5994.7 6081.1 6236.2 6245.5 6263.4 5710.5 5774.6 5802.7 5808.3 5819.7 6281.7 6323.4 6335.3 6361.1 6369.1 5800.1 6000.2 6002.8 6087.9 6096.4 6140.2 6254.1 6255.9 6257.5 6274.9 6184.6 6232.6 6237.6 6244.9 6247.1 6003.5 6005.5 6234.9 6237.1 6240.6 4638.2 4654.1 4660.5 4671.1 4704.6 4186.3 4187.4 4201.0 4214.9 4216.4 5608.2 5785.4 5815.0 5877.6 5878.1 6128.6 6278.6 6300.1 6302.7 6308.9 5892.0 5928.3 5940.3 5940.4 5988.2 5154.1 5246.0 5360.1 5364.9 5376.1 6311.6 6459.1 6470.6 6484.5 6493.9 6225.7 6228.4 6248.4 6265.2 6271.8 6100.4 6256.7 6372.4 6425.7 6485.4 6061.8 6098.0 6115.7 6119.5 6121.7 6194.9 6215.5 6217.7 6244.6 6256.3 5903.8 6026.8 6036.7 6046.4 6075.8 6077.0 6169.7 6171.5 6175.2 6189.4 6081.8 6110.2 6147.6 6166.4 6179.5 5853.8 6025.7 6153.0 6185.5 6233.8 5938.8 6007.3 6027.5 6043.1 6060.7 01:46:08 INFO - PROCESS | 8239 | 01:46:08 INFO - PROCESS | 8239 | [#1] ext.html Cycles:180 Average:62690.25 Median:45328.93 stddev:123855.21 (273.2%) stddev-sans-first:124114.87 01:46:08 INFO - PROCESS | 8239 | Values: 580.4 621.3 653.7 677.9 712.7 42189.6 46126.8 46129.3 46215.8 46475.3 45814.0 46539.3 46637.5 46648.8 46664.5 9095.8 9263.6 9335.8 9336.0 9341.9 9229.1 9271.0 9329.9 9337.3 9355.2 9396.4 9409.9 9417.6 9419.4 9435.8 9263.5 9270.3 9287.5 9335.5 9339.9 9101.2 9165.7 9177.9 9190.7 9212.4 43938.3 44367.0 44377.6 44422.3 44543.0 3723.9 3854.0 4006.9 4196.8 4251.3 34774.5 34903.8 34999.5 35130.5 35382.8 4673.3 4727.0 4738.8 4742.6 4767.3 267138.0 267922.0 269387.0 269556.0 270609.0 63902.4 64114.7 64300.4 64369.0 64448.0 39769.6 68285.3 68457.7 68491.0 68587.0 18539.4 19525.8 19642.5 19654.1 19671.0 15272.2 15311.5 15370.7 15396.5 15401.5 21211.9 21227.3 21534.9 21538.4 21557.8 7751.4 7762.8 7793.4 7940.9 7992.8 1001.4 1064.6 1073.0 1097.7 1126.3 33941.8 34168.0 34382.3 34390.5 34602.5 1178.7 1217.5 1234.6 1263.8 1277.9 17947.1 17961.4 18123.3 18128.5 18246.8 592235.0 593533.0 593571.0 594266.0 594681.0 64822.4 65121.7 65260.3 65520.3 65873.0 1456.8 1478.9 1484.9 1488.1 1518.1 31619.5 32257.0 32420.5 32543.5 32992.2 56038.7 56040.0 56050.2 56212.4 56216.7 472250.0 475172.0 476200.0 476418.0 478327.0 31030.2 31293.4 31373.5 31456.2 31686.7 52355.5 52608.7 52614.5 52635.7 52838.5 44387.8 45052.0 45112.8 45387.5 46293.5 43206.8 44292.3 44620.1 45216.0 45931.5 45043.0 45270.3 46076.8 46191.0 48242.0 38137.0 39268.2 40221.8 40466.6 41088.0 29815.4 30589.0 30669.2 31094.7 31222.8 01:46:08 INFO - PROCESS | 8239 | 01:46:08 INFO - PROCESS | 8239 | [#2] jquery.html Cycles:180 Average:31794.57 Median:1714.93 stddev:94206.53 (5493.3%) stddev-sans-first:94441.27 01:46:08 INFO - PROCESS | 8239 | Values: 383.5 392.3 415.4 418.0 418.4 14630.9 18885.7 19670.1 20610.7 20640.5 19852.9 19948.7 19999.4 20139.2 20180.5 5774.2 5929.0 5951.1 5999.6 6013.4 5680.9 5822.7 5877.7 5881.4 5895.2 5592.7 5840.1 5851.3 5925.0 5940.5 5912.2 5913.5 5929.2 5970.6 5975.1 5919.5 5970.0 6010.6 6045.2 6077.4 14579.8 14715.5 19402.5 23139.4 23930.4 2511.8 2640.8 2710.8 2998.0 3046.4 10303.8 10355.2 10606.8 10743.9 10827.9 1954.8 2061.4 2082.8 2107.2 2136.9 87025.6 87361.0 87444.0 88989.6 89284.6 9341.8 9878.6 9925.7 9959.5 10307.8 14003.2 14081.1 14093.2 14120.7 14155.2 8456.3 8565.7 8695.3 8696.0 8716.9 8823.2 8837.8 8869.2 8991.5 9055.6 4286.5 4300.9 4311.7 4339.7 4382.3 1968.0 2049.9 2063.6 2090.2 2125.7 871.0 1003.3 1004.6 1021.4 1022.2 18070.2 18621.2 18696.3 18890.1 19103.7 946.6 958.4 960.1 976.8 993.5 9836.2 10356.4 10399.6 10633.7 10901.5 501893.0 539034.0 589813.0 600489.0 618629.0 59506.7 99312.0 103867.0 104960.5 108599.0 1101.4 1129.2 1136.4 1150.3 1161.8 18346.7 18494.0 18777.2 18837.9 19033.9 44875.8 45234.0 46404.0 46944.0 47053.3 66205.7 66294.3 67652.7 68217.0 68843.7 7482.8 8192.7 8446.7 9081.6 9389.8 12871.6 13012.9 13018.4 13163.5 13170.3 11372.8 11466.5 11484.8 11641.4 11687.5 11506.9 11547.3 11554.8 11722.7 11805.6 11170.3 11471.4 11484.9 11843.0 11868.0 8362.2 8429.0 8484.0 8514.1 8679.7 8967.6 10999.4 11154.9 11330.3 11642.1 01:46:08 INFO - PROCESS | 8239 | 01:46:08 INFO - PROCESS | 8239 | [#3] mootools.html Cycles:180 Average:6377.02 Median:4484.88 stddev:3677.46 (82.0%) stddev-sans-first:3670.23 01:46:08 INFO - PROCESS | 8239 | Values: 1595.7 1612.9 1808.8 1834.3 1923.1 8462.1 8968.8 9200.9 10073.5 10082.9 8949.3 9276.4 9286.6 9297.3 9301.1 2496.4 2530.0 2554.8 2555.9 2556.1 2471.9 2540.7 2546.6 2554.9 2556.6 2490.3 2491.2 2526.9 2526.9 2531.4 2450.9 2457.1 2471.9 2474.0 2478.3 2449.4 2464.6 2484.0 2487.0 2493.5 9870.0 9927.3 9987.2 9993.3 10013.9 5663.6 5816.5 5848.4 5849.3 5867.3 3613.8 3685.2 3724.6 3726.8 3778.2 1200.3 1246.8 1272.8 1274.6 1285.4 13746.0 13781.4 13831.7 13847.2 13900.7 4776.2 5074.7 5079.1 5102.5 5122.9 11677.9 11684.6 11688.8 11705.1 11736.3 5803.1 5810.7 5812.9 5835.7 5838.3 4798.5 5218.3 5240.9 5296.5 5305.7 4283.2 4363.2 4400.8 4403.9 4414.1 1326.3 1338.0 1346.2 1348.0 1360.8 949.7 1061.6 1064.3 1066.6 1073.3 6375.8 6429.4 6486.1 6528.4 6541.6 492.6 503.3 509.3 511.3 516.8 4430.2 4457.8 4462.2 4466.2 4503.6 11829.6 12086.4 12184.8 12207.8 12213.4 11135.3 11606.7 11726.6 12108.3 12135.9 8896.6 8944.1 8983.3 9072.0 9092.9 6433.2 6723.7 6742.6 6744.2 6859.7 10545.5 10893.3 11143.2 11176.7 11177.3 11070.9 11088.2 11247.8 11257.3 11308.5 9058.6 9060.5 9141.7 9175.2 9331.8 9580.8 9605.7 9613.3 9622.2 9635.7 8302.9 8366.3 8549.1 8688.7 8761.3 7811.4 7968.5 8043.3 8200.3 8206.6 7218.9 7322.7 7383.2 7572.7 7603.8 5637.0 5788.9 5815.9 5855.7 5916.1 5830.9 5865.4 5911.1 6024.0 6067.5 01:46:08 INFO - PROCESS | 8239 | 01:46:08 INFO - PROCESS | 8239 | [#4] prototype.html Cycles:180 Average:5173.27 Median:5900.97 stddev:1556.97 (26.4%) stddev-sans-first:1518.31 01:46:08 INFO - PROCESS | 8239 | Values: 330.4 334.4 340.7 341.5 343.6 5909.6 5954.7 5971.5 5972.1 5987.8 5785.5 5808.8 5813.8 5849.8 5869.8 4335.5 4465.2 4538.8 4556.9 4574.1 4523.2 4538.5 4548.0 4577.7 4589.5 4541.1 4548.1 4558.7 4573.1 4578.1 4300.8 4423.0 4472.0 4485.9 4494.0 3872.7 3883.7 3895.0 3900.4 3913.3 5642.4 5662.9 5702.8 5709.9 5738.8 5034.2 5095.6 5161.4 5166.8 5177.2 6036.4 6099.4 6100.9 6106.3 6140.0 4213.8 4323.5 4345.7 4351.2 4367.3 6235.6 6285.5 6313.1 6324.3 6346.8 4400.0 4412.9 4421.0 4442.6 4443.2 4344.3 4431.2 4442.1 4442.9 4463.8 5091.3 5128.4 5132.2 5143.7 5159.9 6075.3 6096.7 6111.8 6124.7 6133.9 1430.9 1461.2 1487.0 1503.4 1509.0 962.1 970.4 1030.9 1043.2 1058.0 5895.7 5898.9 5903.0 5908.7 5909.6 6222.8 6227.6 6228.2 6281.4 6287.9 5669.2 5810.9 5866.0 5879.5 5887.4 3344.0 3383.1 3408.9 3409.3 3414.6 6278.9 6447.5 6527.0 6585.2 6597.0 6509.3 6527.8 6535.7 6553.8 6568.1 6339.4 6527.9 6690.0 6700.5 6734.2 6376.2 6378.1 6437.6 6443.5 6447.0 6127.8 6346.2 6367.9 6369.3 6395.9 6562.5 6577.4 6612.6 6612.8 6615.4 4463.7 4477.6 4488.7 4489.9 4493.9 6313.3 6346.1 6361.3 6385.2 6475.3 6189.8 6202.6 6224.7 6246.0 6304.1 6010.4 6181.6 6190.4 6202.8 6260.7 6036.2 6179.9 6183.7 6187.6 6236.2 6187.9 6237.2 6250.2 6268.5 6332.0 5886.7 6058.3 6090.3 6116.2 6119.8 01:46:08 INFO - PROCESS | 8239 | 01:46:08 INFO - PROCESS | 8239 | [#5] yui.html Cycles:180 Average:22178.80 Median:4064.42 stddev:31168.29 (766.9%) stddev-sans-first:31214.50 01:46:08 INFO - PROCESS | 8239 | Values: 830.7 878.9 900.2 912.9 951.8 15747.2 15759.4 15798.0 15828.4 15938.9 16393.0 16447.3 16587.1 16611.4 17218.1 877.6 878.4 908.2 908.9 910.1 912.6 913.7 916.5 916.6 917.4 951.3 952.1 952.8 953.7 953.8 943.8 951.2 952.1 952.9 953.5 697.3 697.7 697.8 698.0 700.9 16676.2 16718.8 16761.0 16766.8 16950.1 15658.1 15729.9 16033.6 16147.0 16458.0 13871.8 13891.5 13936.9 14073.9 14118.2 4051.3 4077.6 4098.3 4109.7 4163.6 91684.5 91861.5 92114.3 92228.0 92463.5 12704.2 12872.4 12892.7 12899.2 12920.9 21273.5 21600.9 21670.3 21686.8 21815.7 9258.0 9266.5 9340.8 9401.5 9510.3 5117.6 5205.0 5211.2 5256.2 5374.6 4162.1 4242.3 4261.3 4290.8 4306.8 686.2 689.3 696.4 705.7 713.2 757.7 811.7 835.7 840.6 841.9 10323.7 10544.8 10854.3 11218.2 11295.7 720.9 759.7 774.3 812.4 856.9 5055.8 5280.6 5285.2 5377.1 5494.4 104075.0 115960.0 117209.4 117219.4 122495.4 102913.0 103223.5 104209.4 105702.5 108579.5 60479.1 81214.6 81887.0 93088.5 93169.0 10138.2 10711.6 11024.4 11076.2 11348.9 54483.5 55567.7 55726.4 57132.1 57651.0 72244.3 72292.6 78928.6 79288.0 79920.2 14263.9 14335.4 14346.6 14504.3 14547.8 16185.9 16241.7 16343.3 16920.8 17301.7 14349.4 14577.0 14814.3 15718.0 15747.1 14219.9 14338.3 14452.2 14765.9 15183.5 14030.2 14373.4 14706.0 15455.5 16042.6 11457.3 11847.3 12177.6 13010.6 13054.9 11895.5 12041.8 12422.9 12603.7 12658.7 01:46:08 INFO - PROCESS | 8239 | -------- Summary: end -------- 01:46:08 INFO - PROCESS | 8239 | 01:46:08 INFO - TEST-INFO | 8239: exit 0 01:46:08 INFO - TEST-OK | dromaeo_css | took 1110497ms 01:46:08 INFO - TEST-START | kraken 01:46:08 INFO - operating with platform_type : linux_ 01:46:08 INFO - Initialising browser for kraken test... 01:46:08 INFO - TEST-INFO | started process 8479 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNJXKrq/profile http://localhost:50456/getInfo.html) 01:46:12 INFO - PROCESS | 8479 | __metrics Screen width/height:1600/1200 01:46:12 INFO - PROCESS | 8479 | colorDepth:24 01:46:12 INFO - PROCESS | 8479 | Browser inner width/height: 1024/697 01:46:12 INFO - PROCESS | 8479 | __metrics 01:46:13 ERROR - PROCESS | 8479 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 01:46:14 INFO - TEST-INFO | 8479: exit 0 01:46:14 INFO - Browser initialized. 01:46:14 INFO - Running cycle 1/1 for kraken test... 01:46:14 INFO - TEST-INFO | started process 8605 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNJXKrq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:46:26 INFO - PROCESS | 8605 | RSS: Main: 150216704 01:46:26 INFO - PROCESS | 8605 | 01:46:28 INFO - PROCESS | 8605 | Cycle 1(1): loaded http://localhost:50456/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50456/tests/kraken/driver.html?testName=audio-beat-detection) 01:46:29 INFO - PROCESS | 8605 | RSS: Main: 156545024 01:46:29 INFO - PROCESS | 8605 | 01:46:32 INFO - PROCESS | 8605 | Cycle 1(1): loaded http://localhost:50456/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50456/tests/kraken/driver.html?testName=audio-dft) 01:46:32 INFO - PROCESS | 8605 | RSS: Main: 155246592 01:46:32 INFO - PROCESS | 8605 | 01:46:35 INFO - PROCESS | 8605 | Cycle 1(1): loaded http://localhost:50456/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50456/tests/kraken/driver.html?testName=audio-fft) 01:46:36 INFO - PROCESS | 8605 | RSS: Main: 156000256 01:46:36 INFO - PROCESS | 8605 | 01:46:38 INFO - PROCESS | 8605 | Cycle 1(1): loaded http://localhost:50456/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50456/tests/kraken/driver.html?testName=audio-oscillator) 01:46:38 INFO - PROCESS | 8605 | RSS: Main: 155803648 01:46:38 INFO - PROCESS | 8605 | 01:46:41 INFO - PROCESS | 8605 | Cycle 1(1): loaded http://localhost:50456/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50456/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:46:41 INFO - PROCESS | 8605 | RSS: Main: 156119040 01:46:41 INFO - PROCESS | 8605 | 01:46:44 INFO - PROCESS | 8605 | Cycle 1(1): loaded http://localhost:50456/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50456/tests/kraken/driver.html?testName=imaging-darkroom) 01:46:45 INFO - PROCESS | 8605 | RSS: Main: 156004352 01:46:45 INFO - PROCESS | 8605 | 01:46:48 INFO - PROCESS | 8605 | Cycle 1(1): loaded http://localhost:50456/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50456/tests/kraken/driver.html?testName=imaging-desaturate) 01:46:49 INFO - PROCESS | 8605 | RSS: Main: 156172288 01:46:49 INFO - PROCESS | 8605 | 01:46:52 INFO - PROCESS | 8605 | Cycle 1(1): loaded http://localhost:50456/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50456/tests/kraken/driver.html?testName=json-parse-financial) 01:46:52 INFO - PROCESS | 8605 | RSS: Main: 156692480 01:46:52 INFO - PROCESS | 8605 | 01:46:54 INFO - PROCESS | 8605 | Cycle 1(1): loaded http://localhost:50456/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50456/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:46:55 INFO - PROCESS | 8605 | RSS: Main: 156102656 01:46:55 INFO - PROCESS | 8605 | 01:46:56 INFO - PROCESS | 8605 | Cycle 1(1): loaded http://localhost:50456/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50456/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:46:57 INFO - PROCESS | 8605 | RSS: Main: 156065792 01:46:57 INFO - PROCESS | 8605 | 01:46:59 INFO - PROCESS | 8605 | Cycle 1(1): loaded http://localhost:50456/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50456/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:47:00 INFO - PROCESS | 8605 | RSS: Main: 156680192 01:47:00 INFO - PROCESS | 8605 | 01:47:02 INFO - PROCESS | 8605 | Cycle 1(1): loaded http://localhost:50456/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50456/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:47:03 INFO - PROCESS | 8605 | RSS: Main: 156585984 01:47:03 INFO - PROCESS | 8605 | 01:47:05 INFO - PROCESS | 8605 | Cycle 1(1): loaded http://localhost:50456/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50456/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:47:06 INFO - PROCESS | 8605 | RSS: Main: 157118464 01:47:06 INFO - PROCESS | 8605 | 01:47:08 INFO - PROCESS | 8605 | Cycle 1(1): loaded http://localhost:50456/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50456/tests/kraken/driver.html?testName=ai-astar) 01:47:08 INFO - PROCESS | 8605 | RSS: Main: 158842880 01:47:08 INFO - PROCESS | 8605 | 01:47:08 INFO - PROCESS | 8605 | __start_tp_report 01:47:08 INFO - PROCESS | 8605 | _x_x_mozilla_page_load 01:47:08 INFO - PROCESS | 8605 | _x_x_mozilla_page_load_details 01:47:08 INFO - PROCESS | 8605 | |i|pagename|runs| 01:47:08 INFO - PROCESS | 8605 | |0;ai-astar;103;111;107;111;111;112;112;112;111;114 01:47:08 INFO - PROCESS | 8605 | |1;audio-beat-detection;148;117;141;169;119;153;144;119;180;119 01:47:08 INFO - PROCESS | 8605 | |2;audio-dft;160;163;164;163;162;161;161;160;161;160 01:47:08 INFO - PROCESS | 8605 | |3;audio-fft;75;75;75;178;76;75;76;76;166;76 01:47:08 INFO - PROCESS | 8605 | |4;audio-oscillator;82;83;147;83;83;83;140;84;83;149 01:47:08 INFO - PROCESS | 8605 | |5;imaging-gaussian-blur;118;118;127;117;117;117;117;116;117;117 01:47:08 INFO - PROCESS | 8605 | |6;imaging-darkroom;147;148;148;147;148;147;147;150;147;146 01:47:08 INFO - PROCESS | 8605 | |7;imaging-desaturate;95;95;95;95;94;96;95;96;96;94 01:47:08 INFO - PROCESS | 8605 | |8;json-parse-financial;52;53;54;54;52;54;53;53;52;52 01:47:08 INFO - PROCESS | 8605 | |9;json-stringify-tinderbox;64;66;59;60;60;60;59;62;60;60 01:47:08 INFO - PROCESS | 8605 | |10;stanford-crypto-aes;72;73;72;72;73;74;72;73;80;75 01:47:08 INFO - PROCESS | 8605 | |11;stanford-crypto-ccm;117;131;126;125;117;119;134;118;149;125 01:47:08 INFO - PROCESS | 8605 | |12;stanford-crypto-pbkdf2;151;150;157;155;148;150;151;151;148;148 01:47:08 INFO - PROCESS | 8605 | |13;stanford-crypto-sha256-iterative;63;64;67;62;62;66;65;62;60;61 01:47:08 INFO - PROCESS | 8605 | __end_tp_report 01:47:08 INFO - PROCESS | 8605 | __start_cc_report 01:47:08 INFO - PROCESS | 8605 | _x_x_mozilla_cycle_collect,867 01:47:08 INFO - PROCESS | 8605 | __end_cc_report 01:47:08 INFO - PROCESS | 8605 | __startTimestamp1474361228443__endTimestamp 01:47:08 INFO - PROCESS | 8605 | 01:47:08 INFO - PROCESS | 8605 | ------- Summary: start ------- 01:47:08 INFO - PROCESS | 8605 | Number of tests: 14 01:47:08 INFO - PROCESS | 8605 | 01:47:08 INFO - PROCESS | 8605 | [#0] ai-astar Cycles:10 Average:110.40 Median:111.50 stddev:3.13 (2.8%) stddev-sans-first:1.86 01:47:08 INFO - PROCESS | 8605 | Values: 103.0 111.0 107.0 111.0 111.0 112.0 112.0 112.0 111.0 114.0 01:47:08 INFO - PROCESS | 8605 | 01:47:08 INFO - PROCESS | 8605 | [#1] audio-beat-detection Cycles:10 Average:140.90 Median:146.00 stddev:22.45 (15.4%) stddev-sans-first:23.66 01:47:08 INFO - PROCESS | 8605 | Values: 148.0 117.0 141.0 169.0 119.0 153.0 144.0 119.0 180.0 119.0 01:47:08 INFO - PROCESS | 8605 | 01:47:08 INFO - PROCESS | 8605 | [#2] audio-dft Cycles:10 Average:161.50 Median:161.50 stddev:1.43 (0.9%) stddev-sans-first:1.41 01:47:08 INFO - PROCESS | 8605 | Values: 160.0 163.0 164.0 163.0 162.0 161.0 161.0 160.0 161.0 160.0 01:47:08 INFO - PROCESS | 8605 | 01:47:08 INFO - PROCESS | 8605 | [#3] audio-fft Cycles:10 Average:94.80 Median:75.50 stddev:40.79 (54.0%) stddev-sans-first:42.63 01:47:08 INFO - PROCESS | 8605 | Values: 75.0 75.0 75.0 178.0 76.0 75.0 76.0 76.0 166.0 76.0 01:47:08 INFO - PROCESS | 8605 | 01:47:08 INFO - PROCESS | 8605 | [#4] audio-oscillator Cycles:10 Average:101.70 Median:83.00 stddev:30.20 (36.4%) stddev-sans-first:31.17 01:47:08 INFO - PROCESS | 8605 | Values: 82.0 83.0 147.0 83.0 83.0 83.0 140.0 84.0 83.0 149.0 01:47:08 INFO - PROCESS | 8605 | 01:47:08 INFO - PROCESS | 8605 | [#5] imaging-gaussian-blur Cycles:10 Average:118.10 Median:117.00 stddev:3.18 (2.7%) stddev-sans-first:3.37 01:47:08 INFO - PROCESS | 8605 | Values: 118.0 118.0 127.0 117.0 117.0 117.0 117.0 116.0 117.0 117.0 01:47:08 INFO - PROCESS | 8605 | 01:47:08 INFO - PROCESS | 8605 | [#6] imaging-darkroom Cycles:10 Average:147.50 Median:147.50 stddev:1.08 (0.7%) stddev-sans-first:1.13 01:47:08 INFO - PROCESS | 8605 | Values: 147.0 148.0 148.0 147.0 148.0 147.0 147.0 150.0 147.0 146.0 01:47:08 INFO - PROCESS | 8605 | 01:47:08 INFO - PROCESS | 8605 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.74 (0.8%) stddev-sans-first:0.78 01:47:08 INFO - PROCESS | 8605 | Values: 95.0 95.0 95.0 95.0 94.0 96.0 95.0 96.0 96.0 94.0 01:47:08 INFO - PROCESS | 8605 | 01:47:08 INFO - PROCESS | 8605 | [#8] json-parse-financial Cycles:10 Average:52.90 Median:53.00 stddev:0.88 (1.7%) stddev-sans-first:0.87 01:47:08 INFO - PROCESS | 8605 | Values: 52.0 53.0 54.0 54.0 52.0 54.0 53.0 53.0 52.0 52.0 01:47:08 INFO - PROCESS | 8605 | 01:47:08 INFO - PROCESS | 8605 | [#9] json-stringify-tinderbox Cycles:10 Average:61.00 Median:60.00 stddev:2.31 (3.8%) stddev-sans-first:2.18 01:47:08 INFO - PROCESS | 8605 | Values: 64.0 66.0 59.0 60.0 60.0 60.0 59.0 62.0 60.0 60.0 01:47:08 INFO - PROCESS | 8605 | 01:47:08 INFO - PROCESS | 8605 | [#10] stanford-crypto-aes Cycles:10 Average:73.60 Median:73.00 stddev:2.46 (3.4%) stddev-sans-first:2.54 01:47:08 INFO - PROCESS | 8605 | Values: 72.0 73.0 72.0 72.0 73.0 74.0 72.0 73.0 80.0 75.0 01:47:08 INFO - PROCESS | 8605 | 01:47:08 INFO - PROCESS | 8605 | [#11] stanford-crypto-ccm Cycles:10 Average:126.10 Median:125.50 stddev:9.97 (7.9%) stddev-sans-first:10.02 01:47:08 INFO - PROCESS | 8605 | Values: 117.0 131.0 126.0 125.0 117.0 119.0 134.0 118.0 149.0 125.0 01:47:08 INFO - PROCESS | 8605 | 01:47:08 INFO - PROCESS | 8605 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:150.90 Median:151.00 stddev:3.00 (2.0%) stddev-sans-first:3.18 01:47:08 INFO - PROCESS | 8605 | Values: 151.0 150.0 157.0 155.0 148.0 150.0 151.0 151.0 148.0 148.0 01:47:08 INFO - PROCESS | 8605 | 01:47:08 INFO - PROCESS | 8605 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.20 Median:63.50 stddev:2.25 (3.5%) stddev-sans-first:2.39 01:47:08 INFO - PROCESS | 8605 | Values: 63.0 64.0 67.0 62.0 62.0 66.0 65.0 62.0 60.0 61.0 01:47:08 INFO - PROCESS | 8605 | -------- Summary: end -------- 01:47:08 INFO - PROCESS | 8605 | 01:47:09 INFO - TEST-INFO | 8605: exit 0 01:47:09 INFO - TEST-OK | kraken | took 60671ms 01:47:09 INFO - SUITE-END | took 1171s 01:47:09 INFO - Completed test suite (00:19:31) 01:47:09 INFO - javascript benchmark 01:47:09 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7804.81267330915, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3263.8857639982602, 3290.8683652653895, 3294.654061323561, 3305.6529064448246, 3319.352726218387, 5775.393583673712, 5782.335031471372, 5782.710864456782, 5806.216160818289, 5809.157672137538, 5816.156657284691, 5817.331006934193, 5817.505512031483, 5822.737976791044, 5833.300003999515, 5219.164933610633, 5272.420033796279, 5281.471852814707, 5296.92054619181, 5347.839564813062, 5140.640155189139, 5169.379674439073, 5215.765290561933, 5231.398389185254, 5265.999460102587, 5258.2638430619745, 5263.26314315996, 5264.736763161845, 5327.573794096463, 5361.865953351178, 5311.654742441749, 5314.282571852789, 5329.813456529003, 5333.439988801198, 5364.731763411853, 5024.371953505812, 5096.770645320969, 5209.557187639055, 5228.189630607262, 5290.68255904647, 5678.858028549278, 5739.253896993406, 5739.3973632768375, 5781.566382521328, 5805.187273781632, 6141.477974372184, 6156.29202641693, 6174.567780255384, 6187.5668703190795, 6199.62802231863, 5994.670293133876, 6081.148639190517, 6236.220472440945, 6245.46913512352, 6263.404976527223, 5710.486056254916, 5774.595778295479, 5802.651840889603, 5808.3030036395485, 5819.67991760453, 6281.685915704288, 6323.36766323364, 6335.271443783931, 6361.077643741724, 6369.108324834527, 5800.071988481823, 6000.249968753906, 6002.759889604455, 6087.939120608826, 6096.3903609639565, 6140.232470941132, 6254.124422580842, 6255.937440625627, 6257.499400047986, 6274.937250627527, 6184.5980011299125, 6232.563720539519, 6237.563370564108, 6244.906326405107, 6247.094171345129, 6003.489703375175, 6005.489533389624, 6234.906476402857, 6237.064440333896, 6240.563160578807, 4638.1883170445035, 4654.138984287918, 4660.533946605312, 4671.0891376182, 4704.553067458586, 4186.330187170038, 4187.413762073312, 4201.0, 4214.915701685949, 4216.388623649554, 5608.158776183605, 5785.363610002896, 5815.0, 5877.647341159459, 5878.059510478303, 6128.564234752123, 6278.560500764994, 6300.086487459384, 6302.716377763011, 6308.873822523616, 5891.9705401473575, 5928.318243401977, 5940.287165540163, 5940.405959404025, 5988.221531200916, 5154.123798954187, 5246.0, 5360.115580929181, 5364.865878353073, 5376.086065368819, 6311.621302721851, 6459.09572659828, 6470.6441145737435, 6484.546081774325, 6493.935060649427, 6225.719842607185, 6228.377162283736, 6248.4064013918605, 6265.216847894013, 6271.780487683091, 6100.359462256399, 6256.687165641609, 6372.394622510854, 6425.678716064084, 6485.351464853666, 6061.848453788691, 6098.0, 6115.69342884025, 6119.53301968977, 6121.696863452088, 6194.876102477835, 6215.533834962305, 6217.720202590804, 6244.562880598496, 6256.343083976299, 5903.822885313448, 6026.819195424145, 6036.698165091816, 6046.425589568984, 6075.7569697213385, 6077.027675571887, 6169.660668663087, 6171.537134714825, 6175.228096487939, 6189.442950134509, 6081.847953801191, 6110.175126358063, 6147.600405973598, 6166.445019948226, 6179.474744646575, 5853.795117170878, 6025.66858822769, 6153.0, 6185.474234689922, 6233.844153896008, 5938.792142275, 6007.339192688888, 6027.547933905063, 6043.123747056564, 6060.727267272895], "value": 5708.494426028942, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [580.4137820800989, 621.2638023941629, 653.7189008726248, 677.8576498935223, 712.7184762019, 42189.57810421895, 46126.7693661532, 46129.308060379124, 46215.76892115539, 46475.30287045697, 45814.0, 46539.30191047137, 46637.533624663745, 46648.76675616617, 46664.53335466644, 9095.81808363834, 9263.583138758739, 9335.76660583484, 9335.95332023339, 9341.906580934172, 9229.12323329286, 9271.0, 9329.906700933007, 9337.346385753, 9355.158035776778, 9396.389234699722, 9409.858852117224, 9417.576209070574, 9419.387739796926, 9435.811283774321, 9263.536823158815, 9270.304727145458, 9287.489188094652, 9335.533223338805, 9339.859902101474, 9101.226395756368, 9165.725028249164, 9177.862332065024, 9190.678326258616, 9212.401193922373, 43938.34092488615, 44367.0, 44377.55622443766, 44422.33366499505, 44543.0, 3723.8696645617542, 3853.9614603853884, 4006.9198616027807, 4196.790160491963, 4251.298535741599, 34774.47838282428, 34903.825480872685, 34999.475007874906, 35130.47304290438, 35382.8230858844, 4673.299005149221, 4726.976365118187, 4738.810447582093, 4742.5731684148595, 4767.284907263904, 267138.0, 267922.0, 269387.0, 269556.0, 270609.0, 63902.36097639057, 64114.67942660257, 64300.35699642944, 64369.0, 64448.0, 39769.60230397717, 68285.31714682789, 68457.65771171212, 68491.0, 68587.0, 18539.35112271064, 19525.80474195268, 19642.508937276398, 19654.115564799617, 19671.0, 15272.160031198391, 15311.464098756713, 15370.692586148438, 15396.46112386061, 15401.460948866736, 21211.893940530197, 21227.257046003317, 21534.892325538272, 21538.353849384544, 21557.78442215558, 7751.37988960893, 7762.767116986386, 7793.376529877596, 7940.920590794133, 7992.80017999543, 1001.399160503692, 1064.6220591689994, 1073.0342691577644, 1097.6981330134277, 1126.3298337489182, 33941.83029084839, 34168.0, 34382.312353752786, 34390.48414273788, 34602.48096278558, 1178.7112157521462, 1217.4886547650008, 1234.6296111166614, 1263.810428435742, 1277.9424925878359, 17947.102644867966, 17961.37135200288, 18123.275068997355, 18128.546786330448, 18246.817531824512, 592235.0, 593533.0, 593571.0, 594266.0, 594681.0, 64822.351776482574, 65121.67439162774, 65260.34739652638, 65520.34479655143, 65873.0, 1456.7523521001244, 1478.8742956848573, 1484.8515148485064, 1488.0655148307158, 1518.1119045358369, 31619.525707113953, 32257.0, 32420.513692294164, 32543.51184732278, 32992.1751956203, 56038.71980640071, 56040.0, 56050.15924761214, 56212.43787562072, 56216.71891640679, 472250.0, 475172.0, 476200.0, 476418.0, 478327.0, 31030.22424439317, 31293.374132517678, 31373.529397058606, 31456.213594660316, 31686.68313316837, 52355.476445236585, 52608.73695631498, 52614.47385526249, 52635.73682131718, 52838.47161528489, 44387.778061109486, 45052.0, 45112.77443612761, 45387.546124538334, 46293.53706462892, 43206.783966079965, 44292.33561496516, 44620.10759784851, 45216.0, 45931.54068459406, 45043.0, 45270.32094518519, 46076.76961615305, 46191.0, 48242.0, 38137.0, 39268.21463570656, 40221.79889100536, 40466.59533404628, 41088.0, 29815.403691926473, 30589.0, 30669.159850409047, 31094.689053110083, 31222.843885780425], "value": 21638.280376453622, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [383.4822988964896, 392.2566736035215, 415.389377614906, 418.0, 418.372441337993, 14630.926845365784, 18885.71671424928, 19670.11484483198, 20610.690839637417, 20640.48398790031, 19852.900735496303, 19948.70076948847, 19999.400017999484, 20139.194432222732, 20180.495487612818, 5774.16274640178, 5929.0, 5951.077582974646, 5999.640021598696, 6013.36859629737, 5680.886382272352, 5822.7088645567765, 5877.7355019024035, 5881.382454842244, 5895.233619629442, 5592.720363981805, 5840.06558950568, 5851.327097383792, 5924.992751232301, 5940.465358117768, 5912.201852749867, 5913.526917846562, 5929.199558059693, 5970.552208584373, 5975.1037344398255, 5919.526437884959, 5970.044792833126, 6010.639361638316, 6045.244344456943, 6077.36187700294, 14579.781303280459, 14715.484958026418, 19402.514937126685, 23139.421514462105, 23930.401739956465, 2511.824172307939, 2640.7755340796057, 2710.7560319571135, 2997.985010074936, 3046.375493023947, 10303.845442318372, 10355.223358248086, 10606.78786424275, 10743.892561074368, 10827.945860270649, 1954.7654281486314, 2061.4228016155503, 2082.822960048398, 2107.1676687708423, 2136.8931553422267, 87025.56487217524, 87361.0, 87444.0, 88989.55505222497, 89284.5535772317, 9341.766455838522, 9878.555465004092, 9925.65260215889, 9959.452230127268, 10307.793844123225, 14003.159810411407, 14081.084729492755, 14093.15441073539, 14120.71758564823, 14155.150690958575, 8456.281216096577, 8565.657373705106, 8695.260902823204, 8695.99737988669, 8716.869246961302, 8823.161799629153, 8837.823243535093, 8869.246114080375, 8991.460512369278, 9055.59249833759, 4286.485621725413, 4300.89247768802, 4311.676624253194, 4339.674524410619, 4382.298832186835, 1967.9606407871763, 2049.866758660682, 2063.597598468314, 2090.226616152003, 2125.7342832145982, 870.9825803483894, 1003.2976916158718, 1004.5780772075746, 1021.4382089850552, 1022.1618273016204, 18070.186841591898, 18621.162047707887, 18696.252149914122, 18890.055497225083, 19103.713444298348, 946.5645802930729, 958.4105774948357, 960.1406740966935, 976.8485884687884, 993.5479356892597, 9836.21310295189, 10356.430396328124, 10399.636012739518, 10633.73415664599, 10901.509432075576, 501893.0, 539034.0, 589813.0, 600489.0, 618629.0, 59506.70246648739, 99312.0, 103867.0, 104960.47519762353, 108599.0, 1101.4217535793773, 1129.170060005895, 1136.3693150301497, 1150.3213104268523, 1161.79668558002, 18346.724799127758, 18494.0, 18777.248910043447, 18837.90581047141, 19033.90483047576, 44875.775621121684, 45234.0, 46404.0, 46944.0, 47053.29420058634, 66205.66897165483, 66294.33705662882, 67652.661736691, 68217.0, 68843.65578172076, 7482.812929676802, 8192.713255036046, 8446.704365347183, 9081.591328390106, 9389.765255868659, 12871.613851584467, 13012.869871301165, 13018.414171362121, 13163.47346106145, 13170.275634839792, 11372.829407559058, 11466.484008219484, 11484.82772758426, 11641.359725215194, 11687.474063666985, 11506.884931150582, 11547.307161570332, 11554.76890462203, 11722.70693232676, 11805.645830625095, 11170.27393219438, 11471.426428678365, 11484.94257528707, 11842.993345565723, 11868.0, 8362.205590468895, 8429.0, 8484.0, 8514.10601886793, 8679.652813887375, 8967.551622418983, 10999.395033273247, 11154.944225279147, 11330.263532870336, 11642.126840486828], "value": 9415.077351541475, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1595.6649103688226, 1612.9354825806968, 1808.8191180881913, 1834.339637730416, 1923.0865338963986, 8462.069172391031, 8968.775780605472, 9200.907990920088, 10073.496325183729, 10082.899171008288, 8949.328800339985, 9276.397034192772, 9286.582103805327, 9297.25621950244, 9301.116393942582, 2496.41334286443, 2530.02594001309, 2554.8211625186245, 2555.9488810223784, 2556.1309154887335, 2471.938201544958, 2540.745925407454, 2546.63073854291, 2554.859482728449, 2556.6292887531295, 2490.340059884132, 2491.215267190838, 2526.873656317186, 2526.873656317186, 2531.4177739120014, 2450.9387265318333, 2457.066314800373, 2471.851688898663, 2474.0474917156844, 2478.2689106713565, 2449.35092200567, 2464.581021226396, 2483.9751602483925, 2486.962695559568, 2493.463905260378, 9869.950650246774, 9927.255455840768, 9987.201023918069, 9993.250506212064, 10013.949930250375, 5663.603547751659, 5816.534677225812, 5848.356680765112, 5849.268841394825, 5867.295924489089, 3613.8074435436242, 3685.1708365617787, 3724.5902950675404, 3726.757760745543, 3778.2443511297574, 1200.333814732823, 1246.7818131826893, 1272.8090786382024, 1274.6048724895313, 1285.395863943945, 13745.9999999999, 13781.379837907318, 13831.723365532735, 13847.238401887893, 13900.652483687887, 4776.188048031843, 5074.67014644047, 5079.111155547765, 5102.540771330524, 5122.871928201825, 11677.941610291895, 11684.591039313584, 11688.766224675459, 11705.063594912386, 11736.295822250693, 5803.129530570448, 5810.6513609183585, 5812.941870581325, 5835.679037652885, 5838.299404071538, 4798.47216806151, 5218.347706536683, 5240.947590524046, 5296.549793267615, 5305.734713264322, 4283.186194623012, 4363.214621368184, 4400.845970391021, 4403.889902752393, 4414.050979039522, 1326.323574976769, 1337.9866201338057, 1346.1788309131423, 1348.0496250143626, 1360.816289800884, 949.6866034208695, 1061.5541472581535, 1064.297563608015, 1066.6320119558895, 1073.2809017957986, 6375.808725738235, 6429.3570642936265, 6486.124373209556, 6528.412442880163, 6541.640209788412, 492.6354497671697, 503.3205173016399, 509.2768269057947, 511.27654369068097, 516.8449465160437, 4430.180416622935, 4457.840961350073, 4462.174497717932, 4466.151431228056, 4503.617192538605, 11829.585964491202, 12086.395680215954, 12184.75630487403, 12207.816882746765, 12213.389330533439, 11135.33188008722, 11606.709832254091, 11726.589569365202, 12108.334041627619, 12135.939320303341, 8896.644134234688, 8944.060873608178, 8983.281337492985, 9072.04743501936, 9092.909070909207, 6433.228012638513, 6723.697433615401, 6742.561733487308, 6744.156980377453, 6859.657017149222, 10545.472726363805, 10893.346399216072, 11143.219974602025, 11176.720581985515, 11177.329360238411, 11070.889291106985, 11088.223824332219, 11247.831282530606, 11257.268277561932, 11308.529891114102, 9058.637654493747, 9060.54697265121, 9141.680041198526, 9175.174234318943, 9331.766705832137, 9580.808383832145, 9605.711828645151, 9613.279004074582, 9622.230221582116, 9635.710928672152, 8302.87545686803, 8366.288865446506, 8549.059603443491, 8688.652453902027, 8761.255293299886, 7811.414143939113, 7968.521888686697, 8043.316318112792, 8200.26197642215, 8206.630701618322, 7218.927810722035, 7322.670479828314, 7383.150937642025, 7572.697092116254, 7603.809904752205, 5637.049142868374, 5788.884222315614, 5815.883682326415, 5855.711743416442, 5916.142159386779, 5830.854228644318, 5865.384134665798, 5911.083782013795, 6023.969880150571, 6067.4539291462215], "value": 4999.0808161997065, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [330.3871318703804, 334.3980834497904, 340.7001838382223, 341.528690407238, 343.5791155834102, 5909.645421274721, 5954.70226488676, 5971.4625683688455, 5972.134040564116, 5987.79042733505, 5785.466851284413, 5808.767649294034, 5813.767449302033, 5849.795257166, 5869.794557190499, 4335.544767799383, 4465.173942820588, 4538.7503687297185, 4556.908861822778, 4574.130915126132, 4523.163214805255, 4538.523455037223, 4547.977260113711, 4577.679562430631, 4589.5410458954, 4541.137183935059, 4548.135854187711, 4558.680892337537, 4573.056451008309, 4578.10726908253, 4300.827966881321, 4422.955770442302, 4472.038511719996, 4485.945802736354, 4494.0, 3872.7095467840027, 3883.650471457554, 3894.955148547007, 3900.4344370066183, 3913.3347330953807, 5642.379338272786, 5662.858428539278, 5702.800401985952, 5709.857253568653, 5738.770449182028, 5034.194528875399, 5095.592352611782, 5161.354830646169, 5166.844994650167, 5177.171652535614, 6036.366181550918, 6099.390060993909, 6100.877982440371, 6106.328303886569, 6140.0, 4213.820130363503, 4323.524412314643, 4345.67407444443, 4351.238533256667, 4367.279398899178, 6235.625862448268, 6285.465735412496, 6313.116163737034, 6324.272708638474, 6346.809595712136, 4399.956000439955, 4412.867613971586, 4421.0, 4442.577955094261, 4443.200223959719, 4344.326629372453, 4431.180231657089, 4442.111577684412, 4442.8667139985855, 4463.754493502824, 5091.287219789232, 5128.410232823273, 5132.204508301219, 5143.665661731976, 5159.864829737452, 6075.3317135115985, 6096.664683442365, 6111.777644471034, 6124.663143527059, 6133.877322453526, 1430.9426483339528, 1461.167134733192, 1487.0, 1503.4437258214311, 1508.9773653395207, 962.1436921140191, 970.4225985538593, 1030.8917563655857, 1043.1967385113421, 1058.0107599394437, 5895.734691938873, 5898.94101058984, 5903.0, 5908.704564771744, 5909.6158749681135, 6222.782202622887, 6227.626342419469, 6228.190335256388, 6281.434670879643, 6287.937120628827, 5669.234653321749, 5810.912836307459, 5866.0, 5879.470847623735, 5887.411258874164, 3343.963371354888, 3383.1392733996695, 3408.9318213636084, 3409.284050349405, 3414.6097731587492, 6278.874422511615, 6447.548671592944, 6526.967365163238, 6585.209774827052, 6597.010448432773, 6509.2839787623325, 6527.804165875032, 6535.738570457129, 6553.770618028346, 6568.146141001735, 6339.415146213447, 6527.934720652733, 6689.966550167218, 6700.530962832555, 6734.225564060098, 6376.171097757262, 6378.138951241523, 6437.581557198767, 6443.452306553995, 6447.0, 6127.84680382994, 6346.174997250328, 6367.93632063692, 6369.267534233437, 6395.904061439175, 6562.5406221564035, 6577.375149360803, 6612.636305003271, 6612.8346791330605, 6615.37153970372, 4463.665225108065, 4477.567178502848, 4488.663350248679, 4489.91020179588, 4493.865184044484, 6313.273973493107, 6346.143270658402, 6361.268454127649, 6385.23377194721, 6475.287718350884, 6189.752409903554, 6202.627842329474, 6224.692814509004, 6246.0, 6304.148939893055, 6010.398960104129, 6181.567290289817, 6190.350013248544, 6202.782902598387, 6260.74957001733, 6036.185115009417, 6179.876402472015, 6183.659898705614, 6187.628742275478, 6236.18929539157, 6187.938120618916, 6237.1891654084675, 6250.1874756281395, 6268.4671802897265, 6332.0, 5886.705664716833, 6058.273007238983, 6090.330063692902, 6116.204893364013, 6119.816405507842], "value": 4663.218007782617, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [830.6926437218226, 878.8681697745337, 900.1718419054471, 912.9406588571746, 951.8001219743853, 15747.212639368043, 15759.448419305327, 15798.0, 15828.446004389849, 15938.920305398471, 16393.0, 16447.342106315733, 16587.087710175965, 16611.41860034899, 17218.139093045327, 877.6182360673112, 878.3587980774039, 908.1826356279336, 908.9000209976897, 910.0535443139128, 912.5619702542783, 913.7304495173906, 916.5463095767604, 916.6425094213229, 917.3670167584345, 951.2532661860406, 952.0859974424561, 952.7951490429556, 953.6805170267969, 953.7520244736384, 943.8395472769577, 951.1629765806081, 952.1097773581689, 952.8951815300311, 953.4946478366477, 697.3026973026973, 697.6755808548999, 697.7767114523355, 697.9895301570481, 700.9369156775915, 16676.24956876931, 16718.832811671848, 16761.0, 16766.83233167677, 16950.06774627395, 15658.06051636894, 15729.92135039329, 16033.599160020976, 16147.0, 16458.0, 13871.791923121162, 13891.513797017156, 13936.930315348358, 14073.859261407357, 14118.15291082528, 4051.2910240707756, 4077.55146933837, 4098.262312783698, 4109.691773116998, 4163.5628259032665, 91684.54157729236, 91861.54069229611, 92114.32799556085, 92228.0, 92463.53768231116, 12704.174228675107, 12872.420741066675, 12892.742145157044, 12899.161554499115, 12920.870791292155, 21273.468163296042, 21600.891995540234, 21670.34988950334, 21686.783132168475, 21815.67276490854, 9257.95371023154, 9266.536673166314, 9340.813183736424, 9401.482918439551, 9510.334276600572, 5117.616178786606, 5205.0, 5211.218317252366, 5256.237845512379, 5374.5700343972385, 4162.146759914211, 4242.257604919127, 4261.339492378686, 4290.785460726951, 4306.784660766949, 686.1868685607571, 689.3175756001523, 696.3523922751889, 705.7071315404049, 713.1905287498727, 757.6742000939539, 811.6996711216888, 835.6573804740149, 840.64272684109, 841.9031811341774, 10323.690289291333, 10544.789104217873, 10854.29447085937, 11218.158638102175, 11295.717607059725, 720.8846584546448, 759.654357267442, 774.2721841469001, 812.3582369927809, 856.9442986205877, 5055.848324550269, 5280.630355875129, 5285.207218917193, 5377.085895397791, 5494.36814766307, 104075.0, 115960.0, 117209.4139529314, 117219.41390292994, 122495.38752306181, 102912.97087029184, 103223.48388258011, 104209.43685844718, 105702.4714876436, 108579.45710271556, 60479.09281360783, 81214.59392702999, 81887.0, 93088.53455732678, 93168.99999999865, 10138.18894488454, 10711.571537138427, 11024.393658348868, 11076.172431549026, 11348.886511134782, 54483.455165447835, 55567.722161388934, 55726.44273557213, 57132.14301785394, 57651.0, 72244.27755722376, 72292.63853680698, 78928.60535697285, 79288.0, 79920.2007979936, 14263.928680356532, 14335.426582936983, 14346.641333966734, 14504.274786260856, 14547.781783273047, 16185.919070404572, 16241.67516649684, 16343.346266149221, 16920.830791691926, 17301.653966920843, 14349.354279057676, 14577.0, 14814.25928703539, 15718.0, 15747.055176689435, 14219.928900355431, 14338.283085845875, 14452.205128718022, 14765.926170369079, 15183.544493665207, 14030.158190508602, 14373.425062997363, 14705.999403044389, 15455.459058933333, 16042.598935026252, 11457.255278406878, 11847.348395837967, 12177.573784917486, 13010.579519833202, 13054.934725326313, 11895.524179032742, 12041.81937270924, 12422.944049755872, 12603.747925041265, 12658.683532911751], "value": 8336.625771260902, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1497.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 111.0, 107.0, 111.0, 111.0, 112.0, 112.0, 112.0, 111.0, 114.0], "value": 110.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 117.0, 141.0, 169.0, 119.0, 153.0, 144.0, 119.0, 180.0, 119.0], "value": 140.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 163.0, 164.0, 163.0, 162.0, 161.0, 161.0, 160.0, 161.0, 160.0], "value": 161.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 178.0, 76.0, 75.0, 76.0, 76.0, 166.0, 76.0], "value": 94.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 147.0, 83.0, 83.0, 83.0, 140.0, 84.0, 83.0, 149.0], "value": 101.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 127.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0], "value": 118.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 148.0, 147.0, 148.0, 147.0, 147.0, 150.0, 147.0, 146.0], "value": 147.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 94.0, 96.0, 95.0, 96.0, 96.0, 94.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 53.0, 54.0, 54.0, 52.0, 54.0, 53.0, 53.0, 52.0, 52.0], "value": 52.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 66.0, 59.0, 60.0, 60.0, 60.0, 59.0, 62.0, 60.0, 60.0], "value": 61.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 73.0, 72.0, 72.0, 73.0, 74.0, 72.0, 73.0, 80.0, 75.0], "value": 73.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 131.0, 126.0, 125.0, 117.0, 119.0, 134.0, 118.0, 149.0, 125.0], "value": 126.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 150.0, 157.0, 155.0, 148.0, 150.0, 151.0, 151.0, 148.0, 148.0], "value": 150.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 67.0, 62.0, 62.0, 66.0, 65.0, 62.0, 60.0, 61.0], "value": 63.2, "unit": "score"}]}]} 01:47:09 INFO - Return code: 0 01:47:09 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 01:47:09 ERROR - # TBPL SUCCESS # 01:47:09 INFO - Running post-action listener: _resource_record_post_action 01:47:09 INFO - [mozharness: 2016-09-20 08:47:09.766907Z] Finished run-tests step (success) 01:47:09 INFO - Running post-run listener: _resource_record_post_run 01:47:09 INFO - Total resource usage - Wall time: 1185s; CPU: 13.0%; Read bytes: 4096; Write bytes: 489050112; Read time: 16; Write time: 420308 01:47:09 INFO - TinderboxPrint: CPU usage
13.2% 01:47:09 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 01:47:09 INFO - TinderboxPrint: I/O write bytes / time
489,050,112 / 420,308 01:47:09 INFO - TinderboxPrint: CPU idle
8,210.9 (86.8%) 01:47:09 INFO - TinderboxPrint: CPU user
1,191.6 (12.6%) 01:47:09 INFO - TinderboxPrint: Swap in / out
0 / 0 01:47:09 INFO - install - Wall time: 14s; CPU: 14.0%; Read bytes: 0; Write bytes: 260349952; Read time: 0; Write time: 287876 01:47:09 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 4096; Write bytes: 228700160; Read time: 16; Write time: 132432 01:47:10 INFO - Running post-run listener: _upload_blobber_files 01:47:10 INFO - Blob upload gear active. 01:47:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 01:47:10 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 01:47:10 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'] 01:47:10 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 01:47:10 INFO - (blobuploader) - INFO - Open directory for files ... 01:47:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 01:47:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:47:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:47:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 01:47:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:47:11 INFO - (blobuploader) - INFO - Done attempting. 01:47:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 01:47:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:47:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:47:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 01:47:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:47:15 INFO - (blobuploader) - INFO - Done attempting. 01:47:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 01:47:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:47:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:47:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 01:47:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:47:15 INFO - (blobuploader) - INFO - Done attempting. 01:47:15 INFO - (blobuploader) - INFO - Iteration through files over. 01:47:15 INFO - Return code: 0 01:47:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 01:47:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 01:47:15 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/40ca40b8d0d64024412ed8a0da6b5435727a3730719d0aa2ef9f3f2dd15f1613ad57fe6e06dd483b5134d02a2ac8304019b337c4513f6c9601c2c6e4f028a48c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8f6d37528d1c589fde9459b64e61eccc70da36147a4cb9888d911e3d89675876f31ecb2742d4f7cf17edd552e5d58f506c350a8930a84b03c2390a55ba35db5e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9f75c32d20e63b74bcb716948306033e4c1b32c1e297d140e3492088097c29b6db368fe494a06faa155e866a2ed158ee5491bb6d72b2bd29cc18edc31869e8d6"} 01:47:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 01:47:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 01:47:15 INFO - Contents: 01:47:15 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/40ca40b8d0d64024412ed8a0da6b5435727a3730719d0aa2ef9f3f2dd15f1613ad57fe6e06dd483b5134d02a2ac8304019b337c4513f6c9601c2c6e4f028a48c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8f6d37528d1c589fde9459b64e61eccc70da36147a4cb9888d911e3d89675876f31ecb2742d4f7cf17edd552e5d58f506c350a8930a84b03c2390a55ba35db5e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9f75c32d20e63b74bcb716948306033e4c1b32c1e297d140e3492088097c29b6db368fe494a06faa155e866a2ed158ee5491bb6d72b2bd29cc18edc31869e8d6"} 01:47:15 INFO - Running post-run listener: copy_logs_to_upload_dir 01:47:15 INFO - Copying logs to upload dir... 01:47:15 INFO - mkdir: /builds/slave/test/build/upload/logs 01:47:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1219.919845 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 19 secs) (at 2016-09-20 01:47:15.314614) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-20 01:47:15.318151) ========= 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=6dbc10da6d83fee962c624be000001a8-1474360014.376953-62072691 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/40ca40b8d0d64024412ed8a0da6b5435727a3730719d0aa2ef9f3f2dd15f1613ad57fe6e06dd483b5134d02a2ac8304019b337c4513f6c9601c2c6e4f028a48c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8f6d37528d1c589fde9459b64e61eccc70da36147a4cb9888d911e3d89675876f31ecb2742d4f7cf17edd552e5d58f506c350a8930a84b03c2390a55ba35db5e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9f75c32d20e63b74bcb716948306033e4c1b32c1e297d140e3492088097c29b6db368fe494a06faa155e866a2ed158ee5491bb6d72b2bd29cc18edc31869e8d6"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474323317/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011276 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1474323317/firefox-50.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/40ca40b8d0d64024412ed8a0da6b5435727a3730719d0aa2ef9f3f2dd15f1613ad57fe6e06dd483b5134d02a2ac8304019b337c4513f6c9601c2c6e4f028a48c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8f6d37528d1c589fde9459b64e61eccc70da36147a4cb9888d911e3d89675876f31ecb2742d4f7cf17edd552e5d58f506c350a8930a84b03c2390a55ba35db5e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9f75c32d20e63b74bcb716948306033e4c1b32c1e297d140e3492088097c29b6db368fe494a06faa155e866a2ed158ee5491bb6d72b2bd29cc18edc31869e8d6"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-20 01:47:15.343550) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 01:47:15.343885) ========= 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=6dbc10da6d83fee962c624be000001a8-1474360014.376953-62072691 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004569 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 01:47:15.427421) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-20 01:47:15.427740) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-20 01:47:15.428022) ========= ========= Total master_lag: 0.15 =========