builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-006 starttime: 1474360021.31 results: success (0) buildid: 20160920012102 builduid: d02ed13ddd8a49f38e24cf380871a043 revision: 4376f2389699910ebe72e39977d563c77a094a1d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-20 01:27:01.308955) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-20 01:27:01.309480) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-20 01:27:01.309821) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-20 01:27:01.325103) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 01:27:01.325516) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1474360020.566327-1268571996 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-20 01:27:01-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 227M=0s 2016-09-20 01:27:01 (227 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.077448 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 01:27:01.417986) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 01:27:01.418350) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1474360020.566327-1268571996 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014746 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 01:27:01.469989) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 01:27:01.470394) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1474360020.566327-1268571996 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-20 01:27:01,623 truncating revision to first 12 chars 2016-09-20 01:27:01,623 Setting DEBUG logging. 2016-09-20 01:27:01,623 attempt 1/10 2016-09-20 01:27:01,623 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:27:01,926 unpacking tar archive at: mozilla-beta-4376f2389699/testing/mozharness/ program finished with exit code 0 elapsedTime=0.729291 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 01:27:02.214668) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-20 01:27:02.215039) ========= script_repo_revision: 4376f2389699910ebe72e39977d563c77a094a1d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-20 01:27:02.215518) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-20 01:27:02.215850) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-20 01:27:02.226437) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 20 secs) (at 2016-09-20 01:27:02.226928) ========= /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=e31afd3c0f0de4e86ebe4d94000001b5-1474360020.566327-1268571996 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 01:27:02 INFO - MultiFileLogger online at 20160920 01:27:02 in /builds/slave/test 01:27:02 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:27:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:27:02 INFO - {'append_to_log': False, 01:27:02 INFO - 'base_work_dir': '/builds/slave/test', 01:27:02 INFO - 'blob_upload_branch': 'Mozilla-Beta', 01:27:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:27:02 INFO - 'branch': 'Mozilla-Beta', 01:27:02 INFO - 'buildbot_json_path': 'buildprops.json', 01:27:02 INFO - 'config_files': ('talos/linux_config.py',), 01:27:02 INFO - 'default_actions': ('clobber', 01:27:02 INFO - 'read-buildbot-config', 01:27:02 INFO - 'download-and-extract', 01:27:02 INFO - 'populate-webroot', 01:27:02 INFO - 'create-virtualenv', 01:27:02 INFO - 'install', 01:27:02 INFO - 'run-tests'), 01:27:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:27:02 INFO - 'download_minidump_stackwalk': True, 01:27:02 INFO - 'download_symbols': 'ondemand', 01:27:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:27:02 INFO - 'tooltool.py': '/tools/tooltool.py', 01:27:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:27:02 INFO - '/tools/misc-python/virtualenv.py')}, 01:27:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:27:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:27:02 INFO - 'installer_path': 'installer.exe', 01:27:02 INFO - 'log_level': 'info', 01:27:02 INFO - 'log_name': 'talos', 01:27:02 INFO - 'log_to_console': True, 01:27:02 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 01:27:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 01:27:02 INFO - 'opt_config_files': (), 01:27:02 INFO - 'pip_index': False, 01:27:02 INFO - 'sps_profile': False, 01:27:02 INFO - 'sps_profile_interval': 0, 01:27:02 INFO - 'suite': 'dromaeojs-e10s', 01:27:02 INFO - 'system_bits': '32', 01:27:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:27:02 INFO - 'title': 'talos-linux64-ix-006', 01:27:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:27:02 INFO - 'use_talos_json': True, 01:27:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:27:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:27:02 INFO - 'work_dir': 'build'} 01:27:02 INFO - [mozharness: 2016-09-20 08:27:02.470671Z] Running clobber step. 01:27:02 INFO - Running pre-action listener: _resource_record_pre_action 01:27:02 INFO - Running main action method: clobber 01:27:02 INFO - rmtree: /builds/slave/test/build 01:27:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:27:03 INFO - Running post-action listener: _resource_record_post_action 01:27:03 INFO - [mozharness: 2016-09-20 08:27:03.446946Z] Finished clobber step (success) 01:27:03 INFO - [mozharness: 2016-09-20 08:27:03.447047Z] Running read-buildbot-config step. 01:27:03 INFO - Running pre-action listener: _resource_record_pre_action 01:27:03 INFO - Running main action method: read_buildbot_config 01:27:03 INFO - Using buildbot properties: 01:27:03 INFO - { 01:27:03 INFO - "project": "", 01:27:03 INFO - "product": "firefox", 01:27:03 INFO - "script_repo_revision": "production", 01:27:03 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s", 01:27:03 INFO - "repository": "", 01:27:03 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs-e10s", 01:27:03 INFO - "buildid": "20160920012102", 01:27:03 INFO - "stage_platform": "linux64-pgo", 01:27:03 INFO - "basedir": "/builds/slave/test", 01:27:03 INFO - "buildnumber": 241, 01:27:03 INFO - "platform": "ubuntu64_hw", 01:27:03 INFO - "mozci_request": { 01:27:03 INFO - "priority": -1, 01:27:03 INFO - "type": "trigger_all_talos_jobs", 01:27:03 INFO - "times": 6 01:27:03 INFO - }, 01:27:03 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 01:27:03 INFO - "slavebuilddir": "test", 01:27:03 INFO - "branch": "mozilla-beta", 01:27:03 INFO - "revision": "4376f2389699910ebe72e39977d563c77a094a1d", 01:27:03 INFO - "slavename": "talos-linux64-ix-006", 01:27:03 INFO - "builduid": "d02ed13ddd8a49f38e24cf380871a043", 01:27:03 INFO - "repo_path": "releases/mozilla-beta" 01:27:03 INFO - } 01:27:03 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:27:03 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:27:03 INFO - Running post-action listener: _resource_record_post_action 01:27:03 INFO - [mozharness: 2016-09-20 08:27:03.449803Z] Finished read-buildbot-config step (success) 01:27:03 INFO - [mozharness: 2016-09-20 08:27:03.449892Z] Running download-and-extract step. 01:27:03 INFO - Running pre-action listener: _resource_record_pre_action 01:27:03 INFO - Running main action method: download_and_extract 01:27:03 INFO - mkdir: /builds/slave/test/build/tests 01:27:03 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:03 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:27:03 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:27:03 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:27:03 INFO - Downloaded 1327 bytes. 01:27:03 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 01:27:03 INFO - Using the following test package requirements: 01:27:03 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 01:27:03 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 01:27:03 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 01:27:03 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 01:27:03 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 01:27:03 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 01:27:03 INFO - u'jsshell-linux-x86_64.zip'], 01:27:03 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 01:27:03 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 01:27:03 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 01:27:03 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 01:27:03 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 01:27:03 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 01:27:03 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 01:27:03 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 01:27:03 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 01:27:03 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 01:27:03 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 01:27:03 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 01:27:03 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:03 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:27:03 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:27:03 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:27:03 INFO - Downloaded 21771354 bytes. 01:27:03 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:27:03 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:04 INFO - Return code: 0 01:27:04 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: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 - 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:27:04 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:27:04 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:27:05 INFO - Downloaded 21771354 bytes. 01:27:05 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:27:05 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:06 INFO - Return code: 0 01:27:06 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:06 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:06 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:06 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:06 INFO - Downloaded 14413740 bytes. 01:27:06 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:06 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:07 INFO - Return code: 0 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 - 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:07 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:07 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:07 INFO - Downloaded 56770127 bytes. 01:27:07 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:07 INFO - mkdir: /builds/slave/test/properties 01:27:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:27:07 INFO - Writing to file /builds/slave/test/properties/build_url 01:27:07 INFO - Contents: 01:27:07 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:08 INFO - Running post-action listener: _resource_record_post_action 01:27:08 INFO - Running post-action listener: set_extra_try_arguments 01:27:08 INFO - [mozharness: 2016-09-20 08:27:08.065916Z] Finished download-and-extract step (success) 01:27:08 INFO - [mozharness: 2016-09-20 08:27:08.066147Z] Running populate-webroot step. 01:27:08 INFO - Running pre-action listener: _resource_record_pre_action 01:27:08 INFO - Running main action method: populate_webroot 01:27:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:27:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:27:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:27:08 INFO - '--tppagecycles', 01:27:08 INFO - '7'], 01:27:08 INFO - 'tests': ['tsvgm']}}, 01:27:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:27:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:27:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:27:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:27:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:27:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:27:08 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:27:08 INFO - 'talos_options': ['--disable-e10s'], 01:27:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:27:08 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:27:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:27:08 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:27:08 INFO - 'talos_options': ['--disable-e10s'], 01:27:08 INFO - 'tests': ['damp', 'tps']}, 01:27:08 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:27:08 INFO - 'tests': ['damp', 'tps']}, 01:27:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:27:08 INFO - 'tests': ['dromaeo_dom']}, 01:27:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:27:08 INFO - 'g4': {'talos_options': ['--disable-e10s'], 01:27:08 INFO - 'tests': ['basic_compositor_video']}, 01:27:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 01:27:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:27:08 INFO - 'tests': ['a11yr', 01:27:08 INFO - 'ts_paint', 01:27:08 INFO - 'tpaint', 01:27:08 INFO - 'sessionrestore', 01:27:08 INFO - 'sessionrestore_no_auto_restore', 01:27:08 INFO - 'tabpaint']}, 01:27:08 INFO - 'other-e10s': {'tests': ['a11yr', 01:27:08 INFO - 'ts_paint', 01:27:08 INFO - 'tpaint', 01:27:08 INFO - 'sessionrestore', 01:27:08 INFO - 'sessionrestore_no_auto_restore', 01:27:08 INFO - 'tabpaint']}, 01:27:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:27:08 INFO - 'ts_paint', 01:27:08 INFO - 'tpaint', 01:27:08 INFO - 'sessionrestore', 01:27:08 INFO - 'sessionrestore_no_auto_restore', 01:27:08 INFO - 'tabpaint']}, 01:27:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:27:08 INFO - 'ts_paint', 01:27:08 INFO - 'tpaint', 01:27:08 INFO - 'sessionrestore', 01:27:08 INFO - 'sessionrestore_no_auto_restore', 01:27:08 INFO - 'tabpaint']}, 01:27:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:27:08 INFO - 'tests': ['a11yr', 01:27:08 INFO - 'ts_paint', 01:27:08 INFO - 'tpaint', 01:27:08 INFO - 'sessionrestore', 01:27:08 INFO - 'sessionrestore_no_auto_restore', 01:27:08 INFO - 'tabpaint']}, 01:27:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:27:08 INFO - 'tests': ['a11yr', 01:27:08 INFO - 'ts_paint', 01:27:08 INFO - 'tpaint', 01:27:08 INFO - 'sessionrestore', 01:27:08 INFO - 'sessionrestore_no_auto_restore', 01:27:08 INFO - 'tabpaint']}, 01:27:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:27:08 INFO - 'tests': ['tsvgx', 01:27:08 INFO - 'tsvgr_opacity', 01:27:08 INFO - 'tart', 01:27:08 INFO - 'tscrollx', 01:27:08 INFO - 'cart']}, 01:27:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:27:08 INFO - 'tsvgr_opacity', 01:27:08 INFO - 'tart', 01:27:08 INFO - 'tscrollx', 01:27:08 INFO - 'cart']}, 01:27:08 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:27:08 INFO - 'talos_options': ['--disable-e10s'], 01:27:08 INFO - 'tests': ['tp5o']}, 01:27:08 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:27:08 INFO - 'tests': ['tp5o']}, 01:27:08 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:27:08 INFO - 'talos_options': ['--disable-e10s', 01:27:08 INFO - '--xperf_path', 01:27:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:27:08 INFO - 'tests': ['tp5n']}, 01:27:08 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:27:08 INFO - 'talos_options': ['--xperf_path', 01:27:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:27:08 INFO - 'tests': ['tp5n']}}, 01:27:08 INFO - 'talos.zip': {'path': '', 01:27:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:27:08 INFO - Running post-action listener: _resource_record_post_action 01:27:08 INFO - [mozharness: 2016-09-20 08:27:08.088291Z] Finished populate-webroot step (success) 01:27:08 INFO - [mozharness: 2016-09-20 08:27:08.088380Z] Running create-virtualenv step. 01:27:08 INFO - Running pre-action listener: _resource_record_pre_action 01:27:08 INFO - Running main action method: create_virtualenv 01:27:08 INFO - Creating virtualenv /builds/slave/test/build/venv 01:27:08 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:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:27:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:27:08 INFO - Using real prefix '/usr' 01:27:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:27:08 INFO - Installing distribute.............................................................................................................................................................................................done. 01:27:10 INFO - Installing pip.................done. 01:27:10 INFO - Return code: 0 01:27:10 INFO - Installing pip>=1.5 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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1131030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12948a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1360400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ef570>, '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 0x13f0470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13f0900>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1474360020.566327-1268571996', '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', 'pip>=1.5'] 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 pip>=1.5 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': 'e31afd3c0f0de4e86ebe4d94000001b5-1474360020.566327-1268571996', 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 - 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:10 INFO - Cleaning up... 01:27:10 INFO - Return code: 0 01:27:10 INFO - Installing psutil>=3.1.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', '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 0x1131030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12948a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1360400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ef570>, '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 0x13f0470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13f0900>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1474360020.566327-1268571996', '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', 'psutil>=3.1.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 psutil>=3.1.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': 'e31afd3c0f0de4e86ebe4d94000001b5-1474360020.566327-1268571996', 01:27:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:27:10 INFO - '_': '/tools/buildbot/bin/python'} 01:27:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:27:11 INFO - Downloading/unpacking psutil>=3.1.1 01:27:11 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:11 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:11 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:11 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:11 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:11 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 - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:27:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:27:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:27:13 INFO - Installing collected packages: psutil 01:27:13 INFO - Running setup.py install for psutil 01:27:13 INFO - building 'psutil._psutil_linux' extension 01:27:13 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:13 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:13 INFO - building 'psutil._psutil_posix' extension 01:27:13 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:13 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:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:27:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:27:13 INFO - Successfully installed psutil 01:27:13 INFO - Cleaning up... 01:27:13 INFO - Return code: 0 01:27:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:27:13 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:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:27:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:13 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:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:27:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:13 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 0x1131030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12948a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1360400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ef570>, '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 0x13f0470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13f0900>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1474360020.566327-1268571996', '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:13 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:13 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:13 INFO - Using env: {'DISPLAY': ':0', 01:27:13 INFO - 'HOME': '/home/cltbld', 01:27:13 INFO - 'LANG': 'en_US.UTF-8', 01:27:13 INFO - 'LANGUAGE': 'en_US:en', 01:27:13 INFO - 'LOGNAME': 'cltbld', 01:27:13 INFO - 'MAIL': '/var/mail/cltbld', 01:27:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:27:13 INFO - 'MOZ_NO_REMOTE': '1', 01:27:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:27:13 INFO - 'NO_EM_RESTART': '1', 01:27:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:27:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:27:13 INFO - 'PWD': '/builds/slave/test', 01:27:13 INFO - 'SHELL': '/bin/bash', 01:27:13 INFO - 'SHLVL': '1', 01:27:13 INFO - 'TERM': 'linux', 01:27:13 INFO - 'TMOUT': '86400', 01:27:13 INFO - 'USER': 'cltbld', 01:27:13 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1474360020.566327-1268571996', 01:27:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:27:13 INFO - '_': '/tools/buildbot/bin/python'} 01:27:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:27:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 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 mozsystemmonitor-0.3.tar.gz 01:27:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:27:15 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:15 INFO - Installing collected packages: mozsystemmonitor 01:27:15 INFO - Running setup.py install for mozsystemmonitor 01:27:15 INFO - Successfully installed mozsystemmonitor 01:27:15 INFO - Cleaning up... 01:27:15 INFO - Return code: 0 01:27:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 01:27:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:27:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:27:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:27:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:27:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:15 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 0x1131030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12948a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1360400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ef570>, '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 0x13f0470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13f0900>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1474360020.566327-1268571996', '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:15 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:15 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:15 INFO - Using env: {'DISPLAY': ':0', 01:27:15 INFO - 'HOME': '/home/cltbld', 01:27:15 INFO - 'LANG': 'en_US.UTF-8', 01:27:15 INFO - 'LANGUAGE': 'en_US:en', 01:27:15 INFO - 'LOGNAME': 'cltbld', 01:27:15 INFO - 'MAIL': '/var/mail/cltbld', 01:27:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:27:15 INFO - 'MOZ_NO_REMOTE': '1', 01:27:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:27:15 INFO - 'NO_EM_RESTART': '1', 01:27:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:27:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:27:15 INFO - 'PWD': '/builds/slave/test', 01:27:15 INFO - 'SHELL': '/bin/bash', 01:27:15 INFO - 'SHLVL': '1', 01:27:15 INFO - 'TERM': 'linux', 01:27:15 INFO - 'TMOUT': '86400', 01:27:15 INFO - 'USER': 'cltbld', 01:27:15 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1474360020.566327-1268571996', 01:27:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:27:15 INFO - '_': '/tools/buildbot/bin/python'} 01:27:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:27:16 INFO - Downloading/unpacking jsonschema==2.5.1 01:27:16 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:16 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:16 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:16 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:16 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:16 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:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:27:18 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:27:18 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:18 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:18 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:18 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:18 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:18 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:18 INFO - Downloading functools32-3.2.3-2.tar.gz 01:27:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:27:18 INFO - warning: no files found matching '*.txt' 01:27:18 INFO - no previously-included directories found matching 'build' 01:27:18 INFO - no previously-included directories found matching 'dist' 01:27:18 INFO - no previously-included directories found matching '.git*' 01:27:18 INFO - Installing collected packages: jsonschema, functools32 01:27:18 INFO - Running setup.py install for functools32 01:27:18 INFO - warning: no files found matching '*.txt' 01:27:18 INFO - no previously-included directories found matching 'build' 01:27:18 INFO - no previously-included directories found matching 'dist' 01:27:18 INFO - no previously-included directories found matching '.git*' 01:27:18 INFO - Successfully installed jsonschema functools32 01:27:18 INFO - Cleaning up... 01:27:18 INFO - Return code: 0 01:27:18 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 01:27:18 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:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:27:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:18 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:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:27:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:18 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 0x1131030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12948a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1360400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ef570>, '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 0x13f0470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13f0900>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1474360020.566327-1268571996', '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:18 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:18 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:18 INFO - Using env: {'DISPLAY': ':0', 01:27:18 INFO - 'HOME': '/home/cltbld', 01:27:18 INFO - 'LANG': 'en_US.UTF-8', 01:27:18 INFO - 'LANGUAGE': 'en_US:en', 01:27:18 INFO - 'LOGNAME': 'cltbld', 01:27:18 INFO - 'MAIL': '/var/mail/cltbld', 01:27:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:27:18 INFO - 'MOZ_NO_REMOTE': '1', 01:27:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:27:18 INFO - 'NO_EM_RESTART': '1', 01:27:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:27:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:27:18 INFO - 'PWD': '/builds/slave/test', 01:27:18 INFO - 'SHELL': '/bin/bash', 01:27:18 INFO - 'SHLVL': '1', 01:27:18 INFO - 'TERM': 'linux', 01:27:18 INFO - 'TMOUT': '86400', 01:27:18 INFO - 'USER': 'cltbld', 01:27:18 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1474360020.566327-1268571996', 01:27:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:27:18 INFO - '_': '/tools/buildbot/bin/python'} 01:27:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:27:18 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 01:27:18 INFO - Cleaning up... 01:27:18 INFO - Return code: 0 01:27:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:27:18 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:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:27:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:18 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:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:27:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:18 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 0x1131030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12948a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1360400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ef570>, '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 0x13f0470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13f0900>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1474360020.566327-1268571996', '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:18 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:18 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:18 INFO - Using env: {'DISPLAY': ':0', 01:27:18 INFO - 'HOME': '/home/cltbld', 01:27:18 INFO - 'LANG': 'en_US.UTF-8', 01:27:18 INFO - 'LANGUAGE': 'en_US:en', 01:27:18 INFO - 'LOGNAME': 'cltbld', 01:27:18 INFO - 'MAIL': '/var/mail/cltbld', 01:27:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:27:18 INFO - 'MOZ_NO_REMOTE': '1', 01:27:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:27:18 INFO - 'NO_EM_RESTART': '1', 01:27:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:27:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:27:18 INFO - 'PWD': '/builds/slave/test', 01:27:18 INFO - 'SHELL': '/bin/bash', 01:27:18 INFO - 'SHLVL': '1', 01:27:18 INFO - 'TERM': 'linux', 01:27:18 INFO - 'TMOUT': '86400', 01:27:18 INFO - 'USER': 'cltbld', 01:27:18 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1474360020.566327-1268571996', 01:27:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:27:18 INFO - '_': '/tools/buildbot/bin/python'} 01:27:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:27:18 INFO - Downloading/unpacking blobuploader==1.2.4 01:27:18 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:18 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:18 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:18 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:18 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:18 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:20 INFO - Downloading blobuploader-1.2.4.tar.gz 01:27:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:27:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:27:20 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:20 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:20 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:20 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:20 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:20 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 - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:27:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 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:21 INFO - Downloading docopt-0.6.1.tar.gz 01:27:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:27:21 INFO - Installing collected packages: blobuploader, requests, docopt 01:27:21 INFO - Running setup.py install for blobuploader 01:27:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:27:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:27:21 INFO - Running setup.py install for requests 01:27:22 INFO - Running setup.py install for docopt 01:27:22 INFO - Successfully installed blobuploader requests docopt 01:27:22 INFO - Cleaning up... 01:27:22 INFO - Return code: 0 01:27:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:27:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:27:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:27:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:27:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:27:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:22 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 0x1131030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12948a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1360400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ef570>, '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 0x13f0470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13f0900>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1474360020.566327-1268571996', '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:22 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:22 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:22 INFO - Using env: {'DISPLAY': ':0', 01:27:22 INFO - 'HOME': '/home/cltbld', 01:27:22 INFO - 'LANG': 'en_US.UTF-8', 01:27:22 INFO - 'LANGUAGE': 'en_US:en', 01:27:22 INFO - 'LOGNAME': 'cltbld', 01:27:22 INFO - 'MAIL': '/var/mail/cltbld', 01:27:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:27:22 INFO - 'MOZ_NO_REMOTE': '1', 01:27:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:27:22 INFO - 'NO_EM_RESTART': '1', 01:27:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:27:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:27:22 INFO - 'PWD': '/builds/slave/test', 01:27:22 INFO - 'SHELL': '/bin/bash', 01:27:22 INFO - 'SHLVL': '1', 01:27:22 INFO - 'TERM': 'linux', 01:27:22 INFO - 'TMOUT': '86400', 01:27:22 INFO - 'USER': 'cltbld', 01:27:22 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1474360020.566327-1268571996', 01:27:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:27:22 INFO - '_': '/tools/buildbot/bin/python'} 01:27:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:27:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:27:22 INFO - Running setup.py (path:/tmp/pip-etSlTz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:27:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:27:22 INFO - Running setup.py (path:/tmp/pip-mMT0Xr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:27:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:27:22 INFO - Running setup.py (path:/tmp/pip-KOFLdT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:27:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:27:22 INFO - Running setup.py (path:/tmp/pip-9FHAjr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:27:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:27:22 INFO - Running setup.py (path:/tmp/pip-vyXWyE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:27:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:27:22 INFO - Running setup.py (path:/tmp/pip-Oxsnhw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:27:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:27:22 INFO - Running setup.py (path:/tmp/pip-RbrgHQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:27:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:27:22 INFO - Running setup.py (path:/tmp/pip-36oYh5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:27:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:27:22 INFO - Running setup.py (path:/tmp/pip-ZI_HIU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:27:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:27:23 INFO - Running setup.py (path:/tmp/pip-VKXynw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:27:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:27:23 INFO - Running setup.py (path:/tmp/pip-0y0yOr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:27:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:27:23 INFO - Running setup.py (path:/tmp/pip-9QsUaA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:27:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:27:23 INFO - Running setup.py (path:/tmp/pip-uzIIJd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:27:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:27:23 INFO - Running setup.py (path:/tmp/pip-zdEqUN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:27:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:27:23 INFO - Running setup.py (path:/tmp/pip-k4tQ3f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:27:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:27:23 INFO - Running setup.py (path:/tmp/pip-hr_YZI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:27:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:27:23 INFO - Running setup.py (path:/tmp/pip-PPTVqO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:27:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:27:23 INFO - Running setup.py install for manifestparser 01:27:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:27:23 INFO - Running setup.py install for mozcrash 01:27:23 INFO - Running setup.py install for mozdebug 01:27:23 INFO - Running setup.py install for mozdevice 01:27:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:27:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:27:24 INFO - Running setup.py install for mozfile 01:27:24 INFO - Running setup.py install for mozhttpd 01:27:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:27:24 INFO - Running setup.py install for mozinfo 01:27:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:27:24 INFO - Running setup.py install for mozInstall 01:27:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:27:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:27:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:27:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:27:24 INFO - Running setup.py install for mozleak 01:27:24 INFO - Running setup.py install for mozlog 01:27:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:27:24 INFO - Running setup.py install for moznetwork 01:27:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:27:24 INFO - Running setup.py install for mozprocess 01:27:24 INFO - Running setup.py install for mozprofile 01:27:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:27:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:27:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:27:24 INFO - Running setup.py install for mozrunner 01:27:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:27:25 INFO - Running setup.py install for mozscreenshot 01:27:25 INFO - Running setup.py install for moztest 01:27:25 INFO - Running setup.py install for mozversion 01:27:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:27:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:27:25 INFO - Cleaning up... 01:27:25 INFO - Return code: 0 01:27:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:27:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:27:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:27:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:27:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:27:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:25 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 0x1131030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12948a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1360400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ef570>, '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 0x13f0470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13f0900>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1474360020.566327-1268571996', '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:25 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:25 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:25 INFO - Using env: {'DISPLAY': ':0', 01:27:25 INFO - 'HOME': '/home/cltbld', 01:27:25 INFO - 'LANG': 'en_US.UTF-8', 01:27:25 INFO - 'LANGUAGE': 'en_US:en', 01:27:25 INFO - 'LOGNAME': 'cltbld', 01:27:25 INFO - 'MAIL': '/var/mail/cltbld', 01:27:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:27:25 INFO - 'MOZ_NO_REMOTE': '1', 01:27:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:27:25 INFO - 'NO_EM_RESTART': '1', 01:27:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:27:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:27:25 INFO - 'PWD': '/builds/slave/test', 01:27:25 INFO - 'SHELL': '/bin/bash', 01:27:25 INFO - 'SHLVL': '1', 01:27:25 INFO - 'TERM': 'linux', 01:27:25 INFO - 'TMOUT': '86400', 01:27:25 INFO - 'USER': 'cltbld', 01:27:25 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1474360020.566327-1268571996', 01:27:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:27:25 INFO - '_': '/tools/buildbot/bin/python'} 01:27:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:27:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:27:25 INFO - Running setup.py (path:/tmp/pip-XBWNfM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:27:25 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:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:27:25 INFO - Running setup.py (path:/tmp/pip-5GY_ZY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:27:25 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:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:27:25 INFO - Running setup.py (path:/tmp/pip-bXtJHC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:27:25 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:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:27:25 INFO - Running setup.py (path:/tmp/pip-XATPkf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:27:25 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:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:27:25 INFO - Running setup.py (path:/tmp/pip-tXL0Rn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:27:26 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:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:27:26 INFO - Running setup.py (path:/tmp/pip-zxthf4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:27:26 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:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:27:26 INFO - Running setup.py (path:/tmp/pip-HjDajN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:27:26 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:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:27:26 INFO - Running setup.py (path:/tmp/pip-1yxO5J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:27:26 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:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:27:26 INFO - Running setup.py (path:/tmp/pip-0wi2b5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:27:26 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:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:27:26 INFO - Running setup.py (path:/tmp/pip-x99Zj8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:27:26 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:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:27:26 INFO - Running setup.py (path:/tmp/pip-qIknHw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:27:26 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:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:27:26 INFO - Running setup.py (path:/tmp/pip-nBq7S0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:27:26 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:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:27:26 INFO - Running setup.py (path:/tmp/pip-_Ovc3m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:27:26 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:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:27:26 INFO - Running setup.py (path:/tmp/pip-OICjbq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:27:26 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:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:27:26 INFO - Running setup.py (path:/tmp/pip-agJx8y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:27:26 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:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:27:26 INFO - Running setup.py (path:/tmp/pip-ia0SOI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:27:26 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:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:27:26 INFO - Running setup.py (path:/tmp/pip-yWQ4QD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:27:26 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:26 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:26 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:26 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:26 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:26 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:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:27:26 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:26 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:26 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:26 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:26 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:26 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:28 INFO - Downloading blessings-1.6.tar.gz 01:27:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:27:28 INFO - Installing collected packages: blessings 01:27:28 INFO - Running setup.py install for blessings 01:27:28 INFO - Successfully installed blessings 01:27:28 INFO - Cleaning up... 01:27:29 INFO - Return code: 0 01:27:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:27:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:27:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:27:29 INFO - Reading from file tmpfile_stdout 01:27:29 INFO - Current package versions: 01:27:29 INFO - argparse == 1.2.1 01:27:29 INFO - blessings == 1.6 01:27:29 INFO - blobuploader == 1.2.4 01:27:29 INFO - docopt == 0.6.1 01:27:29 INFO - functools32 == 3.2.3-2 01:27:29 INFO - jsonschema == 2.5.1 01:27:29 INFO - manifestparser == 1.1 01:27:29 INFO - mozInstall == 1.12 01:27:29 INFO - mozcrash == 0.17 01:27:29 INFO - mozdebug == 0.1 01:27:29 INFO - mozdevice == 0.48 01:27:29 INFO - mozfile == 1.2 01:27:29 INFO - mozhttpd == 0.7 01:27:29 INFO - mozinfo == 0.9 01:27:29 INFO - mozleak == 0.1 01:27:29 INFO - mozlog == 3.3 01:27:29 INFO - moznetwork == 0.27 01:27:29 INFO - mozprocess == 0.23 01:27:29 INFO - mozprofile == 0.28 01:27:29 INFO - mozrunner == 6.12 01:27:29 INFO - mozscreenshot == 0.1 01:27:29 INFO - mozsystemmonitor == 0.3 01:27:29 INFO - moztest == 0.7 01:27:29 INFO - mozversion == 1.4 01:27:29 INFO - psutil == 3.1.1 01:27:29 INFO - requests == 1.2.3 01:27:29 INFO - wsgiref == 0.1.2 01:27:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:27:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:27:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:27:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:27:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:27:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:29 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 0x1131030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12948a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1360400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ef570>, '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 0x13f0470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13f0900>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1474360020.566327-1268571996', '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:29 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:29 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:29 INFO - Using env: {'DISPLAY': ':0', 01:27:29 INFO - 'HOME': '/home/cltbld', 01:27:29 INFO - 'LANG': 'en_US.UTF-8', 01:27:29 INFO - 'LANGUAGE': 'en_US:en', 01:27:29 INFO - 'LOGNAME': 'cltbld', 01:27:29 INFO - 'MAIL': '/var/mail/cltbld', 01:27:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:27:29 INFO - 'MOZ_NO_REMOTE': '1', 01:27:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:27:29 INFO - 'NO_EM_RESTART': '1', 01:27:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:27:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:27:29 INFO - 'PWD': '/builds/slave/test', 01:27:29 INFO - 'SHELL': '/bin/bash', 01:27:29 INFO - 'SHLVL': '1', 01:27:29 INFO - 'TERM': 'linux', 01:27:29 INFO - 'TMOUT': '86400', 01:27:29 INFO - 'USER': 'cltbld', 01:27:29 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1474360020.566327-1268571996', 01:27:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:27:29 INFO - '_': '/tools/buildbot/bin/python'} 01:27:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:27:29 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:29 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:29 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:29 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:29 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:29 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:29 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:29 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:29 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:29 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:29 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:29 INFO - Cleaning up... 01:27:29 INFO - Return code: 0 01:27:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:27:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:27:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:27:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:27:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:27:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1131030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12948a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1360400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ef570>, '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 0x13f0470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13f0900>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1474360020.566327-1268571996', '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:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 01:27:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 01:27:29 INFO - Using env: {'DISPLAY': ':0', 01:27:29 INFO - 'HOME': '/home/cltbld', 01:27:29 INFO - 'LANG': 'en_US.UTF-8', 01:27:29 INFO - 'LANGUAGE': 'en_US:en', 01:27:29 INFO - 'LOGNAME': 'cltbld', 01:27:29 INFO - 'MAIL': '/var/mail/cltbld', 01:27:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:27:29 INFO - 'MOZ_NO_REMOTE': '1', 01:27:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:27:29 INFO - 'NO_EM_RESTART': '1', 01:27:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:27:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:27:29 INFO - 'PWD': '/builds/slave/test', 01:27:29 INFO - 'SHELL': '/bin/bash', 01:27:29 INFO - 'SHLVL': '1', 01:27:29 INFO - 'TERM': 'linux', 01:27:29 INFO - 'TMOUT': '86400', 01:27:29 INFO - 'USER': 'cltbld', 01:27:29 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1474360020.566327-1268571996', 01:27:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:27:29 INFO - '_': '/tools/buildbot/bin/python'} 01:27:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:27:29 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 01:27:29 INFO - Cleaning up... 01:27:29 INFO - Return code: 0 01:27:29 INFO - Running post-action listener: _resource_record_post_action 01:27:29 INFO - Running post-action listener: _start_resource_monitoring 01:27:29 INFO - Starting resource monitoring. 01:27:29 INFO - [mozharness: 2016-09-20 08:27:29.707478Z] Finished create-virtualenv step (success) 01:27:29 INFO - [mozharness: 2016-09-20 08:27:29.707904Z] Running install step. 01:27:29 INFO - Running pre-action listener: _resource_record_pre_action 01:27:29 INFO - Running main action method: install 01:27:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:27:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:27:29 INFO - Reading from file tmpfile_stdout 01:27:29 INFO - Detecting whether we're running mozinstall >=1.0... 01:27:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:27:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:27:30 INFO - Reading from file tmpfile_stdout 01:27:30 INFO - Output received: 01:27:30 INFO - Usage: mozinstall [options] installer 01:27:30 INFO - Options: 01:27:30 INFO - -h, --help show this help message and exit 01:27:30 INFO - -d DEST, --destination=DEST 01:27:30 INFO - Directory to install application into. [default: 01:27:30 INFO - "/builds/slave/test"] 01:27:30 INFO - --app=APP Application being installed. [default: firefox] 01:27:30 INFO - mkdir: /builds/slave/test/build/application 01:27:30 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:30 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:43 INFO - Reading from file tmpfile_stdout 01:27:43 INFO - Output received: 01:27:43 INFO - /builds/slave/test/build/application/firefox/firefox 01:27:43 INFO - Running post-action listener: _resource_record_post_action 01:27:43 INFO - [mozharness: 2016-09-20 08:27:43.509528Z] Finished install step (success) 01:27:43 INFO - [mozharness: 2016-09-20 08:27:43.509806Z] Running run-tests step. 01:27:43 INFO - Running pre-action listener: _resource_record_pre_action 01:27:43 INFO - Running main action method: run_tests 01:27:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:27:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:27:43 INFO - Python 2.7.3 01:27:43 INFO - Return code: 0 01:27:43 INFO - grabbing minidump binary from tooltool 01:27:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:27:43 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 0x13ef570>, '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 0x13f0470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13f0900>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:27:43 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:43 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:43 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:43 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:27:43 INFO - Return code: 0 01:27:43 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 01:27:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:27:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:27:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 01:27:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:27:43 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-006', '--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:43 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-006 --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:43 INFO - Using env: {'DISPLAY': ':0', 01:27:43 INFO - 'HOME': '/home/cltbld', 01:27:43 INFO - 'LANG': 'en_US.UTF-8', 01:27:43 INFO - 'LANGUAGE': 'en_US:en', 01:27:43 INFO - 'LOGNAME': 'cltbld', 01:27:43 INFO - 'MAIL': '/var/mail/cltbld', 01:27:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:27:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 01:27:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:27:43 INFO - 'MOZ_NO_REMOTE': '1', 01:27:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:27:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:27:43 INFO - 'NO_EM_RESTART': '1', 01:27:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:27:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:27:43 INFO - 'PWD': '/builds/slave/test', 01:27:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:27:43 INFO - 'SHELL': '/bin/bash', 01:27:43 INFO - 'SHLVL': '1', 01:27:43 INFO - 'TERM': 'linux', 01:27:43 INFO - 'TMOUT': '86400', 01:27:43 INFO - 'USER': 'cltbld', 01:27:43 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1474360020.566327-1268571996', 01:27:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:27:43 INFO - '_': '/tools/buildbot/bin/python'} 01:27:43 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-006', '--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:43 INFO - mozversion application_buildid: 20160919151517 01:27:43 INFO - mozversion application_changeset: 4376f2389699910ebe72e39977d563c77a094a1d 01:27:43 INFO - mozversion application_display_name: Firefox 01:27:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:27:43 INFO - mozversion application_name: Firefox 01:27:43 INFO - mozversion application_remotingname: firefox 01:27:43 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 01:27:43 INFO - mozversion application_vendor: Mozilla 01:27:43 INFO - mozversion application_version: 50.0 01:27:43 INFO - mozversion platform_buildid: 20160919151517 01:27:43 INFO - mozversion platform_changeset: 4376f2389699910ebe72e39977d563c77a094a1d 01:27:43 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 01:27:43 INFO - mozversion platform_version: 50.0 01:27:43 INFO - using testdate: 1474360063 01:27:43 INFO - actual date: 1474360063 01:27:43 INFO - starting webserver on 'localhost:41772' 01:27:43 INFO - SUITE-START | Running 2 tests 01:27:43 INFO - TEST-START | dromaeo_css 01:27:43 INFO - operating with platform_type : linux_ 01:27:43 INFO - Initialising browser for dromaeo_css test... 01:27:43 INFO - TEST-INFO | started process 2692 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_CKbYO/profile http://localhost:41772/getInfo.html) 01:27:47 INFO - PROCESS | 2692 | __metrics Screen width/height:1600/1200 01:27:47 INFO - PROCESS | 2692 | colorDepth:24 01:27:47 INFO - PROCESS | 2692 | Browser inner width/height: 1024/697 01:27:47 INFO - PROCESS | 2692 | __metrics 01:27:48 ERROR - PROCESS | 2692 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 01:27:49 INFO - TEST-INFO | 2692: exit 0 01:27:49 INFO - Browser initialized. 01:27:49 INFO - Running cycle 1/1 for dromaeo_css test... 01:27:49 INFO - TEST-INFO | started process 2915 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_CKbYO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:28:01 INFO - PROCESS | 2915 | RSS: Main: 149958656 01:28:01 INFO - PROCESS | 2915 | 01:28:50 INFO - PROCESS | 2915 | 1474360130753 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:59 INFO - PROCESS | 2915 | Cycle 1(1): loaded http://localhost:41772/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41772/tests/dromaeo/cssquery-ext.html) 01:31:00 INFO - PROCESS | 2915 | RSS: Main: 158572544 01:31:00 INFO - PROCESS | 2915 | 01:34:02 INFO - PROCESS | 2915 | Cycle 1(1): loaded http://localhost:41772/tests/dromaeo/cssquery-ext.html (next: http://localhost:41772/tests/dromaeo/cssquery-jquery.html) 01:34:03 INFO - PROCESS | 2915 | RSS: Main: 155295744 01:34:03 INFO - PROCESS | 2915 | 01:37:05 INFO - PROCESS | 2915 | Cycle 1(1): loaded http://localhost:41772/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41772/tests/dromaeo/cssquery-mootools.html) 01:37:05 INFO - PROCESS | 2915 | RSS: Main: 154865664 01:37:05 INFO - PROCESS | 2915 | 01:40:07 INFO - PROCESS | 2915 | Cycle 1(1): loaded http://localhost:41772/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41772/tests/dromaeo/cssquery-prototype.html) 01:40:08 INFO - PROCESS | 2915 | RSS: Main: 156688384 01:40:08 INFO - PROCESS | 2915 | 01:43:10 INFO - PROCESS | 2915 | Cycle 1(1): loaded http://localhost:41772/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41772/tests/dromaeo/cssquery-yui.html) 01:43:11 INFO - PROCESS | 2915 | RSS: Main: 157339648 01:43:11 INFO - PROCESS | 2915 | 01:46:13 INFO - PROCESS | 2915 | Cycle 1(1): loaded http://localhost:41772/tests/dromaeo/cssquery-yui.html (next: http://localhost:41772/tests/dromaeo/cssquery-dojo.html) 01:46:13 INFO - PROCESS | 2915 | RSS: Main: 157696000 01:46:13 INFO - PROCESS | 2915 | 01:46:13 INFO - PROCESS | 2915 | __start_tp_report 01:46:13 INFO - PROCESS | 2915 | _x_x_mozilla_page_load 01:46:13 INFO - PROCESS | 2915 | _x_x_mozilla_page_load_details 01:46:13 INFO - PROCESS | 2915 | |i|pagename|runs| 01:46:13 INFO - PROCESS | 2915 | |0;dojo.html;3282.0810173151517;3316.6683331666836;3316.684914933082;3341.231516751147;3345.715614172795;5787.016207244768;5901.3508514063415;5923.7630494780215;5924.585279030464;5929.051351783715;5858.648481091127;5905;5907.0548712206055;5912.9704351478185;5936.228290322263;5316.76074576644;5374.381946076192;5392.649477783951;5435.592330575203;5455.318085239345;5220.817271395502;5231.973840130813;5234.502722241381;5262.657927234717;5313.3092697949405;5323.148296272597;5334.359876814788;5341;5352.437994010631;5401.297831281928;5392.56859451243;5393.352797664266;5396.298481197439;5403.648762830403;5431.103867861798;5067;5161.045206636784;5199.844004679866;5305.204219367087;5306.336707911511;5811.128330750379;5837.241158649391;5838.7080645967535;5855.677937713424;5899.056151015817;6259.624422534617;6279.309275979638;6305;6305.274893387227;6309.936900630981;5968.31364393096;6108.450239478424;6125.327021303102;6341.80974570759;6346.619202847798;5816.302043754777;5825.1844741736195;5836.766529338779;5857.7949771757785;5859.6777177255235;6355.142055822497;6362.745490180433;6375.394337537927;6377.681115944184;6384.233891932905;5915.852103697356;5999.820005399845;6021.819345419557;6059.606125601822;6075.2405949256345;6203.193584833943;6208.751649933952;6218.22272215973;6219.564630475823;6231.22109736283;6204.13142160098;6212.47193988507;6213.751449942042;6215.316315205324;6235.906461403082;5950.761969521171;6048.879022419527;6055.666938318347;6205.441510264098;6223;4714.5521175488275;4715.599174070242;4721.693089949143;4741.905161896742;4743.383360163157;4203.43253660758;4206.789660516962;4206.978965105216;4232.111256636141;4240.342746874239;5768.105943578752;5818.941810581925;5839.883202335929;5879.706014699248;5899.76400943966;6266.780662676875;6310.211223597051;6310.684465776692;6312.684365781693;6323.52573556985;5972.581919265696;5975;5976.5218782497295;5977.790777322773;6011.338752737195;5301.045811753845;5344.8663783404945;5367.865803354947;5407.810726624549;5424.972875135678;6505.251896031922;6506.4469520091325;6507.544471887017;6515.869682606227;6522.608643481406;6270.4670103039925;6271.247450305992;6273.780417685359;6276.278228003838;6308.022256550242;6210.390384424464;6223.097650840512;6331.778387756406;6374.872502550016;6470.805875823733;6163.445289923749;6202.4417802398;6202.782902598387;6203.038529028008;6203.596766210182;6320.273168585672;6321.273053598895;6322.399372059647;6325.367463253638;6333.778317758856;5920.082387229814;5984.820455386345;6024.518038556817;6054.939450605438;6147.047207682816;6112.442689633966;6121.3572574879;6123.1609584945345;6125.033656770631;6130.000698177663;6036.094585812163;6046.54650901193;6064.514838812973;6098.725557350019;6100.816975490742;5950.137230101697;5989.041753319448;6042.154098426312;6046.969765151146;6124.785632502841;5916.763329466773;5936.821895343147;5945.048792193228;5947;5986.07215881539 01:46:13 INFO - PROCESS | 2915 | |1;ext.html;588.799808065258;638.7828138432935;639.6258188959458;662.5395350231588;692.1313751242191;42841.571584284146;47465;47773;47783;47822.28266575996;47708;48036.75981620087;48186.51813481864;48275.75862120684;48277.275840862414;9163.49600771959;9225.446473211596;9247.6300947962;9251.907480925172;9307.90692093077;9218.677346292881;9310.906890931072;9320.347575669672;9328.906710932906;9329.440233585972;9320.953395233015;9376.484293363863;9387.718368448957;9392.718218453458;9425.905740942571;9344.43933363997;9376.390534615228;9378.155965963097;9392.859107113365;9394.671186508507;9215.539223038888;9277.257819374434;9285.025072367373;9290.070992900655;9297.209737172281;45320.3201951971;45352.77323613394;45576;45674;45861;3535.699465545432;3643.125649844045;3854.8265328060306;4296.3770253313405;4626.329182268586;36860.63139368599;37124;37305.25389492222;37432.62567374318;37469.812650936845;4748.406449193851;4792.089502994455;4795.6403269754555;4905.068037072945;4940.851774446773;410526;414399;419637;420457.89771051257;422478;38684.41973370402;39663.80168099141;39711.40432893509;69223.65388173128;69621;41407.792961035004;41627.7918610405;41679.7916010418;41750.37374439386;41768.16463670709;16919;16985.91507042457;16989.150542472828;17044.14779261032;17134.828651713324;15203.467878624195;15212.543623691306;15218.315175817115;15247.542573722805;15297.082175069309;9394.38936469141;9484.90515094854;9486.430814151174;9525.237980961507;9528.380655257386;3565.6790888820133;3601.657842504958;3672.779633222015;3673.577538583044;3692.7045836333027;1146.8623765148068;1150.9769804603861;1157.3750174905624;1159.299250333076;1219.6645922371242;34815.82592087074;35058.474122888176;35169.47245791315;35583.11042223965;35839.641603584154;1198.7542553776454;1201.4593432955205;1208.957686480969;1237.4864431261105;1270.4410059573759;17882.91058544725;18430.17064232113;18524.814751852307;18626.906865465586;18627.720584191247;173278;690565;691220;692951;697021;43965.120697585866;44277.77861110738;44592.3311150333;44657.77671111624;44792.55207447949;1444.674948136682;1457.6428774949993;1481.5259117082646;1497.4609140709335;1502.9849701503065;32878.506822398165;33293;33452.330953381286;33622.327553448304;33905.83047084749;57521.71239143777;58106;58159.709201455415;58204.708976454844;58264;492259;494152;495837;496929;501890;31900.011922267153;32681.18297042593;32694.67305326916;32842;32908.50637240491;53716;54022;54435.45564544462;54744.45255547394;54986.72506637441;45949.54050459586;45954;46749.53250467588;47049.05901882012;47962.760186198844;46239.76880115578;47045.52954470548;47194.29208561943;47662.76168619135;47683.52316476929;44988;45186.064371988425;45707.54292457166;46050.53949460462;46254;39998.80000599979;40516.3922541168;40869;41186.79406602948;41235.587644124374;30267.545986809775;30667.693323066484;30679.53980690336;30808.3330004401;30909 01:46:13 INFO - PROCESS | 2915 | |2;jquery.html;400.1177403824567;403.94748682671246;414.69312708595635;418.7215501691375;429.4395813463431;19145.42563723087;20706;20809.16763329467;21140.365789026335;21414.571708565858;20302.69545956812;20502.487437814063;20733.792662073374;20790.376288711326;21012.89493552534;5986.07215881538;6211.6273023618505;6219.844503887393;6221.751129954796;6230.5638605297645;6031.155638210654;6068.939310606882;7192.856142877139;7499.13759917611;7540.359069479101;7541.585212813293;7562;7607;7610.581418022007;7669.539827610333;7557.395408367317;7563.659635316424;7653.464257501949;7712.614369281569;7743.303102720783;7707.229277072304;7778.261065198797;7863.764087077397;7864.41016923733;7898.881516777253;28487;28587;28920.710792892012;29166.41667166666;29717.851410743024;2693.905713300025;2698.5952107183884;2889.9711002889912;2980.6423229212633;3016.125323656137;16753.413630522995;17171.656566868594;17245.482635520955;17486;17849.7322540162;2145.924892628766;2217.135317226267;2224.165937773335;2230.933072007826;2238.8992495337748;99431.50284248605;102694.48652756763;105028.47485762522;106521.99999999923;107406.46296768544;18155.909220453814;18314.725279120823;18819.52951176204;19173;19256.22975081009;34818;34885.65114348875;34934.30131397358;35094;35272.118197044765;10995.175361847894;11365.829512557319;11423.971842534213;11534.307941523535;11785.175037747447;9640.469774162488;9662.855057174149;9689.757756056155;9863.704088877344;9865.704028879145;9439.24486041115;9482.288828337902;9522.85725712919;9760.951195243979;9974.900250997542;3263.738900887923;3299.769016168845;3328.700416962485;3487.163080860575;3601.3028536085585;985.9408435493893;1020.0921180149672;1065.9040686338267;1077.989220107789;1108.3294606762954;20944.895275523526;21038.78961210399;21041.684374734392;21108.366748997556;21134.365969020953;1080.5189660295168;1090.1496832470687;1101.2071308657753;1109.9334039957628;1125.3248051169396;10822;11634.592789252336;11697.473613687405;11789.587364442203;11854.229475084092;519536;529641;530660;536024;538230;59911.40088599058;61856;100005.49997249967;102173.97826021793;102652.48673756733;1060.5916722061907;1095.5070218401752;1104.8563686720674;1141.680329507739;1150.6087930103638;17941.461756147335;18613.348532801287;18979;19099.80900191036;19296.71054934149;44604.553954461335;44756.55243447524;44934.32598510959;45233.77383113063;46529.76735116303;67028.6648566754;68072.6596367015;69283;69413;69736.30263697298;9169.312301577542;9248.121428464285;9288.860667089864;9309.115634014757;9378.249740020918;9983.351082179635;12665.113442058968;12974.935125324691;13006.479740810262;13228.537001204912;11451;11582.594609188638;11583.594574189863;11805.940970295094;12017.879821201675;11260.774784504429;11285;11333.093352531943;11353.545858165911;11618.6514404568;11493.597724079616;11566.132540059361;11685.298882067103;11723.237989530653;11730.237534560227;9173.816523669622;9182.449053056838;9228.630854765734;9420.576074076545;9532.475713835805;10869.347839129678;11457.484413201593;11471.139664525028;11488.080953523531;11782.82325765097 01:46:13 INFO - PROCESS | 2915 | |3;mootools.html;1537.03167004787;1556.1596737761608;1743.9040852753094;1813.7370081338215;1875.174923033865;8064.71773487928;8990.280777537797;9291.48896810675;9782.364146330492;9787.119159275664;9336.906630933689;9684.951575242132;9694.321397502177;9697.515124243777;9748.805023899518;2459.86470744109;2486.5648511510503;2495.201535508638;2502.649629051934;2505;2377.8335516513853;2394.353524548371;2396.5686176488225;2405.482821193443;2414.770596793302;2425.320910145162;2437.1591800828687;2482.2801387597665;2504.1485894795765;2508.1723031399683;2444.1445494077107;2447.278052974377;2462.8645424501556;2473.591857343545;2481.8759062046915;2443.8900249488815;2448.5592593333195;2454.275988583372;2458;2493.8254322197454;9391.577379017961;9473.242140628734;9523.476208808514;9549.665761698307;9586.37688550249;5668.093105103164;5802.216700745387;5803.82588522345;5822.505087067604;5848.035074212792;3493.371193185226;3688.17016171362;3706.796126213057;3714.182879766447;3741.0834345585504;1172.8299396587533;1199.412287978893;1202.4649031181127;1205.2166092040156;1223.8041913293919;13472.25902575358;13560.050796444253;13562.660933476644;13566.389512471962;13579.185248885196;4883.58489528387;4971.9254211186135;4987.925181122214;5021.14640511114;5118.411382690963;11373.829392559119;11394.658160255205;11396.088312935111;11398.316101033799;11407.144464165054;5777.768889244383;5780.768769249267;5799.9130013049835;5800.303963524404;5807.070868660994;4587.266037434061;5081.822136225214;5147.3308469898675;5158.200478925773;5304.655197412233;4199.97900010498;4287.970886987163;4311.741295522279;4347.7173983690955;4500.504944456107;1395.6789938314041;1422.8150340455675;1425.109306683323;1430.342042660388;1438.9424423022963;1006.225206590921;1052.4842827014709;1057.101463755817;1059.6185373265619;1087.8259478483405;6161.6611086389785;6374.490040796725;6550.410463058348;6567.047778072249;6597.802065937934;472.789608624159;485.3859867267909;489.1781806564934;495.31893646236426;517.7592419524939;4454.665900057444;4490.281554951257;4499.1451624191295;4516.1419330327135;4538.432695913011;11842.407879605986;12242.44908979098;12283.078769092353;12299.877001230052;12304.692382690504;11364.715882103013;11933.940330298467;11939.044876409866;12140.21088629254;12303;8874.511901845332;8973.955130224307;9036.367454278137;9048.50233237165;9114.908850911406;6477.125588045554;6495.220573531206;6780.32196780318;6782.796516104525;6788.660566971533;10737.624183153552;11137.94431027872;11163.274387164809;11393.943030284796;11434.714132146762;10737.677869663923;11308.49111789988;11529.192956492965;11574;11679.708007299885;7712.113106992543;8224.76628505705;8332.958335208285;8557.358198135289;8774.912250877409;8951;8985.595648195716;8987.730368088967;9001.099890010946;9002.324825637972;8254.669813207405;8360.205780450848;8366.330693544624;8377.790555235924;8383.874241886499;8110;8117.025956884975;8145.104038555635;8155.266026057683;8202.917970820454;7323.2676732326345;7336.559806411632;7430.405567554691;7473.252674732484;7504;5615.915761263503;5675;5747.08046712524;5822.5633077518505;5865.442782935615;5658.349289831557;5710.314762228559;5731.054376027909;5780.132980053026;5780.710964451845 01:46:13 INFO - PROCESS | 2915 | |4;prototype.html;329.9472084466486;338.67148865600365;342.6333822809594;343.8229311904368;344.00239306012577;5883.411658834115;5937.584369094155;5955.315138759033;5965.284165900092;5971.552133589977;5768.567357448198;5775.797847075342;5795.623284486505;5801.216835727176;5821.767129314833;4282.764447955354;4427.756473393962;4487.798049087783;4526.6831321807485;4552.180607490651;4511.571400716943;4514.683972121937;4525.208088584501;4547.567516232392;4551.795169217393;4538.410006699124;4539.500654927955;4542.4776150742755;4550.567696068868;4566.84016059438;4267.573242675739;4475.440569928759;4484.461864576256;4504.932426013613;4523.819047238107;3888.8833334999995;3905.258000979805;3917.941230881539;3925.31307021273;3937.0747874249814;5683.573731970118;5708.600397972144;5710.257666503369;5740.282464691913;5746.310442746897;4992.251162325644;5091.745412729386;5101.520559037875;5130.71781052042;5131.4098878629065;6023.759049638009;6032.004719221323;6045.637261764265;6089.543284253698;6122.020476723747;4233.682473814476;4310.762908040057;4312.82748690052;4315.697901146921;4318.373835793793;6144.446999770043;6205.627662340229;6220.253569571635;6241.843953901189;6300.936990630126;4315.892102697458;4356.520782713899;4367.344898265222;4369.344598310279;4369.759663218553;4223.345381465878;4358.084802191512;4359.346098085312;4359.825606975685;4374.78126093694;5041.495850415003;5049.192129259374;5049.84850454487;5067.417247016567;5072.163093089673;6008.429199226067;6089.2997305310455;6118.418750218722;6125.753350724123;6135.785247516315;1511.9697606047816;1518.1498360917922;1531.663034132489;1544.980312993419;1560.9375624974873;962.831504486712;970.3304719743354;1011.1001208924064;1026.1226651213256;1039.5062345385882;5839.5036421904615;5862.824115276549;5867.119932010147;5886.23478947734;5903.1145328200255;6182.907256391068;6228.4705800007505;6250.468710159596;6281.811545653638;6305.5901366411035;5678.133178393888;5867.178594996704;5895.233619629421;5895.380984996599;5922.052471604608;3344.8829290975186;3346.6486018968144;3350.547676063749;3361.277325375056;3373.207296285371;6291.339409361951;6462.612243265419;6463.547551671338;6545.083688283644;6574.737010519621;6404.583702059351;6475.352464753487;6478.805635830932;6503.577267477599;6505.316941721051;6292.182016337938;6475.384838440341;6575;6608.257573107203;6651.268360480343;6289.937100628935;6292.559520833498;6298.653574053471;6311.337309582428;6324.873502529832;6172.722227499863;6322.861884860769;6333.556651034383;6337.162222955291;6353.8880695877115;6528.902066469102;6573.441257493167;6591.472682185509;6621.172353455818;6656.700448479927;4443.600075993176;4470.821167153407;4474.284114541658;4482.663800214932;4487.439070116236;6203.844903877439;6331.366863313632;6331.620102793847;6451.935480645134;6467.741290348334;6166.753329866934;6190.81427557174;6217.7823776169425;6260.154879091447;6261.937380626136;5937.6249500200265;6190.907136393048;6199.636080062379;6203.596766210182;6213.720382582885;5972.522198224045;5972.761089556369;6172.6913654318005;6231.813045608639;6278.748850045947;6218.440340369389;6238.594491358047;6264.467520260647;6298.275698294755;6306.558540902276;5983.730732117152;6098.377697590079;6101.420365065312;6110.541709371726;6111.84720381994 01:46:13 INFO - PROCESS | 2915 | |5;yui.html;827.2843989948689;828.329053466692;870.9896520036758;918.3112665500875;954.7040417470582;16757.49727508175;16955.237014334354;17102.230399632015;18045.81954180458;18346.816531834676;15580.610484737887;15672.529824105266;15689.45086921958;15741.134237616927;15765.60585985351;864.4899509289518;868.8609822428413;881.0264657553407;882.7219425880859;883.8939327280704;894.4856707393243;899.2715900120922;902.3638334973828;903.6972614174259;905.0361365146098;950.9966984831001;952.2382094324547;953.1564565359637;954.5847556312991;955.2596737528436;949.7055912667096;950.0736781637917;950.8193443245796;951.029949451559;951.8810148731409;697.2191145916606;701.6912558474255;704.1655638068904;705.8941158826166;706.5301574452983;17604.119794010247;17692.557686057822;17842.821571784247;17852.55368615782;17911;15830.9208453957;16260.51218463448;16368.75446868286;16522.75215871763;16697.332106715716;13731.176129432266;14112.294385280695;14210.289485525785;14278.428862845474;14457.493987710379;4034;4107.138407873427;4109;4190.308599081148;4278.4010238566625;77955.22044779536;79920.60039699764;80134.19865801383;80598.59700701518;81068.59465702693;11749.706257343634;11888.405579721153;11973.341466219268;12072.215306005082;12406.875931240573;23475;23525.647115293283;23675.881620591786;23686.881565592063;23698.881505592362;9361.62553497866;9519.428834269827;9546.284028697875;9686.467244301632;9711.465869377253;5288.3918349390315;5307.7080760557765;5351.491608297206;5356.143017117242;5420.273359890573;3775.8867233983024;3903.6291552302487;3905.824237909301;3906.374980003243;3916.3342231820657;708.3023222126202;709.4395427612232;712.2485777504783;731.1482123326372;731.2249016043012;675.560885424478;766.3868904876077;770.951505951905;825.0511911302051;829.2495291760966;9685.951570242103;10834.67495975122;10887.782244355069;10957.890421095846;10966.561337546409;805.90732065812;808.8261023879894;829.7303376402693;850.8213275212274;863.7840539865034;4871.196252618349;4936.654434189572;4949.7772600233075;4958.727270000185;4979.875503112378;70464.64767676129;70855;70922;74264.25735742682;79184.60407697925;116578.41710791392;116600.41699791446;119079;121108.39445802891;124484;102524;104643;105996;107284;110515.44742276237;9763.072508111718;9813.607455701693;9909;9944.651937182163;9986.290210415593;60443.69778151081;61289.387106128364;61444.692776535834;62564.68717656383;63443;91355.54322228613;93388;93557;94012.52993734987;94444;12658.683532911751;12959.35203239853;13578.728425431633;13612.319384030561;13886.583402497941;15000.849991499945;15389.230538472808;15488.303026363616;15567.299471524035;15828.366865325257;13966.231857247949;13995.51015714445;14207.64480887986;14240.572782816533;14593;14027.929860350632;14084.295785210494;14118.717625647225;14265.57203283903;14508.107677128146;13463.663408414868;13749;13927.442902283796;14319.909041948784;14550.563483095524;11901.880981190423;11997.940010299893;11999.460024298753;12157.08821838348;12176.75646487083;11721.413929303671;11814.409279536161;11831.585894493652;12165.33090680021;12277.815832762695 01:46:13 INFO - PROCESS | 2915 | __end_tp_report 01:46:13 INFO - PROCESS | 2915 | __start_cc_report 01:46:13 INFO - PROCESS | 2915 | _x_x_mozilla_cycle_collect,341 01:46:13 INFO - PROCESS | 2915 | __end_cc_report 01:46:13 INFO - PROCESS | 2915 | __startTimestamp1474361173622__endTimestamp 01:46:13 INFO - PROCESS | 2915 | 01:46:13 INFO - PROCESS | 2915 | ------- Summary: start ------- 01:46:13 INFO - PROCESS | 2915 | Number of tests: 6 01:46:13 INFO - PROCESS | 2915 | 01:46:13 INFO - PROCESS | 2915 | [#0] dojo.html Cycles:165 Average:5790.03 Median:5977.79 stddev:664.52 (11.1%) stddev-sans-first:636.77 01:46:13 INFO - PROCESS | 2915 | Values: 3282.1 3316.7 3316.7 3341.2 3345.7 5787.0 5901.4 5923.8 5924.6 5929.1 5858.6 5905.0 5907.1 5913.0 5936.2 5316.8 5374.4 5392.6 5435.6 5455.3 5220.8 5232.0 5234.5 5262.7 5313.3 5323.1 5334.4 5341.0 5352.4 5401.3 5392.6 5393.4 5396.3 5403.6 5431.1 5067.0 5161.0 5199.8 5305.2 5306.3 5811.1 5837.2 5838.7 5855.7 5899.1 6259.6 6279.3 6305.0 6305.3 6309.9 5968.3 6108.5 6125.3 6341.8 6346.6 5816.3 5825.2 5836.8 5857.8 5859.7 6355.1 6362.7 6375.4 6377.7 6384.2 5915.9 5999.8 6021.8 6059.6 6075.2 6203.2 6208.8 6218.2 6219.6 6231.2 6204.1 6212.5 6213.8 6215.3 6235.9 5950.8 6048.9 6055.7 6205.4 6223.0 4714.6 4715.6 4721.7 4741.9 4743.4 4203.4 4206.8 4207.0 4232.1 4240.3 5768.1 5818.9 5839.9 5879.7 5899.8 6266.8 6310.2 6310.7 6312.7 6323.5 5972.6 5975.0 5976.5 5977.8 6011.3 5301.0 5344.9 5367.9 5407.8 5425.0 6505.3 6506.4 6507.5 6515.9 6522.6 6270.5 6271.2 6273.8 6276.3 6308.0 6210.4 6223.1 6331.8 6374.9 6470.8 6163.4 6202.4 6202.8 6203.0 6203.6 6320.3 6321.3 6322.4 6325.4 6333.8 5920.1 5984.8 6024.5 6054.9 6147.0 6112.4 6121.4 6123.2 6125.0 6130.0 6036.1 6046.5 6064.5 6098.7 6100.8 5950.1 5989.0 6042.2 6047.0 6124.8 5916.8 5936.8 5945.0 5947.0 5986.1 01:46:13 INFO - PROCESS | 2915 | 01:46:13 INFO - PROCESS | 2915 | [#1] ext.html Cycles:180 Average:65729.54 Median:45253.19 stddev:138829.46 (306.8%) stddev-sans-first:139132.76 01:46:13 INFO - PROCESS | 2915 | Values: 588.8 638.8 639.6 662.5 692.1 42841.6 47465.0 47773.0 47783.0 47822.3 47708.0 48036.8 48186.5 48275.8 48277.3 9163.5 9225.4 9247.6 9251.9 9307.9 9218.7 9310.9 9320.3 9328.9 9329.4 9321.0 9376.5 9387.7 9392.7 9425.9 9344.4 9376.4 9378.2 9392.9 9394.7 9215.5 9277.3 9285.0 9290.1 9297.2 45320.3 45352.8 45576.0 45674.0 45861.0 3535.7 3643.1 3854.8 4296.4 4626.3 36860.6 37124.0 37305.3 37432.6 37469.8 4748.4 4792.1 4795.6 4905.1 4940.9 410526.0 414399.0 419637.0 420457.9 422478.0 38684.4 39663.8 39711.4 69223.7 69621.0 41407.8 41627.8 41679.8 41750.4 41768.2 16919.0 16985.9 16989.2 17044.1 17134.8 15203.5 15212.5 15218.3 15247.5 15297.1 9394.4 9484.9 9486.4 9525.2 9528.4 3565.7 3601.7 3672.8 3673.6 3692.7 1146.9 1151.0 1157.4 1159.3 1219.7 34815.8 35058.5 35169.5 35583.1 35839.6 1198.8 1201.5 1209.0 1237.5 1270.4 17882.9 18430.2 18524.8 18626.9 18627.7 173278.0 690565.0 691220.0 692951.0 697021.0 43965.1 44277.8 44592.3 44657.8 44792.6 1444.7 1457.6 1481.5 1497.5 1503.0 32878.5 33293.0 33452.3 33622.3 33905.8 57521.7 58106.0 58159.7 58204.7 58264.0 492259.0 494152.0 495837.0 496929.0 501890.0 31900.0 32681.2 32694.7 32842.0 32908.5 53716.0 54022.0 54435.5 54744.5 54986.7 45949.5 45954.0 46749.5 47049.1 47962.8 46239.8 47045.5 47194.3 47662.8 47683.5 44988.0 45186.1 45707.5 46050.5 46254.0 39998.8 40516.4 40869.0 41186.8 41235.6 30267.5 30667.7 30679.5 30808.3 30909.0 01:46:13 INFO - PROCESS | 2915 | 01:46:13 INFO - PROCESS | 2915 | [#2] jquery.html Cycles:180 Average:32864.58 Median:2696.25 stddev:87518.63 (3245.9%) stddev-sans-first:87730.20 01:46:13 INFO - PROCESS | 2915 | Values: 400.1 403.9 414.7 418.7 429.4 19145.4 20706.0 20809.2 21140.4 21414.6 20302.7 20502.5 20733.8 20790.4 21012.9 5986.1 6211.6 6219.8 6221.8 6230.6 6031.2 6068.9 7192.9 7499.1 7540.4 7541.6 7562.0 7607.0 7610.6 7669.5 7557.4 7563.7 7653.5 7712.6 7743.3 7707.2 7778.3 7863.8 7864.4 7898.9 28487.0 28587.0 28920.7 29166.4 29717.9 2693.9 2698.6 2890.0 2980.6 3016.1 16753.4 17171.7 17245.5 17486.0 17849.7 2145.9 2217.1 2224.2 2230.9 2238.9 99431.5 102694.5 105028.5 106522.0 107406.5 18155.9 18314.7 18819.5 19173.0 19256.2 34818.0 34885.7 34934.3 35094.0 35272.1 10995.2 11365.8 11424.0 11534.3 11785.2 9640.5 9662.9 9689.8 9863.7 9865.7 9439.2 9482.3 9522.9 9761.0 9974.9 3263.7 3299.8 3328.7 3487.2 3601.3 985.9 1020.1 1065.9 1078.0 1108.3 20944.9 21038.8 21041.7 21108.4 21134.4 1080.5 1090.1 1101.2 1109.9 1125.3 10822.0 11634.6 11697.5 11789.6 11854.2 519536.0 529641.0 530660.0 536024.0 538230.0 59911.4 61856.0 100005.5 102174.0 102652.5 1060.6 1095.5 1104.9 1141.7 1150.6 17941.5 18613.3 18979.0 19099.8 19296.7 44604.6 44756.6 44934.3 45233.8 46529.8 67028.7 68072.7 69283.0 69413.0 69736.3 9169.3 9248.1 9288.9 9309.1 9378.2 9983.4 12665.1 12974.9 13006.5 13228.5 11451.0 11582.6 11583.6 11805.9 12017.9 11260.8 11285.0 11333.1 11353.5 11618.7 11493.6 11566.1 11685.3 11723.2 11730.2 9173.8 9182.4 9228.6 9420.6 9532.5 10869.3 11457.5 11471.1 11488.1 11782.8 01:46:13 INFO - PROCESS | 2915 | 01:46:13 INFO - PROCESS | 2915 | [#3] mootools.html Cycles:180 Average:6306.54 Median:2530.03 stddev:3651.28 (144.3%) stddev-sans-first:3643.93 01:46:13 INFO - PROCESS | 2915 | Values: 1537.0 1556.2 1743.9 1813.7 1875.2 8064.7 8990.3 9291.5 9782.4 9787.1 9336.9 9685.0 9694.3 9697.5 9748.8 2459.9 2486.6 2495.2 2502.6 2505.0 2377.8 2394.4 2396.6 2405.5 2414.8 2425.3 2437.2 2482.3 2504.1 2508.2 2444.1 2447.3 2462.9 2473.6 2481.9 2443.9 2448.6 2454.3 2458.0 2493.8 9391.6 9473.2 9523.5 9549.7 9586.4 5668.1 5802.2 5803.8 5822.5 5848.0 3493.4 3688.2 3706.8 3714.2 3741.1 1172.8 1199.4 1202.5 1205.2 1223.8 13472.3 13560.1 13562.7 13566.4 13579.2 4883.6 4971.9 4987.9 5021.1 5118.4 11373.8 11394.7 11396.1 11398.3 11407.1 5777.8 5780.8 5799.9 5800.3 5807.1 4587.3 5081.8 5147.3 5158.2 5304.7 4200.0 4288.0 4311.7 4347.7 4500.5 1395.7 1422.8 1425.1 1430.3 1438.9 1006.2 1052.5 1057.1 1059.6 1087.8 6161.7 6374.5 6550.4 6567.0 6597.8 472.8 485.4 489.2 495.3 517.8 4454.7 4490.3 4499.1 4516.1 4538.4 11842.4 12242.4 12283.1 12299.9 12304.7 11364.7 11933.9 11939.0 12140.2 12303.0 8874.5 8974.0 9036.4 9048.5 9114.9 6477.1 6495.2 6780.3 6782.8 6788.7 10737.6 11137.9 11163.3 11393.9 11434.7 10737.7 11308.5 11529.2 11574.0 11679.7 7712.1 8224.8 8333.0 8557.4 8774.9 8951.0 8985.6 8987.7 9001.1 9002.3 8254.7 8360.2 8366.3 8377.8 8383.9 8110.0 8117.0 8145.1 8155.3 8202.9 7323.3 7336.6 7430.4 7473.3 7504.0 5615.9 5675.0 5747.1 5822.6 5865.4 5658.3 5710.3 5731.1 5780.1 5780.7 01:46:13 INFO - PROCESS | 2915 | 01:46:13 INFO - PROCESS | 2915 | [#4] prototype.html Cycles:180 Average:5150.13 Median:5895.31 stddev:1547.59 (26.3%) stddev-sans-first:1509.05 01:46:13 INFO - PROCESS | 2915 | Values: 329.9 338.7 342.6 343.8 344.0 5883.4 5937.6 5955.3 5965.3 5971.6 5768.6 5775.8 5795.6 5801.2 5821.8 4282.8 4427.8 4487.8 4526.7 4552.2 4511.6 4514.7 4525.2 4547.6 4551.8 4538.4 4539.5 4542.5 4550.6 4566.8 4267.6 4475.4 4484.5 4504.9 4523.8 3888.9 3905.3 3917.9 3925.3 3937.1 5683.6 5708.6 5710.3 5740.3 5746.3 4992.3 5091.7 5101.5 5130.7 5131.4 6023.8 6032.0 6045.6 6089.5 6122.0 4233.7 4310.8 4312.8 4315.7 4318.4 6144.4 6205.6 6220.3 6241.8 6300.9 4315.9 4356.5 4367.3 4369.3 4369.8 4223.3 4358.1 4359.3 4359.8 4374.8 5041.5 5049.2 5049.8 5067.4 5072.2 6008.4 6089.3 6118.4 6125.8 6135.8 1512.0 1518.1 1531.7 1545.0 1560.9 962.8 970.3 1011.1 1026.1 1039.5 5839.5 5862.8 5867.1 5886.2 5903.1 6182.9 6228.5 6250.5 6281.8 6305.6 5678.1 5867.2 5895.2 5895.4 5922.1 3344.9 3346.6 3350.5 3361.3 3373.2 6291.3 6462.6 6463.5 6545.1 6574.7 6404.6 6475.4 6478.8 6503.6 6505.3 6292.2 6475.4 6575.0 6608.3 6651.3 6289.9 6292.6 6298.7 6311.3 6324.9 6172.7 6322.9 6333.6 6337.2 6353.9 6528.9 6573.4 6591.5 6621.2 6656.7 4443.6 4470.8 4474.3 4482.7 4487.4 6203.8 6331.4 6331.6 6451.9 6467.7 6166.8 6190.8 6217.8 6260.2 6261.9 5937.6 6190.9 6199.6 6203.6 6213.7 5972.5 5972.8 6172.7 6231.8 6278.7 6218.4 6238.6 6264.5 6298.3 6306.6 5983.7 6098.4 6101.4 6110.5 6111.8 01:46:13 INFO - PROCESS | 2915 | 01:46:13 INFO - PROCESS | 2915 | [#5] yui.html Cycles:180 Average:22285.55 Median:4108.07 stddev:31595.61 (769.1%) stddev-sans-first:31643.16 01:46:13 INFO - PROCESS | 2915 | Values: 827.3 828.3 871.0 918.3 954.7 16757.5 16955.2 17102.2 18045.8 18346.8 15580.6 15672.5 15689.5 15741.1 15765.6 864.5 868.9 881.0 882.7 883.9 894.5 899.3 902.4 903.7 905.0 951.0 952.2 953.2 954.6 955.3 949.7 950.1 950.8 951.0 951.9 697.2 701.7 704.2 705.9 706.5 17604.1 17692.6 17842.8 17852.6 17911.0 15830.9 16260.5 16368.8 16522.8 16697.3 13731.2 14112.3 14210.3 14278.4 14457.5 4034.0 4107.1 4109.0 4190.3 4278.4 77955.2 79920.6 80134.2 80598.6 81068.6 11749.7 11888.4 11973.3 12072.2 12406.9 23475.0 23525.6 23675.9 23686.9 23698.9 9361.6 9519.4 9546.3 9686.5 9711.5 5288.4 5307.7 5351.5 5356.1 5420.3 3775.9 3903.6 3905.8 3906.4 3916.3 708.3 709.4 712.2 731.1 731.2 675.6 766.4 771.0 825.1 829.2 9686.0 10834.7 10887.8 10957.9 10966.6 805.9 808.8 829.7 850.8 863.8 4871.2 4936.7 4949.8 4958.7 4979.9 70464.6 70855.0 70922.0 74264.3 79184.6 116578.4 116600.4 119079.0 121108.4 124484.0 102524.0 104643.0 105996.0 107284.0 110515.4 9763.1 9813.6 9909.0 9944.7 9986.3 60443.7 61289.4 61444.7 62564.7 63443.0 91355.5 93388.0 93557.0 94012.5 94444.0 12658.7 12959.4 13578.7 13612.3 13886.6 15000.8 15389.2 15488.3 15567.3 15828.4 13966.2 13995.5 14207.6 14240.6 14593.0 14027.9 14084.3 14118.7 14265.6 14508.1 13463.7 13749.0 13927.4 14319.9 14550.6 11901.9 11997.9 11999.5 12157.1 12176.8 11721.4 11814.4 11831.6 12165.3 12277.8 01:46:13 INFO - PROCESS | 2915 | -------- Summary: end -------- 01:46:13 INFO - PROCESS | 2915 | 01:46:14 INFO - TEST-INFO | 2915: exit 0 01:46:14 INFO - TEST-OK | dromaeo_css | took 1110581ms 01:46:14 INFO - TEST-START | kraken 01:46:14 INFO - operating with platform_type : linux_ 01:46:14 INFO - Initialising browser for kraken test... 01:46:14 INFO - TEST-INFO | started process 3166 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLm4e28/profile http://localhost:41772/getInfo.html) 01:46:18 INFO - PROCESS | 3166 | __metrics Screen width/height:1600/1200 01:46:18 INFO - PROCESS | 3166 | colorDepth:24 01:46:18 INFO - PROCESS | 3166 | Browser inner width/height: 1024/697 01:46:18 INFO - PROCESS | 3166 | __metrics 01:46:19 ERROR - PROCESS | 3166 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 01:46:19 INFO - TEST-INFO | 3166: exit 0 01:46:19 INFO - Browser initialized. 01:46:19 INFO - Running cycle 1/1 for kraken test... 01:46:19 INFO - TEST-INFO | started process 3290 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLm4e28/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:46:31 INFO - PROCESS | 3290 | RSS: Main: 150503424 01:46:31 INFO - PROCESS | 3290 | 01:46:33 INFO - PROCESS | 3290 | Cycle 1(1): loaded http://localhost:41772/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41772/tests/kraken/driver.html?testName=audio-beat-detection) 01:46:34 INFO - PROCESS | 3290 | RSS: Main: 156975104 01:46:34 INFO - PROCESS | 3290 | 01:46:37 INFO - PROCESS | 3290 | Cycle 1(1): loaded http://localhost:41772/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41772/tests/kraken/driver.html?testName=audio-dft) 01:46:38 INFO - PROCESS | 3290 | RSS: Main: 155684864 01:46:38 INFO - PROCESS | 3290 | 01:46:40 INFO - PROCESS | 3290 | Cycle 1(1): loaded http://localhost:41772/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41772/tests/kraken/driver.html?testName=audio-fft) 01:46:41 INFO - PROCESS | 3290 | RSS: Main: 156704768 01:46:41 INFO - PROCESS | 3290 | 01:46:43 INFO - PROCESS | 3290 | Cycle 1(1): loaded http://localhost:41772/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41772/tests/kraken/driver.html?testName=audio-oscillator) 01:46:44 INFO - PROCESS | 3290 | RSS: Main: 156565504 01:46:44 INFO - PROCESS | 3290 | 01:46:46 INFO - PROCESS | 3290 | Cycle 1(1): loaded http://localhost:41772/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41772/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:46:47 INFO - PROCESS | 3290 | RSS: Main: 155725824 01:46:47 INFO - PROCESS | 3290 | 01:46:50 INFO - PROCESS | 3290 | Cycle 1(1): loaded http://localhost:41772/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41772/tests/kraken/driver.html?testName=imaging-darkroom) 01:46:50 INFO - PROCESS | 3290 | RSS: Main: 156401664 01:46:50 INFO - PROCESS | 3290 | 01:46:54 INFO - PROCESS | 3290 | Cycle 1(1): loaded http://localhost:41772/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41772/tests/kraken/driver.html?testName=imaging-desaturate) 01:46:54 INFO - PROCESS | 3290 | RSS: Main: 156708864 01:46:54 INFO - PROCESS | 3290 | 01:46:57 INFO - PROCESS | 3290 | Cycle 1(1): loaded http://localhost:41772/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41772/tests/kraken/driver.html?testName=json-parse-financial) 01:46:58 INFO - PROCESS | 3290 | RSS: Main: 156291072 01:46:58 INFO - PROCESS | 3290 | 01:46:59 INFO - PROCESS | 3290 | Cycle 1(1): loaded http://localhost:41772/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41772/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:47:00 INFO - PROCESS | 3290 | RSS: Main: 155750400 01:47:00 INFO - PROCESS | 3290 | 01:47:02 INFO - PROCESS | 3290 | Cycle 1(1): loaded http://localhost:41772/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41772/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:47:02 INFO - PROCESS | 3290 | RSS: Main: 155885568 01:47:02 INFO - PROCESS | 3290 | 01:47:04 INFO - PROCESS | 3290 | Cycle 1(1): loaded http://localhost:41772/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41772/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:47:05 INFO - PROCESS | 3290 | RSS: Main: 157007872 01:47:05 INFO - PROCESS | 3290 | 01:47:07 INFO - PROCESS | 3290 | Cycle 1(1): loaded http://localhost:41772/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41772/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:47:08 INFO - PROCESS | 3290 | RSS: Main: 156229632 01:47:08 INFO - PROCESS | 3290 | 01:47:11 INFO - PROCESS | 3290 | Cycle 1(1): loaded http://localhost:41772/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41772/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:47:11 INFO - PROCESS | 3290 | RSS: Main: 156549120 01:47:11 INFO - PROCESS | 3290 | 01:47:13 INFO - PROCESS | 3290 | Cycle 1(1): loaded http://localhost:41772/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41772/tests/kraken/driver.html?testName=ai-astar) 01:47:13 INFO - PROCESS | 3290 | RSS: Main: 158572544 01:47:13 INFO - PROCESS | 3290 | 01:47:13 INFO - PROCESS | 3290 | __start_tp_report 01:47:13 INFO - PROCESS | 3290 | _x_x_mozilla_page_load 01:47:13 INFO - PROCESS | 3290 | _x_x_mozilla_page_load_details 01:47:13 INFO - PROCESS | 3290 | |i|pagename|runs| 01:47:13 INFO - PROCESS | 3290 | |0;ai-astar;101;111;105;111;111;113;110;112;112;110 01:47:13 INFO - PROCESS | 3290 | |1;audio-beat-detection;144;119;152;189;116;148;145;122;176;120 01:47:13 INFO - PROCESS | 3290 | |2;audio-dft;163;163;165;163;161;162;163;162;164;165 01:47:13 INFO - PROCESS | 3290 | |3;audio-fft;74;75;76;176;76;74;74;75;162;77 01:47:13 INFO - PROCESS | 3290 | |4;audio-oscillator;84;84;145;83;82;83;145;83;83;149 01:47:13 INFO - PROCESS | 3290 | |5;imaging-gaussian-blur;117;117;119;117;117;117;117;117;118;117 01:47:13 INFO - PROCESS | 3290 | |6;imaging-darkroom;147;147;150;147;148;147;147;147;147;148 01:47:13 INFO - PROCESS | 3290 | |7;imaging-desaturate;95;95;96;95;95;95;95;95;95;95 01:47:13 INFO - PROCESS | 3290 | |8;json-parse-financial;57;53;55;52;56;53;58;53;53;61 01:47:13 INFO - PROCESS | 3290 | |9;json-stringify-tinderbox;60;61;61;60;61;59;60;60;63;61 01:47:13 INFO - PROCESS | 3290 | |10;stanford-crypto-aes;81;73;73;72;79;72;73;74;75;77 01:47:13 INFO - PROCESS | 3290 | |11;stanford-crypto-ccm;122;119;116;118;128;129;116;119;162;127 01:47:13 INFO - PROCESS | 3290 | |12;stanford-crypto-pbkdf2;155;157;153;152;149;154;155;151;149;155 01:47:13 INFO - PROCESS | 3290 | |13;stanford-crypto-sha256-iterative;62;64;62;63;62;64;62;60;60;64 01:47:13 INFO - PROCESS | 3290 | __end_tp_report 01:47:13 INFO - PROCESS | 3290 | __start_cc_report 01:47:13 INFO - PROCESS | 3290 | _x_x_mozilla_cycle_collect,855 01:47:13 INFO - PROCESS | 3290 | __end_cc_report 01:47:13 INFO - PROCESS | 3290 | __startTimestamp1474361233714__endTimestamp 01:47:13 INFO - PROCESS | 3290 | 01:47:13 INFO - PROCESS | 3290 | ------- Summary: start ------- 01:47:13 INFO - PROCESS | 3290 | Number of tests: 14 01:47:13 INFO - PROCESS | 3290 | 01:47:13 INFO - PROCESS | 3290 | [#0] ai-astar Cycles:10 Average:109.60 Median:111.00 stddev:3.72 (3.3%) stddev-sans-first:2.30 01:47:13 INFO - PROCESS | 3290 | Values: 101.0 111.0 105.0 111.0 111.0 113.0 110.0 112.0 112.0 110.0 01:47:13 INFO - PROCESS | 3290 | 01:47:13 INFO - PROCESS | 3290 | [#1] audio-beat-detection Cycles:10 Average:143.10 Median:146.50 stddev:24.92 (17.0%) stddev-sans-first:26.43 01:47:13 INFO - PROCESS | 3290 | Values: 144.0 119.0 152.0 189.0 116.0 148.0 145.0 122.0 176.0 120.0 01:47:13 INFO - PROCESS | 3290 | 01:47:13 INFO - PROCESS | 3290 | [#2] audio-dft Cycles:10 Average:163.10 Median:163.00 stddev:1.29 (0.8%) stddev-sans-first:1.36 01:47:13 INFO - PROCESS | 3290 | Values: 163.0 163.0 165.0 163.0 161.0 162.0 163.0 162.0 164.0 165.0 01:47:13 INFO - PROCESS | 3290 | 01:47:13 INFO - PROCESS | 3290 | [#3] audio-fft Cycles:10 Average:93.90 Median:75.00 stddev:39.73 (53.0%) stddev-sans-first:41.48 01:47:13 INFO - PROCESS | 3290 | Values: 74.0 75.0 76.0 176.0 76.0 74.0 74.0 75.0 162.0 77.0 01:47:13 INFO - PROCESS | 3290 | 01:47:13 INFO - PROCESS | 3290 | [#4] audio-oscillator Cycles:10 Average:102.10 Median:83.00 stddev:30.55 (36.8%) stddev-sans-first:31.69 01:47:13 INFO - PROCESS | 3290 | Values: 84.0 84.0 145.0 83.0 82.0 83.0 145.0 83.0 83.0 149.0 01:47:13 INFO - PROCESS | 3290 | 01:47:13 INFO - PROCESS | 3290 | [#5] imaging-gaussian-blur Cycles:10 Average:117.30 Median:117.00 stddev:0.67 (0.6%) stddev-sans-first:0.71 01:47:13 INFO - PROCESS | 3290 | Values: 117.0 117.0 119.0 117.0 117.0 117.0 117.0 117.0 118.0 117.0 01:47:13 INFO - PROCESS | 3290 | 01:47:13 INFO - PROCESS | 3290 | [#6] imaging-darkroom Cycles:10 Average:147.50 Median:147.00 stddev:0.97 (0.7%) stddev-sans-first:1.01 01:47:13 INFO - PROCESS | 3290 | Values: 147.0 147.0 150.0 147.0 148.0 147.0 147.0 147.0 147.0 148.0 01:47:13 INFO - PROCESS | 3290 | 01:47:13 INFO - PROCESS | 3290 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 01:47:13 INFO - PROCESS | 3290 | Values: 95.0 95.0 96.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 01:47:13 INFO - PROCESS | 3290 | 01:47:13 INFO - PROCESS | 3290 | [#8] json-parse-financial Cycles:10 Average:55.10 Median:55.50 stddev:2.88 (5.2%) stddev-sans-first:2.98 01:47:13 INFO - PROCESS | 3290 | Values: 57.0 53.0 55.0 52.0 56.0 53.0 58.0 53.0 53.0 61.0 01:47:13 INFO - PROCESS | 3290 | 01:47:13 INFO - PROCESS | 3290 | [#9] json-stringify-tinderbox Cycles:10 Average:60.60 Median:61.00 stddev:1.07 (1.8%) stddev-sans-first:1.12 01:47:13 INFO - PROCESS | 3290 | Values: 60.0 61.0 61.0 60.0 61.0 59.0 60.0 60.0 63.0 61.0 01:47:13 INFO - PROCESS | 3290 | 01:47:13 INFO - PROCESS | 3290 | [#10] stanford-crypto-aes Cycles:10 Average:74.90 Median:74.50 stddev:3.11 (4.2%) stddev-sans-first:2.39 01:47:13 INFO - PROCESS | 3290 | Values: 81.0 73.0 73.0 72.0 79.0 72.0 73.0 74.0 75.0 77.0 01:47:13 INFO - PROCESS | 3290 | 01:47:13 INFO - PROCESS | 3290 | [#11] stanford-crypto-ccm Cycles:10 Average:125.60 Median:124.50 stddev:13.69 (11.0%) stddev-sans-first:14.46 01:47:13 INFO - PROCESS | 3290 | Values: 122.0 119.0 116.0 118.0 128.0 129.0 116.0 119.0 162.0 127.0 01:47:13 INFO - PROCESS | 3290 | 01:47:13 INFO - PROCESS | 3290 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.00 Median:154.50 stddev:2.71 (1.8%) stddev-sans-first:2.77 01:47:13 INFO - PROCESS | 3290 | Values: 155.0 157.0 153.0 152.0 149.0 154.0 155.0 151.0 149.0 155.0 01:47:13 INFO - PROCESS | 3290 | 01:47:13 INFO - PROCESS | 3290 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.30 Median:62.50 stddev:1.49 (2.4%) stddev-sans-first:1.58 01:47:13 INFO - PROCESS | 3290 | Values: 62.0 64.0 62.0 63.0 62.0 64.0 62.0 60.0 60.0 64.0 01:47:13 INFO - PROCESS | 3290 | -------- Summary: end -------- 01:47:13 INFO - PROCESS | 3290 | 01:47:14 INFO - TEST-INFO | 3290: exit 0 01:47:14 INFO - TEST-OK | kraken | took 60272ms 01:47:14 INFO - SUITE-END | took 1170s 01:47:14 INFO - Completed test suite (00:19:31) 01:47:14 INFO - javascript benchmark 01:47:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7932.056361836384, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3282.0810173151517, 3316.6683331666836, 3316.684914933082, 3341.231516751147, 3345.715614172795, 5787.016207244768, 5901.3508514063415, 5923.7630494780215, 5924.585279030464, 5929.051351783715, 5858.648481091127, 5905.0, 5907.0548712206055, 5912.9704351478185, 5936.228290322263, 5316.76074576644, 5374.381946076192, 5392.649477783951, 5435.592330575203, 5455.318085239345, 5220.817271395502, 5231.973840130813, 5234.502722241381, 5262.657927234717, 5313.3092697949405, 5323.148296272597, 5334.359876814788, 5341.0, 5352.437994010631, 5401.297831281928, 5392.56859451243, 5393.352797664266, 5396.298481197439, 5403.648762830403, 5431.103867861798, 5067.0, 5161.045206636784, 5199.844004679866, 5305.204219367087, 5306.336707911511, 5811.128330750379, 5837.241158649391, 5838.7080645967535, 5855.677937713424, 5899.056151015817, 6259.624422534617, 6279.309275979638, 6305.0, 6305.274893387227, 6309.936900630981, 5968.31364393096, 6108.450239478424, 6125.327021303102, 6341.80974570759, 6346.619202847798, 5816.302043754777, 5825.1844741736195, 5836.766529338779, 5857.7949771757785, 5859.6777177255235, 6355.142055822497, 6362.745490180433, 6375.394337537927, 6377.681115944184, 6384.233891932905, 5915.852103697356, 5999.820005399845, 6021.819345419557, 6059.606125601822, 6075.2405949256345, 6203.193584833943, 6208.751649933952, 6218.22272215973, 6219.564630475823, 6231.22109736283, 6204.13142160098, 6212.47193988507, 6213.751449942042, 6215.316315205324, 6235.906461403082, 5950.761969521171, 6048.879022419527, 6055.666938318347, 6205.441510264098, 6223.0, 4714.5521175488275, 4715.599174070242, 4721.693089949143, 4741.905161896742, 4743.383360163157, 4203.43253660758, 4206.789660516962, 4206.978965105216, 4232.111256636141, 4240.342746874239, 5768.105943578752, 5818.941810581925, 5839.883202335929, 5879.706014699248, 5899.76400943966, 6266.780662676875, 6310.211223597051, 6310.684465776692, 6312.684365781693, 6323.52573556985, 5972.581919265696, 5975.0, 5976.5218782497295, 5977.790777322773, 6011.338752737195, 5301.045811753845, 5344.8663783404945, 5367.865803354947, 5407.810726624549, 5424.972875135678, 6505.251896031922, 6506.4469520091325, 6507.544471887017, 6515.869682606227, 6522.608643481406, 6270.4670103039925, 6271.247450305992, 6273.780417685359, 6276.278228003838, 6308.022256550242, 6210.390384424464, 6223.097650840512, 6331.778387756406, 6374.872502550016, 6470.805875823733, 6163.445289923749, 6202.4417802398, 6202.782902598387, 6203.038529028008, 6203.596766210182, 6320.273168585672, 6321.273053598895, 6322.399372059647, 6325.367463253638, 6333.778317758856, 5920.082387229814, 5984.820455386345, 6024.518038556817, 6054.939450605438, 6147.047207682816, 6112.442689633966, 6121.3572574879, 6123.1609584945345, 6125.033656770631, 6130.000698177663, 6036.094585812163, 6046.54650901193, 6064.514838812973, 6098.725557350019, 6100.816975490742, 5950.137230101697, 5989.041753319448, 6042.154098426312, 6046.969765151146, 6124.785632502841, 5916.763329466773, 5936.821895343147, 5945.048792193228, 5947.0, 5986.07215881539], "value": 5744.132530388019, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [588.799808065258, 638.7828138432935, 639.6258188959458, 662.5395350231588, 692.1313751242191, 42841.571584284146, 47465.0, 47773.0, 47783.0, 47822.28266575996, 47708.0, 48036.75981620087, 48186.51813481864, 48275.75862120684, 48277.275840862414, 9163.49600771959, 9225.446473211596, 9247.6300947962, 9251.907480925172, 9307.90692093077, 9218.677346292881, 9310.906890931072, 9320.347575669672, 9328.906710932906, 9329.440233585972, 9320.953395233015, 9376.484293363863, 9387.718368448957, 9392.718218453458, 9425.905740942571, 9344.43933363997, 9376.390534615228, 9378.155965963097, 9392.859107113365, 9394.671186508507, 9215.539223038888, 9277.257819374434, 9285.025072367373, 9290.070992900655, 9297.209737172281, 45320.3201951971, 45352.77323613394, 45576.0, 45674.0, 45861.0, 3535.699465545432, 3643.125649844045, 3854.8265328060306, 4296.3770253313405, 4626.329182268586, 36860.63139368599, 37124.0, 37305.25389492222, 37432.62567374318, 37469.812650936845, 4748.406449193851, 4792.089502994455, 4795.6403269754555, 4905.068037072945, 4940.851774446773, 410526.0, 414399.0, 419637.0, 420457.89771051257, 422478.0, 38684.41973370402, 39663.80168099141, 39711.40432893509, 69223.65388173128, 69621.0, 41407.792961035004, 41627.7918610405, 41679.7916010418, 41750.37374439386, 41768.16463670709, 16919.0, 16985.91507042457, 16989.150542472828, 17044.14779261032, 17134.828651713324, 15203.467878624195, 15212.543623691306, 15218.315175817115, 15247.542573722805, 15297.082175069309, 9394.38936469141, 9484.90515094854, 9486.430814151174, 9525.237980961507, 9528.380655257386, 3565.6790888820133, 3601.657842504958, 3672.779633222015, 3673.577538583044, 3692.7045836333027, 1146.8623765148068, 1150.9769804603861, 1157.3750174905624, 1159.299250333076, 1219.6645922371242, 34815.82592087074, 35058.474122888176, 35169.47245791315, 35583.11042223965, 35839.641603584154, 1198.7542553776454, 1201.4593432955205, 1208.957686480969, 1237.4864431261105, 1270.4410059573759, 17882.91058544725, 18430.17064232113, 18524.814751852307, 18626.906865465586, 18627.720584191247, 173278.0, 690565.0, 691220.0, 692951.0, 697021.0, 43965.120697585866, 44277.77861110738, 44592.3311150333, 44657.77671111624, 44792.55207447949, 1444.674948136682, 1457.6428774949993, 1481.5259117082646, 1497.4609140709335, 1502.9849701503065, 32878.506822398165, 33293.0, 33452.330953381286, 33622.327553448304, 33905.83047084749, 57521.71239143777, 58106.0, 58159.709201455415, 58204.708976454844, 58264.0, 492259.0, 494152.0, 495837.0, 496929.0, 501890.0, 31900.011922267153, 32681.18297042593, 32694.67305326916, 32842.0, 32908.50637240491, 53716.0, 54022.0, 54435.45564544462, 54744.45255547394, 54986.72506637441, 45949.54050459586, 45954.0, 46749.53250467588, 47049.05901882012, 47962.760186198844, 46239.76880115578, 47045.52954470548, 47194.29208561943, 47662.76168619135, 47683.52316476929, 44988.0, 45186.064371988425, 45707.54292457166, 46050.53949460462, 46254.0, 39998.80000599979, 40516.3922541168, 40869.0, 41186.79406602948, 41235.587644124374, 30267.545986809775, 30667.693323066484, 30679.53980690336, 30808.3330004401, 30909.0], "value": 20618.624355300726, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [400.1177403824567, 403.94748682671246, 414.69312708595635, 418.7215501691375, 429.4395813463431, 19145.42563723087, 20706.0, 20809.16763329467, 21140.365789026335, 21414.571708565858, 20302.69545956812, 20502.487437814063, 20733.792662073374, 20790.376288711326, 21012.89493552534, 5986.07215881538, 6211.6273023618505, 6219.844503887393, 6221.751129954796, 6230.5638605297645, 6031.155638210654, 6068.939310606882, 7192.856142877139, 7499.13759917611, 7540.359069479101, 7541.585212813293, 7562.0, 7607.0, 7610.581418022007, 7669.539827610333, 7557.395408367317, 7563.659635316424, 7653.464257501949, 7712.614369281569, 7743.303102720783, 7707.229277072304, 7778.261065198797, 7863.764087077397, 7864.41016923733, 7898.881516777253, 28487.0, 28587.0, 28920.710792892012, 29166.41667166666, 29717.851410743024, 2693.905713300025, 2698.5952107183884, 2889.9711002889912, 2980.6423229212633, 3016.125323656137, 16753.413630522995, 17171.656566868594, 17245.482635520955, 17486.0, 17849.7322540162, 2145.924892628766, 2217.135317226267, 2224.165937773335, 2230.933072007826, 2238.8992495337748, 99431.50284248605, 102694.48652756763, 105028.47485762522, 106521.99999999923, 107406.46296768544, 18155.909220453814, 18314.725279120823, 18819.52951176204, 19173.0, 19256.22975081009, 34818.0, 34885.65114348875, 34934.30131397358, 35094.0, 35272.118197044765, 10995.175361847894, 11365.829512557319, 11423.971842534213, 11534.307941523535, 11785.175037747447, 9640.469774162488, 9662.855057174149, 9689.757756056155, 9863.704088877344, 9865.704028879145, 9439.24486041115, 9482.288828337902, 9522.85725712919, 9760.951195243979, 9974.900250997542, 3263.738900887923, 3299.769016168845, 3328.700416962485, 3487.163080860575, 3601.3028536085585, 985.9408435493893, 1020.0921180149672, 1065.9040686338267, 1077.989220107789, 1108.3294606762954, 20944.895275523526, 21038.78961210399, 21041.684374734392, 21108.366748997556, 21134.365969020953, 1080.5189660295168, 1090.1496832470687, 1101.2071308657753, 1109.9334039957628, 1125.3248051169396, 10822.0, 11634.592789252336, 11697.473613687405, 11789.587364442203, 11854.229475084092, 519536.0, 529641.0, 530660.0, 536024.0, 538230.0, 59911.40088599058, 61856.0, 100005.49997249967, 102173.97826021793, 102652.48673756733, 1060.5916722061907, 1095.5070218401752, 1104.8563686720674, 1141.680329507739, 1150.6087930103638, 17941.461756147335, 18613.348532801287, 18979.0, 19099.80900191036, 19296.71054934149, 44604.553954461335, 44756.55243447524, 44934.32598510959, 45233.77383113063, 46529.76735116303, 67028.6648566754, 68072.6596367015, 69283.0, 69413.0, 69736.30263697298, 9169.312301577542, 9248.121428464285, 9288.860667089864, 9309.115634014757, 9378.249740020918, 9983.351082179635, 12665.113442058968, 12974.935125324691, 13006.479740810262, 13228.537001204912, 11451.0, 11582.594609188638, 11583.594574189863, 11805.940970295094, 12017.879821201675, 11260.774784504429, 11285.0, 11333.093352531943, 11353.545858165911, 11618.6514404568, 11493.597724079616, 11566.132540059361, 11685.298882067103, 11723.237989530653, 11730.237534560227, 9173.816523669622, 9182.449053056838, 9228.630854765734, 9420.576074076545, 9532.475713835805, 10869.347839129678, 11457.484413201593, 11471.139664525028, 11488.080953523531, 11782.82325765097], "value": 11094.310270265672, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1537.03167004787, 1556.1596737761608, 1743.9040852753094, 1813.7370081338215, 1875.174923033865, 8064.71773487928, 8990.280777537797, 9291.48896810675, 9782.364146330492, 9787.119159275664, 9336.906630933689, 9684.951575242132, 9694.321397502177, 9697.515124243777, 9748.805023899518, 2459.86470744109, 2486.5648511510503, 2495.201535508638, 2502.649629051934, 2505.0, 2377.8335516513853, 2394.353524548371, 2396.5686176488225, 2405.482821193443, 2414.770596793302, 2425.320910145162, 2437.1591800828687, 2482.2801387597665, 2504.1485894795765, 2508.1723031399683, 2444.1445494077107, 2447.278052974377, 2462.8645424501556, 2473.591857343545, 2481.8759062046915, 2443.8900249488815, 2448.5592593333195, 2454.275988583372, 2458.0, 2493.8254322197454, 9391.577379017961, 9473.242140628734, 9523.476208808514, 9549.665761698307, 9586.37688550249, 5668.093105103164, 5802.216700745387, 5803.82588522345, 5822.505087067604, 5848.035074212792, 3493.371193185226, 3688.17016171362, 3706.796126213057, 3714.182879766447, 3741.0834345585504, 1172.8299396587533, 1199.412287978893, 1202.4649031181127, 1205.2166092040156, 1223.8041913293919, 13472.25902575358, 13560.050796444253, 13562.660933476644, 13566.389512471962, 13579.185248885196, 4883.58489528387, 4971.9254211186135, 4987.925181122214, 5021.14640511114, 5118.411382690963, 11373.829392559119, 11394.658160255205, 11396.088312935111, 11398.316101033799, 11407.144464165054, 5777.768889244383, 5780.768769249267, 5799.9130013049835, 5800.303963524404, 5807.070868660994, 4587.266037434061, 5081.822136225214, 5147.3308469898675, 5158.200478925773, 5304.655197412233, 4199.97900010498, 4287.970886987163, 4311.741295522279, 4347.7173983690955, 4500.504944456107, 1395.6789938314041, 1422.8150340455675, 1425.109306683323, 1430.342042660388, 1438.9424423022963, 1006.225206590921, 1052.4842827014709, 1057.101463755817, 1059.6185373265619, 1087.8259478483405, 6161.6611086389785, 6374.490040796725, 6550.410463058348, 6567.047778072249, 6597.802065937934, 472.789608624159, 485.3859867267909, 489.1781806564934, 495.31893646236426, 517.7592419524939, 4454.665900057444, 4490.281554951257, 4499.1451624191295, 4516.1419330327135, 4538.432695913011, 11842.407879605986, 12242.44908979098, 12283.078769092353, 12299.877001230052, 12304.692382690504, 11364.715882103013, 11933.940330298467, 11939.044876409866, 12140.21088629254, 12303.0, 8874.511901845332, 8973.955130224307, 9036.367454278137, 9048.50233237165, 9114.908850911406, 6477.125588045554, 6495.220573531206, 6780.32196780318, 6782.796516104525, 6788.660566971533, 10737.624183153552, 11137.94431027872, 11163.274387164809, 11393.943030284796, 11434.714132146762, 10737.677869663923, 11308.49111789988, 11529.192956492965, 11574.0, 11679.708007299885, 7712.113106992543, 8224.76628505705, 8332.958335208285, 8557.358198135289, 8774.912250877409, 8951.0, 8985.595648195716, 8987.730368088967, 9001.099890010946, 9002.324825637972, 8254.669813207405, 8360.205780450848, 8366.330693544624, 8377.790555235924, 8383.874241886499, 8110.0, 8117.025956884975, 8145.104038555635, 8155.266026057683, 8202.917970820454, 7323.2676732326345, 7336.559806411632, 7430.405567554691, 7473.252674732484, 7504.0, 5615.915761263503, 5675.0, 5747.08046712524, 5822.5633077518505, 5865.442782935615, 5658.349289831557, 5710.314762228559, 5731.054376027909, 5780.132980053026, 5780.710964451845], "value": 4940.3901504914875, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [329.9472084466486, 338.67148865600365, 342.6333822809594, 343.8229311904368, 344.00239306012577, 5883.411658834115, 5937.584369094155, 5955.315138759033, 5965.284165900092, 5971.552133589977, 5768.567357448198, 5775.797847075342, 5795.623284486505, 5801.216835727176, 5821.767129314833, 4282.764447955354, 4427.756473393962, 4487.798049087783, 4526.6831321807485, 4552.180607490651, 4511.571400716943, 4514.683972121937, 4525.208088584501, 4547.567516232392, 4551.795169217393, 4538.410006699124, 4539.500654927955, 4542.4776150742755, 4550.567696068868, 4566.84016059438, 4267.573242675739, 4475.440569928759, 4484.461864576256, 4504.932426013613, 4523.819047238107, 3888.8833334999995, 3905.258000979805, 3917.941230881539, 3925.31307021273, 3937.0747874249814, 5683.573731970118, 5708.600397972144, 5710.257666503369, 5740.282464691913, 5746.310442746897, 4992.251162325644, 5091.745412729386, 5101.520559037875, 5130.71781052042, 5131.4098878629065, 6023.759049638009, 6032.004719221323, 6045.637261764265, 6089.543284253698, 6122.020476723747, 4233.682473814476, 4310.762908040057, 4312.82748690052, 4315.697901146921, 4318.373835793793, 6144.446999770043, 6205.627662340229, 6220.253569571635, 6241.843953901189, 6300.936990630126, 4315.892102697458, 4356.520782713899, 4367.344898265222, 4369.344598310279, 4369.759663218553, 4223.345381465878, 4358.084802191512, 4359.346098085312, 4359.825606975685, 4374.78126093694, 5041.495850415003, 5049.192129259374, 5049.84850454487, 5067.417247016567, 5072.163093089673, 6008.429199226067, 6089.2997305310455, 6118.418750218722, 6125.753350724123, 6135.785247516315, 1511.9697606047816, 1518.1498360917922, 1531.663034132489, 1544.980312993419, 1560.9375624974873, 962.831504486712, 970.3304719743354, 1011.1001208924064, 1026.1226651213256, 1039.5062345385882, 5839.5036421904615, 5862.824115276549, 5867.119932010147, 5886.23478947734, 5903.1145328200255, 6182.907256391068, 6228.4705800007505, 6250.468710159596, 6281.811545653638, 6305.5901366411035, 5678.133178393888, 5867.178594996704, 5895.233619629421, 5895.380984996599, 5922.052471604608, 3344.8829290975186, 3346.6486018968144, 3350.547676063749, 3361.277325375056, 3373.207296285371, 6291.339409361951, 6462.612243265419, 6463.547551671338, 6545.083688283644, 6574.737010519621, 6404.583702059351, 6475.352464753487, 6478.805635830932, 6503.577267477599, 6505.316941721051, 6292.182016337938, 6475.384838440341, 6575.0, 6608.257573107203, 6651.268360480343, 6289.937100628935, 6292.559520833498, 6298.653574053471, 6311.337309582428, 6324.873502529832, 6172.722227499863, 6322.861884860769, 6333.556651034383, 6337.162222955291, 6353.8880695877115, 6528.902066469102, 6573.441257493167, 6591.472682185509, 6621.172353455818, 6656.700448479927, 4443.600075993176, 4470.821167153407, 4474.284114541658, 4482.663800214932, 4487.439070116236, 6203.844903877439, 6331.366863313632, 6331.620102793847, 6451.935480645134, 6467.741290348334, 6166.753329866934, 6190.81427557174, 6217.7823776169425, 6260.154879091447, 6261.937380626136, 5937.6249500200265, 6190.907136393048, 6199.636080062379, 6203.596766210182, 6213.720382582885, 5972.522198224045, 5972.761089556369, 6172.6913654318005, 6231.813045608639, 6278.748850045947, 6218.440340369389, 6238.594491358047, 6264.467520260647, 6298.275698294755, 6306.558540902276, 5983.730732117152, 6098.377697590079, 6101.420365065312, 6110.541709371726, 6111.84720381994], "value": 4646.1088354543845, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [827.2843989948689, 828.329053466692, 870.9896520036758, 918.3112665500875, 954.7040417470582, 16757.49727508175, 16955.237014334354, 17102.230399632015, 18045.81954180458, 18346.816531834676, 15580.610484737887, 15672.529824105266, 15689.45086921958, 15741.134237616927, 15765.60585985351, 864.4899509289518, 868.8609822428413, 881.0264657553407, 882.7219425880859, 883.8939327280704, 894.4856707393243, 899.2715900120922, 902.3638334973828, 903.6972614174259, 905.0361365146098, 950.9966984831001, 952.2382094324547, 953.1564565359637, 954.5847556312991, 955.2596737528436, 949.7055912667096, 950.0736781637917, 950.8193443245796, 951.029949451559, 951.8810148731409, 697.2191145916606, 701.6912558474255, 704.1655638068904, 705.8941158826166, 706.5301574452983, 17604.119794010247, 17692.557686057822, 17842.821571784247, 17852.55368615782, 17911.0, 15830.9208453957, 16260.51218463448, 16368.75446868286, 16522.75215871763, 16697.332106715716, 13731.176129432266, 14112.294385280695, 14210.289485525785, 14278.428862845474, 14457.493987710379, 4034.0, 4107.138407873427, 4109.0, 4190.308599081148, 4278.4010238566625, 77955.22044779536, 79920.60039699764, 80134.19865801383, 80598.59700701518, 81068.59465702693, 11749.706257343634, 11888.405579721153, 11973.341466219268, 12072.215306005082, 12406.875931240573, 23475.0, 23525.647115293283, 23675.881620591786, 23686.881565592063, 23698.881505592362, 9361.62553497866, 9519.428834269827, 9546.284028697875, 9686.467244301632, 9711.465869377253, 5288.3918349390315, 5307.7080760557765, 5351.491608297206, 5356.143017117242, 5420.273359890573, 3775.8867233983024, 3903.6291552302487, 3905.824237909301, 3906.374980003243, 3916.3342231820657, 708.3023222126202, 709.4395427612232, 712.2485777504783, 731.1482123326372, 731.2249016043012, 675.560885424478, 766.3868904876077, 770.951505951905, 825.0511911302051, 829.2495291760966, 9685.951570242103, 10834.67495975122, 10887.782244355069, 10957.890421095846, 10966.561337546409, 805.90732065812, 808.8261023879894, 829.7303376402693, 850.8213275212274, 863.7840539865034, 4871.196252618349, 4936.654434189572, 4949.7772600233075, 4958.727270000185, 4979.875503112378, 70464.64767676129, 70855.0, 70922.0, 74264.25735742682, 79184.60407697925, 116578.41710791392, 116600.41699791446, 119079.0, 121108.39445802891, 124484.0, 102524.0, 104643.0, 105996.0, 107284.0, 110515.44742276237, 9763.072508111718, 9813.607455701693, 9909.0, 9944.651937182163, 9986.290210415593, 60443.69778151081, 61289.387106128364, 61444.692776535834, 62564.68717656383, 63443.0, 91355.54322228613, 93388.0, 93557.0, 94012.52993734987, 94444.0, 12658.683532911751, 12959.35203239853, 13578.728425431633, 13612.319384030561, 13886.583402497941, 15000.849991499945, 15389.230538472808, 15488.303026363616, 15567.299471524035, 15828.366865325257, 13966.231857247949, 13995.51015714445, 14207.64480887986, 14240.572782816533, 14593.0, 14027.929860350632, 14084.295785210494, 14118.717625647225, 14265.57203283903, 14508.107677128146, 13463.663408414868, 13749.0, 13927.442902283796, 14319.909041948784, 14550.563483095524, 11901.880981190423, 11997.940010299893, 11999.460024298753, 12157.08821838348, 12176.75646487083, 11721.413929303671, 11814.409279536161, 11831.585894493652, 12165.33090680021, 12277.815832762695], "value": 8257.306509884547, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1503.1999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 111.0, 105.0, 111.0, 111.0, 113.0, 110.0, 112.0, 112.0, 110.0], "value": 109.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 119.0, 152.0, 189.0, 116.0, 148.0, 145.0, 122.0, 176.0, 120.0], "value": 143.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 165.0, 163.0, 161.0, 162.0, 163.0, 162.0, 164.0, 165.0], "value": 163.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 76.0, 176.0, 76.0, 74.0, 74.0, 75.0, 162.0, 77.0], "value": 93.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 145.0, 83.0, 82.0, 83.0, 145.0, 83.0, 83.0, 149.0], "value": 102.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 119.0, 117.0, 117.0, 117.0, 117.0, 117.0, 118.0, 117.0], "value": 117.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 150.0, 147.0, 148.0, 147.0, 147.0, 147.0, 147.0, 148.0], "value": 147.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 53.0, 55.0, 52.0, 56.0, 53.0, 58.0, 53.0, 53.0, 61.0], "value": 55.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 61.0, 60.0, 61.0, 59.0, 60.0, 60.0, 63.0, 61.0], "value": 60.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 73.0, 73.0, 72.0, 79.0, 72.0, 73.0, 74.0, 75.0, 77.0], "value": 74.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 119.0, 116.0, 118.0, 128.0, 129.0, 116.0, 119.0, 162.0, 127.0], "value": 125.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 157.0, 153.0, 152.0, 149.0, 154.0, 155.0, 151.0, 149.0, 155.0], "value": 153.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 64.0, 62.0, 63.0, 62.0, 64.0, 62.0, 60.0, 60.0, 64.0], "value": 62.3, "unit": "score"}]}]} 01:47:14 INFO - Return code: 0 01:47:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 01:47:15 ERROR - # TBPL SUCCESS # 01:47:15 INFO - Running post-action listener: _resource_record_post_action 01:47:15 INFO - [mozharness: 2016-09-20 08:47:15.043799Z] Finished run-tests step (success) 01:47:15 INFO - Running post-run listener: _resource_record_post_run 01:47:15 INFO - Total resource usage - Wall time: 1185s; CPU: 13.0%; Read bytes: 8192; Write bytes: 484425728; Read time: 176; Write time: 507848 01:47:15 INFO - TinderboxPrint: CPU usage
13.1% 01:47:15 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 176 01:47:15 INFO - TinderboxPrint: I/O write bytes / time
484,425,728 / 507,848 01:47:15 INFO - TinderboxPrint: CPU idle
8,211.9 (86.8%) 01:47:15 INFO - TinderboxPrint: CPU user
1,191.7 (12.6%) 01:47:15 INFO - TinderboxPrint: Swap in / out
0 / 0 01:47:15 INFO - install - Wall time: 14s; CPU: 15.0%; Read bytes: 0; Write bytes: 255193088; Read time: 0; Write time: 378600 01:47:15 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 8192; Write bytes: 229232640; Read time: 176; Write time: 129248 01:47:15 INFO - Running post-run listener: _upload_blobber_files 01:47:15 INFO - Blob upload gear active. 01:47:15 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 01:47:15 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:15 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:15 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:16 INFO - (blobuploader) - INFO - Open directory for files ... 01:47:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 01:47:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:47:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:47:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 01:47:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:47:17 INFO - (blobuploader) - INFO - Done attempting. 01:47:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 01:47:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:47:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:47:20 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 01:47:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:47:20 INFO - (blobuploader) - INFO - Done attempting. 01:47:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 01:47:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:47:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:47:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 01:47:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:47:22 INFO - (blobuploader) - INFO - Done attempting. 01:47:22 INFO - (blobuploader) - INFO - Iteration through files over. 01:47:22 INFO - Return code: 0 01:47:22 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 01:47:22 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 01:47:22 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/db180f84c160a38d6c2b6bedc0003134b8462f871e9b87e85079abd3c827fce0812c2081c9a7c5355413b9d1c8ad86fb244366cd38a1b2075250160933b40f9a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a9364c33870ccd095de6c78c990152ce3658ba3adcd61ce745090466ed83e4d86f18ce64a407e14f51af1d2952db1ffce31c039cd0c934a8bac1655887a2aedf", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3cac5797585d7486f49092e9d4c41993822b8396a644a4c73c0da78fa0475149dc6b8227f969b3f518d6c21cd17c9b777131c354dd6a086f43980cb2acc5d176"} 01:47:22 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 01:47:22 INFO - Writing to file /builds/slave/test/properties/blobber_files 01:47:22 INFO - Contents: 01:47:22 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/db180f84c160a38d6c2b6bedc0003134b8462f871e9b87e85079abd3c827fce0812c2081c9a7c5355413b9d1c8ad86fb244366cd38a1b2075250160933b40f9a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a9364c33870ccd095de6c78c990152ce3658ba3adcd61ce745090466ed83e4d86f18ce64a407e14f51af1d2952db1ffce31c039cd0c934a8bac1655887a2aedf", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3cac5797585d7486f49092e9d4c41993822b8396a644a4c73c0da78fa0475149dc6b8227f969b3f518d6c21cd17c9b777131c354dd6a086f43980cb2acc5d176"} 01:47:22 INFO - Running post-run listener: copy_logs_to_upload_dir 01:47:22 INFO - Copying logs to upload dir... 01:47:22 INFO - mkdir: /builds/slave/test/build/upload/logs 01:47:22 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1220.394362 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 20 secs) (at 2016-09-20 01:47:22.631860) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-20 01:47:22.635038) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1474360020.566327-1268571996 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/db180f84c160a38d6c2b6bedc0003134b8462f871e9b87e85079abd3c827fce0812c2081c9a7c5355413b9d1c8ad86fb244366cd38a1b2075250160933b40f9a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a9364c33870ccd095de6c78c990152ce3658ba3adcd61ce745090466ed83e4d86f18ce64a407e14f51af1d2952db1ffce31c039cd0c934a8bac1655887a2aedf", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3cac5797585d7486f49092e9d4c41993822b8396a644a4c73c0da78fa0475149dc6b8227f969b3f518d6c21cd17c9b777131c354dd6a086f43980cb2acc5d176"} 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.011341 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/db180f84c160a38d6c2b6bedc0003134b8462f871e9b87e85079abd3c827fce0812c2081c9a7c5355413b9d1c8ad86fb244366cd38a1b2075250160933b40f9a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a9364c33870ccd095de6c78c990152ce3658ba3adcd61ce745090466ed83e4d86f18ce64a407e14f51af1d2952db1ffce31c039cd0c934a8bac1655887a2aedf", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3cac5797585d7486f49092e9d4c41993822b8396a644a4c73c0da78fa0475149dc6b8227f969b3f518d6c21cd17c9b777131c354dd6a086f43980cb2acc5d176"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-20 01:47:22.660057) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 01:47:22.660371) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1474360020.566327-1268571996 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004434 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 01:47:22.743585) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-20 01:47:22.743904) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-20 01:47:22.744186) ========= ========= Total master_lag: 0.17 =========