builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-068 starttime: 1474360337.76 results: success (0) buildid: 20160920012103 builduid: 8c7a41086d3e45199e2275a13f295c16 revision: 4376f2389699910ebe72e39977d563c77a094a1d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-20 01:32:17.757060) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-20 01:32:17.757529) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-20 01:32:17.757830) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-20 01:32:17.772748) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 01:32:17.773095) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1474360337.28753-1459457385 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-20 01:32:17-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 80.7M=0s 2016-09-20 01:32:17 (80.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.073632 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 01:32:17.860867) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 01:32:17.861268) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1474360337.28753-1459457385 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020486 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 01:32:17.911625) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-20 01:32:17.912072) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1474360337.28753-1459457385 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-20 01:32:18,097 truncating revision to first 12 chars 2016-09-20 01:32:18,097 Setting DEBUG logging. 2016-09-20 01:32:18,097 attempt 1/10 2016-09-20 01:32:18,097 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:32:18,837 unpacking tar archive at: mozilla-beta-4376f2389699/testing/mozharness/ program finished with exit code 0 elapsedTime=1.062986 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-20 01:32:18.987977) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-20 01:32:18.988313) ========= script_repo_revision: 4376f2389699910ebe72e39977d563c77a094a1d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-20 01:32:18.988804) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-20 01:32:18.989086) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-20 01:32:18.996708) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 20 secs) (at 2016-09-20 01:32:18.997104) ========= /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=97facb8ba2ac28944cc45e6b000001d4-1474360337.28753-1459457385 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 01:32:19 INFO - MultiFileLogger online at 20160920 01:32:19 in /builds/slave/test 01:32:19 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:32:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:32:19 INFO - {'append_to_log': False, 01:32:19 INFO - 'base_work_dir': '/builds/slave/test', 01:32:19 INFO - 'blob_upload_branch': 'Mozilla-Beta', 01:32:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:32:19 INFO - 'branch': 'Mozilla-Beta', 01:32:19 INFO - 'buildbot_json_path': 'buildprops.json', 01:32:19 INFO - 'config_files': ('talos/linux_config.py',), 01:32:19 INFO - 'default_actions': ('clobber', 01:32:19 INFO - 'read-buildbot-config', 01:32:19 INFO - 'download-and-extract', 01:32:19 INFO - 'populate-webroot', 01:32:19 INFO - 'create-virtualenv', 01:32:19 INFO - 'install', 01:32:19 INFO - 'run-tests'), 01:32:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:32:19 INFO - 'download_minidump_stackwalk': True, 01:32:19 INFO - 'download_symbols': 'ondemand', 01:32:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:32:19 INFO - 'tooltool.py': '/tools/tooltool.py', 01:32:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:32:19 INFO - '/tools/misc-python/virtualenv.py')}, 01:32:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:32:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:32:19 INFO - 'installer_path': 'installer.exe', 01:32:19 INFO - 'log_level': 'info', 01:32:19 INFO - 'log_name': 'talos', 01:32:19 INFO - 'log_to_console': True, 01:32:19 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 01:32:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 01:32:19 INFO - 'opt_config_files': (), 01:32:19 INFO - 'pip_index': False, 01:32:19 INFO - 'sps_profile': False, 01:32:19 INFO - 'sps_profile_interval': 0, 01:32:19 INFO - 'suite': 'dromaeojs-e10s', 01:32:19 INFO - 'system_bits': '32', 01:32:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:32:19 INFO - 'title': 'talos-linux64-ix-068', 01:32:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:32:19 INFO - 'use_talos_json': True, 01:32:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:32:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:32:19 INFO - 'work_dir': 'build'} 01:32:19 INFO - [mozharness: 2016-09-20 08:32:19.273511Z] Running clobber step. 01:32:19 INFO - Running pre-action listener: _resource_record_pre_action 01:32:19 INFO - Running main action method: clobber 01:32:19 INFO - rmtree: /builds/slave/test/build 01:32:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:32:19 INFO - Running post-action listener: _resource_record_post_action 01:32:19 INFO - [mozharness: 2016-09-20 08:32:19.725479Z] Finished clobber step (success) 01:32:19 INFO - [mozharness: 2016-09-20 08:32:19.725593Z] Running read-buildbot-config step. 01:32:19 INFO - Running pre-action listener: _resource_record_pre_action 01:32:19 INFO - Running main action method: read_buildbot_config 01:32:19 INFO - Using buildbot properties: 01:32:19 INFO - { 01:32:19 INFO - "project": "", 01:32:19 INFO - "product": "firefox", 01:32:19 INFO - "script_repo_revision": "production", 01:32:19 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s", 01:32:19 INFO - "repository": "", 01:32:19 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs-e10s", 01:32:19 INFO - "buildid": "20160920012103", 01:32:19 INFO - "stage_platform": "linux64-pgo", 01:32:19 INFO - "basedir": "/builds/slave/test", 01:32:19 INFO - "buildnumber": 242, 01:32:19 INFO - "platform": "ubuntu64_hw", 01:32:19 INFO - "mozci_request": { 01:32:19 INFO - "priority": -1, 01:32:19 INFO - "type": "trigger_all_talos_jobs", 01:32:19 INFO - "times": 6 01:32:19 INFO - }, 01:32:19 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 01:32:19 INFO - "slavebuilddir": "test", 01:32:19 INFO - "branch": "mozilla-beta", 01:32:19 INFO - "revision": "4376f2389699910ebe72e39977d563c77a094a1d", 01:32:19 INFO - "slavename": "talos-linux64-ix-068", 01:32:19 INFO - "builduid": "8c7a41086d3e45199e2275a13f295c16", 01:32:19 INFO - "repo_path": "releases/mozilla-beta" 01:32:19 INFO - } 01:32:19 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:32:19 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:32:19 INFO - Running post-action listener: _resource_record_post_action 01:32:19 INFO - [mozharness: 2016-09-20 08:32:19.728355Z] Finished read-buildbot-config step (success) 01:32:19 INFO - [mozharness: 2016-09-20 08:32:19.728444Z] Running download-and-extract step. 01:32:19 INFO - Running pre-action listener: _resource_record_pre_action 01:32:19 INFO - Running main action method: download_and_extract 01:32:19 INFO - mkdir: /builds/slave/test/build/tests 01:32:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:32:19 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:32:19 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:32:19 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:32:19 INFO - Downloaded 1327 bytes. 01:32:19 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 01:32:19 INFO - Using the following test package requirements: 01:32:19 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 01:32:19 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 01:32:19 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 01:32:19 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 01:32:19 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 01:32:19 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 01:32:19 INFO - u'jsshell-linux-x86_64.zip'], 01:32:19 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 01:32:19 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 01:32:19 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 01:32:19 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 01:32:19 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 01:32:19 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 01:32:19 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 01:32:19 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 01:32:19 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 01:32:19 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 01:32:19 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 01:32:19 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 01:32:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:32:19 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:32:19 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:32:19 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:32:20 INFO - Downloaded 21771354 bytes. 01:32:20 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:32:20 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:32:21 INFO - Return code: 0 01:32:21 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:32:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:32:21 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:32:21 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:32:21 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:32:21 INFO - Downloaded 21771354 bytes. 01:32:21 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:32:21 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:32:22 INFO - Return code: 0 01:32: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:32:22 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:32:22 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:32:22 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:32:23 INFO - Downloaded 14413740 bytes. 01:32:23 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:32:23 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:32:23 INFO - Return code: 0 01:32:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:32:23 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:32:23 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:32:23 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:32:23 INFO - Downloaded 56770127 bytes. 01:32:23 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:32:23 INFO - mkdir: /builds/slave/test/properties 01:32:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:32:23 INFO - Writing to file /builds/slave/test/properties/build_url 01:32:23 INFO - Contents: 01:32:23 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:32:24 INFO - Running post-action listener: _resource_record_post_action 01:32:24 INFO - Running post-action listener: set_extra_try_arguments 01:32:24 INFO - [mozharness: 2016-09-20 08:32:24.160754Z] Finished download-and-extract step (success) 01:32:24 INFO - [mozharness: 2016-09-20 08:32:24.161080Z] Running populate-webroot step. 01:32:24 INFO - Running pre-action listener: _resource_record_pre_action 01:32:24 INFO - Running main action method: populate_webroot 01:32:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:32:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:32:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:32:24 INFO - '--tppagecycles', 01:32:24 INFO - '7'], 01:32:24 INFO - 'tests': ['tsvgm']}}, 01:32:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:32:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:32:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:32:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:32:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:32:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:32:24 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:32:24 INFO - 'talos_options': ['--disable-e10s'], 01:32:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:32:24 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:32:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:32:24 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:32:24 INFO - 'talos_options': ['--disable-e10s'], 01:32:24 INFO - 'tests': ['damp', 'tps']}, 01:32:24 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:32:24 INFO - 'tests': ['damp', 'tps']}, 01:32:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:32:24 INFO - 'tests': ['dromaeo_dom']}, 01:32:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:32:24 INFO - 'g4': {'talos_options': ['--disable-e10s'], 01:32:24 INFO - 'tests': ['basic_compositor_video']}, 01:32:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 01:32:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:32:24 INFO - 'tests': ['a11yr', 01:32:24 INFO - 'ts_paint', 01:32:24 INFO - 'tpaint', 01:32:24 INFO - 'sessionrestore', 01:32:24 INFO - 'sessionrestore_no_auto_restore', 01:32:24 INFO - 'tabpaint']}, 01:32:24 INFO - 'other-e10s': {'tests': ['a11yr', 01:32:24 INFO - 'ts_paint', 01:32:24 INFO - 'tpaint', 01:32:24 INFO - 'sessionrestore', 01:32:24 INFO - 'sessionrestore_no_auto_restore', 01:32:24 INFO - 'tabpaint']}, 01:32:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:32:24 INFO - 'ts_paint', 01:32:24 INFO - 'tpaint', 01:32:24 INFO - 'sessionrestore', 01:32:24 INFO - 'sessionrestore_no_auto_restore', 01:32:24 INFO - 'tabpaint']}, 01:32:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:32:24 INFO - 'ts_paint', 01:32:24 INFO - 'tpaint', 01:32:24 INFO - 'sessionrestore', 01:32:24 INFO - 'sessionrestore_no_auto_restore', 01:32:24 INFO - 'tabpaint']}, 01:32:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:32:24 INFO - 'tests': ['a11yr', 01:32:24 INFO - 'ts_paint', 01:32:24 INFO - 'tpaint', 01:32:24 INFO - 'sessionrestore', 01:32:24 INFO - 'sessionrestore_no_auto_restore', 01:32:24 INFO - 'tabpaint']}, 01:32:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:32:24 INFO - 'tests': ['a11yr', 01:32:24 INFO - 'ts_paint', 01:32:24 INFO - 'tpaint', 01:32:24 INFO - 'sessionrestore', 01:32:24 INFO - 'sessionrestore_no_auto_restore', 01:32:24 INFO - 'tabpaint']}, 01:32:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:32:24 INFO - 'tests': ['tsvgx', 01:32:24 INFO - 'tsvgr_opacity', 01:32:24 INFO - 'tart', 01:32:24 INFO - 'tscrollx', 01:32:24 INFO - 'cart']}, 01:32:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:32:24 INFO - 'tsvgr_opacity', 01:32:24 INFO - 'tart', 01:32:24 INFO - 'tscrollx', 01:32:24 INFO - 'cart']}, 01:32:24 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:32:24 INFO - 'talos_options': ['--disable-e10s'], 01:32:24 INFO - 'tests': ['tp5o']}, 01:32:24 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:32:24 INFO - 'tests': ['tp5o']}, 01:32:24 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:32:24 INFO - 'talos_options': ['--disable-e10s', 01:32:24 INFO - '--xperf_path', 01:32:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:32:24 INFO - 'tests': ['tp5n']}, 01:32:24 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:32:24 INFO - 'talos_options': ['--xperf_path', 01:32:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:32:24 INFO - 'tests': ['tp5n']}}, 01:32:24 INFO - 'talos.zip': {'path': '', 01:32:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:32:24 INFO - Running post-action listener: _resource_record_post_action 01:32:24 INFO - [mozharness: 2016-09-20 08:32:24.180351Z] Finished populate-webroot step (success) 01:32:24 INFO - [mozharness: 2016-09-20 08:32:24.180441Z] Running create-virtualenv step. 01:32:24 INFO - Running pre-action listener: _resource_record_pre_action 01:32:24 INFO - Running main action method: create_virtualenv 01:32:24 INFO - Creating virtualenv /builds/slave/test/build/venv 01:32:24 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:32:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:32:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:32:24 INFO - Using real prefix '/usr' 01:32:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:32:24 INFO - Installing distribute.............................................................................................................................................................................................done. 01:32:26 INFO - Installing pip.................done. 01:32:26 INFO - Return code: 0 01:32:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:32:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:32:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:32:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:32:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:32:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:32:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:32:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b79030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cdc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1da8400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e37570>, '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 0x1e38470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e38900>, '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': '97facb8ba2ac28944cc45e6b000001d4-1474360337.28753-1459457385', '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:32:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 01:32:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 01:32:26 INFO - Using env: {'DISPLAY': ':0', 01:32:26 INFO - 'HOME': '/home/cltbld', 01:32:26 INFO - 'LANG': 'en_US.UTF-8', 01:32:26 INFO - 'LANGUAGE': 'en_US:en', 01:32:26 INFO - 'LOGNAME': 'cltbld', 01:32:26 INFO - 'MAIL': '/var/mail/cltbld', 01:32:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:32:26 INFO - 'MOZ_NO_REMOTE': '1', 01:32:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:32:26 INFO - 'NO_EM_RESTART': '1', 01:32:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:32:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:32:26 INFO - 'PWD': '/builds/slave/test', 01:32:26 INFO - 'SHELL': '/bin/bash', 01:32:26 INFO - 'SHLVL': '1', 01:32:26 INFO - 'TERM': 'linux', 01:32:26 INFO - 'TMOUT': '86400', 01:32:26 INFO - 'USER': 'cltbld', 01:32:26 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1474360337.28753-1459457385', 01:32:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:32:26 INFO - '_': '/tools/buildbot/bin/python'} 01:32:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:32:27 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:32:27 INFO - Cleaning up... 01:32:27 INFO - Return code: 0 01:32:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:32:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:32:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:32:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:32:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:32:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:32:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:32:27 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 0x1b79030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cdc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1da8400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e37570>, '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 0x1e38470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e38900>, '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': '97facb8ba2ac28944cc45e6b000001d4-1474360337.28753-1459457385', '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:32:27 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:32:27 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:32:27 INFO - Using env: {'DISPLAY': ':0', 01:32:27 INFO - 'HOME': '/home/cltbld', 01:32:27 INFO - 'LANG': 'en_US.UTF-8', 01:32:27 INFO - 'LANGUAGE': 'en_US:en', 01:32:27 INFO - 'LOGNAME': 'cltbld', 01:32:27 INFO - 'MAIL': '/var/mail/cltbld', 01:32:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:32:27 INFO - 'MOZ_NO_REMOTE': '1', 01:32:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:32:27 INFO - 'NO_EM_RESTART': '1', 01:32:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:32:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:32:27 INFO - 'PWD': '/builds/slave/test', 01:32:27 INFO - 'SHELL': '/bin/bash', 01:32:27 INFO - 'SHLVL': '1', 01:32:27 INFO - 'TERM': 'linux', 01:32:27 INFO - 'TMOUT': '86400', 01:32:27 INFO - 'USER': 'cltbld', 01:32:27 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1474360337.28753-1459457385', 01:32:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:32:27 INFO - '_': '/tools/buildbot/bin/python'} 01:32:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:32:27 INFO - Downloading/unpacking psutil>=3.1.1 01:32:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:32:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:32:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:32:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:32:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:32:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:32:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:32:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:32:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:32:29 INFO - Installing collected packages: psutil 01:32:29 INFO - Running setup.py install for psutil 01:32:29 INFO - building 'psutil._psutil_linux' extension 01:32:29 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:32:29 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:32:29 INFO - building 'psutil._psutil_posix' extension 01:32:29 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:32:29 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:32:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:32:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:32:29 INFO - Successfully installed psutil 01:32:29 INFO - Cleaning up... 01:32:29 INFO - Return code: 0 01:32:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:32: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:32:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:32:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:32: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:32:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:32:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:32: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b79030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cdc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1da8400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e37570>, '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 0x1e38470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e38900>, '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': '97facb8ba2ac28944cc45e6b000001d4-1474360337.28753-1459457385', '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:32: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 01:32: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 mozsystemmonitor==0.3 01:32:29 INFO - Using env: {'DISPLAY': ':0', 01:32:29 INFO - 'HOME': '/home/cltbld', 01:32:29 INFO - 'LANG': 'en_US.UTF-8', 01:32:29 INFO - 'LANGUAGE': 'en_US:en', 01:32:29 INFO - 'LOGNAME': 'cltbld', 01:32:29 INFO - 'MAIL': '/var/mail/cltbld', 01:32:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:32:29 INFO - 'MOZ_NO_REMOTE': '1', 01:32:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:32:29 INFO - 'NO_EM_RESTART': '1', 01:32:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:32:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:32:29 INFO - 'PWD': '/builds/slave/test', 01:32:29 INFO - 'SHELL': '/bin/bash', 01:32:29 INFO - 'SHLVL': '1', 01:32:29 INFO - 'TERM': 'linux', 01:32:29 INFO - 'TMOUT': '86400', 01:32:29 INFO - 'USER': 'cltbld', 01:32:29 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1474360337.28753-1459457385', 01:32:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:32:29 INFO - '_': '/tools/buildbot/bin/python'} 01:32:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:32:30 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:32:30 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:32:30 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:32:30 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:32:30 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:32:30 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:32:30 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:32:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:32:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:32:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:32:31 INFO - Installing collected packages: mozsystemmonitor 01:32:31 INFO - Running setup.py install for mozsystemmonitor 01:32:32 INFO - Successfully installed mozsystemmonitor 01:32:32 INFO - Cleaning up... 01:32:32 INFO - Return code: 0 01:32:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 01:32:32 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:32:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:32:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:32:32 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:32:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:32:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:32:32 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 0x1b79030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cdc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1da8400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e37570>, '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 0x1e38470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e38900>, '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': '97facb8ba2ac28944cc45e6b000001d4-1474360337.28753-1459457385', '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:32:32 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:32:32 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:32:32 INFO - Using env: {'DISPLAY': ':0', 01:32:32 INFO - 'HOME': '/home/cltbld', 01:32:32 INFO - 'LANG': 'en_US.UTF-8', 01:32:32 INFO - 'LANGUAGE': 'en_US:en', 01:32:32 INFO - 'LOGNAME': 'cltbld', 01:32:32 INFO - 'MAIL': '/var/mail/cltbld', 01:32:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:32:32 INFO - 'MOZ_NO_REMOTE': '1', 01:32:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:32:32 INFO - 'NO_EM_RESTART': '1', 01:32:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:32:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:32:32 INFO - 'PWD': '/builds/slave/test', 01:32:32 INFO - 'SHELL': '/bin/bash', 01:32:32 INFO - 'SHLVL': '1', 01:32:32 INFO - 'TERM': 'linux', 01:32:32 INFO - 'TMOUT': '86400', 01:32:32 INFO - 'USER': 'cltbld', 01:32:32 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1474360337.28753-1459457385', 01:32:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:32:32 INFO - '_': '/tools/buildbot/bin/python'} 01:32:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:32:32 INFO - Downloading/unpacking jsonschema==2.5.1 01:32:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:32:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:32:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:32:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:32:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:32:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:32:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:32:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:32:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:32:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:32:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:32:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:32:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:32:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:32:34 INFO - Downloading functools32-3.2.3-2.tar.gz 01:32:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:32:34 INFO - warning: no files found matching '*.txt' 01:32:34 INFO - no previously-included directories found matching 'build' 01:32:34 INFO - no previously-included directories found matching 'dist' 01:32:34 INFO - no previously-included directories found matching '.git*' 01:32:34 INFO - Installing collected packages: jsonschema, functools32 01:32:34 INFO - Running setup.py install for functools32 01:32:34 INFO - warning: no files found matching '*.txt' 01:32:34 INFO - no previously-included directories found matching 'build' 01:32:34 INFO - no previously-included directories found matching 'dist' 01:32:34 INFO - no previously-included directories found matching '.git*' 01:32:34 INFO - Successfully installed jsonschema functools32 01:32:34 INFO - Cleaning up... 01:32:34 INFO - Return code: 0 01:32:34 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 01:32:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:32:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:32:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:32:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:32:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:32:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:32:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1b79030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cdc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1da8400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e37570>, '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 0x1e38470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e38900>, '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': '97facb8ba2ac28944cc45e6b000001d4-1474360337.28753-1459457385', '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:32:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 01:32:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 01:32:34 INFO - Using env: {'DISPLAY': ':0', 01:32:34 INFO - 'HOME': '/home/cltbld', 01:32:34 INFO - 'LANG': 'en_US.UTF-8', 01:32:34 INFO - 'LANGUAGE': 'en_US:en', 01:32:34 INFO - 'LOGNAME': 'cltbld', 01:32:34 INFO - 'MAIL': '/var/mail/cltbld', 01:32:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:32:34 INFO - 'MOZ_NO_REMOTE': '1', 01:32:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:32:34 INFO - 'NO_EM_RESTART': '1', 01:32:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:32:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:32:34 INFO - 'PWD': '/builds/slave/test', 01:32:34 INFO - 'SHELL': '/bin/bash', 01:32:34 INFO - 'SHLVL': '1', 01:32:34 INFO - 'TERM': 'linux', 01:32:34 INFO - 'TMOUT': '86400', 01:32:34 INFO - 'USER': 'cltbld', 01:32:34 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1474360337.28753-1459457385', 01:32:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:32:34 INFO - '_': '/tools/buildbot/bin/python'} 01:32:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:32:34 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 01:32:34 INFO - Cleaning up... 01:32:34 INFO - Return code: 0 01:32:34 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:32:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:32:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:32:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:32:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:32:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:32:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:32:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1b79030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cdc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1da8400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e37570>, '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 0x1e38470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e38900>, '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': '97facb8ba2ac28944cc45e6b000001d4-1474360337.28753-1459457385', '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:32:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 01:32:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 01:32:34 INFO - Using env: {'DISPLAY': ':0', 01:32:34 INFO - 'HOME': '/home/cltbld', 01:32:34 INFO - 'LANG': 'en_US.UTF-8', 01:32:34 INFO - 'LANGUAGE': 'en_US:en', 01:32:34 INFO - 'LOGNAME': 'cltbld', 01:32:34 INFO - 'MAIL': '/var/mail/cltbld', 01:32:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:32:34 INFO - 'MOZ_NO_REMOTE': '1', 01:32:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:32:34 INFO - 'NO_EM_RESTART': '1', 01:32:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:32:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:32:34 INFO - 'PWD': '/builds/slave/test', 01:32:34 INFO - 'SHELL': '/bin/bash', 01:32:34 INFO - 'SHLVL': '1', 01:32:34 INFO - 'TERM': 'linux', 01:32:34 INFO - 'TMOUT': '86400', 01:32:34 INFO - 'USER': 'cltbld', 01:32:34 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1474360337.28753-1459457385', 01:32:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:32:34 INFO - '_': '/tools/buildbot/bin/python'} 01:32:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:32:35 INFO - Downloading/unpacking blobuploader==1.2.4 01:32:35 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:32:35 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:32:35 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:32:35 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:32:35 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:32:35 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:32:37 INFO - Downloading blobuploader-1.2.4.tar.gz 01:32:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:32:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:32:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:32:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:32:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:32:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:32:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:32:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:32:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:32:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:32:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:32:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:32:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:32:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:32:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:32:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:32:37 INFO - Downloading docopt-0.6.1.tar.gz 01:32:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:32:37 INFO - Installing collected packages: blobuploader, requests, docopt 01:32:37 INFO - Running setup.py install for blobuploader 01:32:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:32:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:32:37 INFO - Running setup.py install for requests 01:32:38 INFO - Running setup.py install for docopt 01:32:38 INFO - Successfully installed blobuploader requests docopt 01:32:38 INFO - Cleaning up... 01:32:38 INFO - Return code: 0 01:32:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:32:38 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:32:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:32:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:32:38 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:32:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:32:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:32:38 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 0x1b79030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cdc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1da8400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e37570>, '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 0x1e38470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e38900>, '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': '97facb8ba2ac28944cc45e6b000001d4-1474360337.28753-1459457385', '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:32:38 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:32:38 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:32:38 INFO - Using env: {'DISPLAY': ':0', 01:32:38 INFO - 'HOME': '/home/cltbld', 01:32:38 INFO - 'LANG': 'en_US.UTF-8', 01:32:38 INFO - 'LANGUAGE': 'en_US:en', 01:32:38 INFO - 'LOGNAME': 'cltbld', 01:32:38 INFO - 'MAIL': '/var/mail/cltbld', 01:32:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:32:38 INFO - 'MOZ_NO_REMOTE': '1', 01:32:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:32:38 INFO - 'NO_EM_RESTART': '1', 01:32:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:32:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:32:38 INFO - 'PWD': '/builds/slave/test', 01:32:38 INFO - 'SHELL': '/bin/bash', 01:32:38 INFO - 'SHLVL': '1', 01:32:38 INFO - 'TERM': 'linux', 01:32:38 INFO - 'TMOUT': '86400', 01:32:38 INFO - 'USER': 'cltbld', 01:32:38 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1474360337.28753-1459457385', 01:32:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:32:38 INFO - '_': '/tools/buildbot/bin/python'} 01:32:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:32:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:32:38 INFO - Running setup.py (path:/tmp/pip-FfQImu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:32:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:32:38 INFO - Running setup.py (path:/tmp/pip-D0aLv0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:32:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:32:38 INFO - Running setup.py (path:/tmp/pip-oz3KtY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:32:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:32:38 INFO - Running setup.py (path:/tmp/pip-3NKj4v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:32:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:32:38 INFO - Running setup.py (path:/tmp/pip-wXYSGK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:32:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:32:38 INFO - Running setup.py (path:/tmp/pip-LLZCR7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:32:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:32:39 INFO - Running setup.py (path:/tmp/pip-ZF7Vpa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:32:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:32:39 INFO - Running setup.py (path:/tmp/pip-zymP5p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:32:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:32:39 INFO - Running setup.py (path:/tmp/pip-CaixeD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:32:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:32:39 INFO - Running setup.py (path:/tmp/pip-iMElV4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:32:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:32:39 INFO - Running setup.py (path:/tmp/pip-zFxF4t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:32:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:32:39 INFO - Running setup.py (path:/tmp/pip-jjbH4W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:32:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:32:39 INFO - Running setup.py (path:/tmp/pip-8z2r5U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:32:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:32:39 INFO - Running setup.py (path:/tmp/pip-FDxxi3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:32:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:32:39 INFO - Running setup.py (path:/tmp/pip-xZc9Kc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:32:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:32:39 INFO - Running setup.py (path:/tmp/pip-y7YHrh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:32:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:32:39 INFO - Running setup.py (path:/tmp/pip-FNqlxW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:32:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:32:39 INFO - Running setup.py install for manifestparser 01:32:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:32:39 INFO - Running setup.py install for mozcrash 01:32:40 INFO - Running setup.py install for mozdebug 01:32:40 INFO - Running setup.py install for mozdevice 01:32:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:32:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:32:40 INFO - Running setup.py install for mozfile 01:32:40 INFO - Running setup.py install for mozhttpd 01:32:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:32:40 INFO - Running setup.py install for mozinfo 01:32:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:32:40 INFO - Running setup.py install for mozInstall 01:32:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:32:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:32:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:32:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:32:40 INFO - Running setup.py install for mozleak 01:32:40 INFO - Running setup.py install for mozlog 01:32:40 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:32:40 INFO - Running setup.py install for moznetwork 01:32:40 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:32:40 INFO - Running setup.py install for mozprocess 01:32:41 INFO - Running setup.py install for mozprofile 01:32:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:32:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:32:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:32:41 INFO - Running setup.py install for mozrunner 01:32:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:32:41 INFO - Running setup.py install for mozscreenshot 01:32:41 INFO - Running setup.py install for moztest 01:32:41 INFO - Running setup.py install for mozversion 01:32:41 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:32:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:32:41 INFO - Cleaning up... 01:32:41 INFO - Return code: 0 01:32:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:32:41 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:32:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:32:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:32:41 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:32:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:32:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:32:41 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 0x1b79030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cdc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1da8400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e37570>, '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 0x1e38470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e38900>, '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': '97facb8ba2ac28944cc45e6b000001d4-1474360337.28753-1459457385', '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:32:41 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:32:41 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:32:41 INFO - Using env: {'DISPLAY': ':0', 01:32:41 INFO - 'HOME': '/home/cltbld', 01:32:41 INFO - 'LANG': 'en_US.UTF-8', 01:32:41 INFO - 'LANGUAGE': 'en_US:en', 01:32:41 INFO - 'LOGNAME': 'cltbld', 01:32:41 INFO - 'MAIL': '/var/mail/cltbld', 01:32:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:32:41 INFO - 'MOZ_NO_REMOTE': '1', 01:32:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:32:41 INFO - 'NO_EM_RESTART': '1', 01:32:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:32:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:32:41 INFO - 'PWD': '/builds/slave/test', 01:32:41 INFO - 'SHELL': '/bin/bash', 01:32:41 INFO - 'SHLVL': '1', 01:32:41 INFO - 'TERM': 'linux', 01:32:41 INFO - 'TMOUT': '86400', 01:32:41 INFO - 'USER': 'cltbld', 01:32:41 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1474360337.28753-1459457385', 01:32:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:32:41 INFO - '_': '/tools/buildbot/bin/python'} 01:32:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:32:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:32:41 INFO - Running setup.py (path:/tmp/pip-HqRVjB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:32:41 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:32:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:32:41 INFO - Running setup.py (path:/tmp/pip-Cpme8N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:32:42 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:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:32:42 INFO - Running setup.py (path:/tmp/pip-wEZL7h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:32:42 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:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:32:42 INFO - Running setup.py (path:/tmp/pip-u11C6h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:32:42 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:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:32:42 INFO - Running setup.py (path:/tmp/pip-U4Wu08-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:32:42 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:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:32:42 INFO - Running setup.py (path:/tmp/pip-byTyiq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:32:42 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:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:32:42 INFO - Running setup.py (path:/tmp/pip-xe_yqs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:32:42 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:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:32:42 INFO - Running setup.py (path:/tmp/pip-QOEhxc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:32:42 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:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:32:42 INFO - Running setup.py (path:/tmp/pip-DLu8Hx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:32:42 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:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:32:42 INFO - Running setup.py (path:/tmp/pip-qtYR_E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:32:42 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:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:32:42 INFO - Running setup.py (path:/tmp/pip-GX_pXJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:32:42 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:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:32:42 INFO - Running setup.py (path:/tmp/pip-8axEYd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:32:42 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:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:32:42 INFO - Running setup.py (path:/tmp/pip-R5sxq9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:32:42 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:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:32:42 INFO - Running setup.py (path:/tmp/pip-YAUCtm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:32:42 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:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:32:42 INFO - Running setup.py (path:/tmp/pip-wFccBN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:32:42 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:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:32:42 INFO - Running setup.py (path:/tmp/pip-38t7Nd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:32:43 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:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:32:43 INFO - Running setup.py (path:/tmp/pip-Wiinjx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:32:43 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:32:43 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:32:43 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:32:43 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:32:43 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:32:43 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:32:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:32:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:32:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:32:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:32:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:32:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:32:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:32:44 INFO - Downloading blessings-1.6.tar.gz 01:32:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:32:45 INFO - Installing collected packages: blessings 01:32:45 INFO - Running setup.py install for blessings 01:32:45 INFO - Successfully installed blessings 01:32:45 INFO - Cleaning up... 01:32:45 INFO - Return code: 0 01:32:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:32:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:32:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:32:45 INFO - Reading from file tmpfile_stdout 01:32:45 INFO - Current package versions: 01:32:45 INFO - argparse == 1.2.1 01:32:45 INFO - blessings == 1.6 01:32:45 INFO - blobuploader == 1.2.4 01:32:45 INFO - docopt == 0.6.1 01:32:45 INFO - functools32 == 3.2.3-2 01:32:45 INFO - jsonschema == 2.5.1 01:32:45 INFO - manifestparser == 1.1 01:32:45 INFO - mozInstall == 1.12 01:32:45 INFO - mozcrash == 0.17 01:32:45 INFO - mozdebug == 0.1 01:32:45 INFO - mozdevice == 0.48 01:32:45 INFO - mozfile == 1.2 01:32:45 INFO - mozhttpd == 0.7 01:32:45 INFO - mozinfo == 0.9 01:32:45 INFO - mozleak == 0.1 01:32:45 INFO - mozlog == 3.3 01:32:45 INFO - moznetwork == 0.27 01:32:45 INFO - mozprocess == 0.23 01:32:45 INFO - mozprofile == 0.28 01:32:45 INFO - mozrunner == 6.12 01:32:45 INFO - mozscreenshot == 0.1 01:32:45 INFO - mozsystemmonitor == 0.3 01:32:45 INFO - moztest == 0.7 01:32:45 INFO - mozversion == 1.4 01:32:45 INFO - psutil == 3.1.1 01:32:45 INFO - requests == 1.2.3 01:32:45 INFO - wsgiref == 0.1.2 01:32:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:32:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:32:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:32:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:32:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:32:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:32:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:32:45 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 0x1b79030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cdc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1da8400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e37570>, '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 0x1e38470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e38900>, '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': '97facb8ba2ac28944cc45e6b000001d4-1474360337.28753-1459457385', '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:32:45 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:32:45 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:32:45 INFO - Using env: {'DISPLAY': ':0', 01:32:45 INFO - 'HOME': '/home/cltbld', 01:32:45 INFO - 'LANG': 'en_US.UTF-8', 01:32:45 INFO - 'LANGUAGE': 'en_US:en', 01:32:45 INFO - 'LOGNAME': 'cltbld', 01:32:45 INFO - 'MAIL': '/var/mail/cltbld', 01:32:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:32:45 INFO - 'MOZ_NO_REMOTE': '1', 01:32:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:32:45 INFO - 'NO_EM_RESTART': '1', 01:32:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:32:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:32:45 INFO - 'PWD': '/builds/slave/test', 01:32:45 INFO - 'SHELL': '/bin/bash', 01:32:45 INFO - 'SHLVL': '1', 01:32:45 INFO - 'TERM': 'linux', 01:32:45 INFO - 'TMOUT': '86400', 01:32:45 INFO - 'USER': 'cltbld', 01:32:45 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1474360337.28753-1459457385', 01:32:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:32:45 INFO - '_': '/tools/buildbot/bin/python'} 01:32:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:32:45 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:32:45 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:32:45 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:32:45 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:32:45 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:32:45 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:32:45 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:32:45 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:32:45 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:32:45 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:32:45 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:32:45 INFO - Cleaning up... 01:32:45 INFO - Return code: 0 01:32:45 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:32:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:32:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:32:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:32:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:32:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:32:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:32:45 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 0x1b79030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cdc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1da8400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e37570>, '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 0x1e38470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e38900>, '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': '97facb8ba2ac28944cc45e6b000001d4-1474360337.28753-1459457385', '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:32:45 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:32:45 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:32:45 INFO - Using env: {'DISPLAY': ':0', 01:32:45 INFO - 'HOME': '/home/cltbld', 01:32:45 INFO - 'LANG': 'en_US.UTF-8', 01:32:45 INFO - 'LANGUAGE': 'en_US:en', 01:32:45 INFO - 'LOGNAME': 'cltbld', 01:32:45 INFO - 'MAIL': '/var/mail/cltbld', 01:32:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:32:45 INFO - 'MOZ_NO_REMOTE': '1', 01:32:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:32:45 INFO - 'NO_EM_RESTART': '1', 01:32:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:32:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:32:45 INFO - 'PWD': '/builds/slave/test', 01:32:45 INFO - 'SHELL': '/bin/bash', 01:32:45 INFO - 'SHLVL': '1', 01:32:45 INFO - 'TERM': 'linux', 01:32:45 INFO - 'TMOUT': '86400', 01:32:45 INFO - 'USER': 'cltbld', 01:32:45 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1474360337.28753-1459457385', 01:32:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:32:45 INFO - '_': '/tools/buildbot/bin/python'} 01:32:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:32:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 01:32:45 INFO - Cleaning up... 01:32:45 INFO - Return code: 0 01:32:45 INFO - Running post-action listener: _resource_record_post_action 01:32:45 INFO - Running post-action listener: _start_resource_monitoring 01:32:45 INFO - Starting resource monitoring. 01:32:45 INFO - [mozharness: 2016-09-20 08:32:45.941024Z] Finished create-virtualenv step (success) 01:32:45 INFO - [mozharness: 2016-09-20 08:32:45.941454Z] Running install step. 01:32:45 INFO - Running pre-action listener: _resource_record_pre_action 01:32:45 INFO - Running main action method: install 01:32:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:32:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:32:46 INFO - Reading from file tmpfile_stdout 01:32:46 INFO - Detecting whether we're running mozinstall >=1.0... 01:32:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:32:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:32:46 INFO - Reading from file tmpfile_stdout 01:32:46 INFO - Output received: 01:32:46 INFO - Usage: mozinstall [options] installer 01:32:46 INFO - Options: 01:32:46 INFO - -h, --help show this help message and exit 01:32:46 INFO - -d DEST, --destination=DEST 01:32:46 INFO - Directory to install application into. [default: 01:32:46 INFO - "/builds/slave/test"] 01:32:46 INFO - --app=APP Application being installed. [default: firefox] 01:32:46 INFO - mkdir: /builds/slave/test/build/application 01:32:46 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:32:46 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:32:59 INFO - Reading from file tmpfile_stdout 01:32:59 INFO - Output received: 01:32:59 INFO - /builds/slave/test/build/application/firefox/firefox 01:32:59 INFO - Running post-action listener: _resource_record_post_action 01:32:59 INFO - [mozharness: 2016-09-20 08:32:59.748587Z] Finished install step (success) 01:32:59 INFO - [mozharness: 2016-09-20 08:32:59.748908Z] Running run-tests step. 01:32:59 INFO - Running pre-action listener: _resource_record_pre_action 01:32:59 INFO - Running main action method: run_tests 01:32:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:32:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:32:59 INFO - Python 2.7.3 01:32:59 INFO - Return code: 0 01:32:59 INFO - grabbing minidump binary from tooltool 01:32:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:32:59 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 0x1e37570>, '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 0x1e38470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e38900>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:32:59 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:32:59 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:32:59 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:32:59 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:32:59 INFO - Return code: 0 01:32:59 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 01:32:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:32:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:32:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 01:32:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:32:59 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-068', '--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:32:59 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-068 --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:32:59 INFO - Using env: {'DISPLAY': ':0', 01:32:59 INFO - 'HOME': '/home/cltbld', 01:32:59 INFO - 'LANG': 'en_US.UTF-8', 01:32:59 INFO - 'LANGUAGE': 'en_US:en', 01:32:59 INFO - 'LOGNAME': 'cltbld', 01:32:59 INFO - 'MAIL': '/var/mail/cltbld', 01:32:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:32:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 01:32:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:32:59 INFO - 'MOZ_NO_REMOTE': '1', 01:32:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:32:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:32:59 INFO - 'NO_EM_RESTART': '1', 01:32:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:32:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:32:59 INFO - 'PWD': '/builds/slave/test', 01:32:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:32:59 INFO - 'SHELL': '/bin/bash', 01:32:59 INFO - 'SHLVL': '1', 01:32:59 INFO - 'TERM': 'linux', 01:32:59 INFO - 'TMOUT': '86400', 01:32:59 INFO - 'USER': 'cltbld', 01:32:59 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1474360337.28753-1459457385', 01:32:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:32:59 INFO - '_': '/tools/buildbot/bin/python'} 01:32:59 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-068', '--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:33:00 INFO - mozversion application_buildid: 20160919151517 01:33:00 INFO - mozversion application_changeset: 4376f2389699910ebe72e39977d563c77a094a1d 01:33:00 INFO - mozversion application_display_name: Firefox 01:33:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:33:00 INFO - mozversion application_name: Firefox 01:33:00 INFO - mozversion application_remotingname: firefox 01:33:00 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 01:33:00 INFO - mozversion application_vendor: Mozilla 01:33:00 INFO - mozversion application_version: 50.0 01:33:00 INFO - mozversion platform_buildid: 20160919151517 01:33:00 INFO - mozversion platform_changeset: 4376f2389699910ebe72e39977d563c77a094a1d 01:33:00 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 01:33:00 INFO - mozversion platform_version: 50.0 01:33:00 INFO - using testdate: 1474360380 01:33:00 INFO - actual date: 1474360380 01:33:00 INFO - starting webserver on 'localhost:55314' 01:33:00 INFO - SUITE-START | Running 2 tests 01:33:00 INFO - TEST-START | dromaeo_css 01:33:00 INFO - operating with platform_type : linux_ 01:33:00 INFO - Initialising browser for dromaeo_css test... 01:33:00 INFO - TEST-INFO | started process 18749 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQbmdfO/profile http://localhost:55314/getInfo.html) 01:33:04 INFO - PROCESS | 18749 | __metrics Screen width/height:1600/1200 01:33:04 INFO - PROCESS | 18749 | colorDepth:24 01:33:04 INFO - PROCESS | 18749 | Browser inner width/height: 1024/697 01:33:04 INFO - PROCESS | 18749 | __metrics 01:33:05 ERROR - PROCESS | 18749 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 01:33:05 INFO - TEST-INFO | 18749: exit 0 01:33:05 INFO - Browser initialized. 01:33:05 INFO - Running cycle 1/1 for dromaeo_css test... 01:33:05 INFO - TEST-INFO | started process 18877 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQbmdfO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:33:17 INFO - PROCESS | 18877 | RSS: Main: 149913600 01:33:17 INFO - PROCESS | 18877 | 01:34:07 INFO - PROCESS | 18877 | 1474360447256 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:36:15 INFO - PROCESS | 18877 | Cycle 1(1): loaded http://localhost:55314/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55314/tests/dromaeo/cssquery-ext.html) 01:36:17 INFO - PROCESS | 18877 | RSS: Main: 157814784 01:36:17 INFO - PROCESS | 18877 | 01:39:19 INFO - PROCESS | 18877 | Cycle 1(1): loaded http://localhost:55314/tests/dromaeo/cssquery-ext.html (next: http://localhost:55314/tests/dromaeo/cssquery-jquery.html) 01:39:19 INFO - PROCESS | 18877 | RSS: Main: 155418624 01:39:19 INFO - PROCESS | 18877 | 01:42:21 INFO - PROCESS | 18877 | Cycle 1(1): loaded http://localhost:55314/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55314/tests/dromaeo/cssquery-mootools.html) 01:42:22 INFO - PROCESS | 18877 | RSS: Main: 154734592 01:42:22 INFO - PROCESS | 18877 | 01:45:23 INFO - PROCESS | 18877 | Cycle 1(1): loaded http://localhost:55314/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55314/tests/dromaeo/cssquery-prototype.html) 01:45:24 INFO - PROCESS | 18877 | RSS: Main: 156442624 01:45:24 INFO - PROCESS | 18877 | 01:48:27 INFO - PROCESS | 18877 | Cycle 1(1): loaded http://localhost:55314/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55314/tests/dromaeo/cssquery-yui.html) 01:48:27 INFO - PROCESS | 18877 | RSS: Main: 157487104 01:48:27 INFO - PROCESS | 18877 | 01:51:29 INFO - PROCESS | 18877 | Cycle 1(1): loaded http://localhost:55314/tests/dromaeo/cssquery-yui.html (next: http://localhost:55314/tests/dromaeo/cssquery-dojo.html) 01:51:30 INFO - PROCESS | 18877 | RSS: Main: 156397568 01:51:30 INFO - PROCESS | 18877 | 01:51:30 INFO - PROCESS | 18877 | __start_tp_report 01:51:30 INFO - PROCESS | 18877 | _x_x_mozilla_page_load 01:51:30 INFO - PROCESS | 18877 | _x_x_mozilla_page_load_details 01:51:30 INFO - PROCESS | 18877 | |i|pagename|runs| 01:51:30 INFO - PROCESS | 18877 | |0;dojo.html;3260.739140868731;3292.407366673998;3299.5215693724417;3302.950455743163;3322.4019676458242;5737.254156959594;5792.565557583182;5805.187273781668;5807.1579620954935;5828.970855145729;5813.563982701304;5815.38938411467;5834.5915785895;5835.299764028312;5840.386759390267;5178.896422071556;5268.47315268472;5300.602454815885;5300.787968481256;5304.92042619361;5162.638615296931;5171.896562068757;5211.817586384477;5276.12943864262;5344.545713614348;5214.270002199695;5268.578513718893;5278;5293.04725149473;5293.550048245885;5325.973370133163;5352.090144675414;5352.705601191933;5358.169483730008;5375.220593013991;5078.695278283278;5130.435652078306;5164.845054648366;5246.921296180561;5260.368755749335;5649.265595472603;5670.943290567083;5681.829545113653;5697.344805347397;5738.598298119134;6080.847978800522;6088.695565221766;6135.78524751636;6142.508599312045;6155.384461553854;6048.092786082079;6117.296510901214;6121.666376154749;6191.752329906798;6215.067739839015;5745.166950792153;5826.388229235954;5843.1235314703135;5861.88276234473;5898.941010589882;6371.108044873721;6387.457066149336;6393.360663933569;6395.936040639534;6410.038494225811;5867.442592953748;5940.495057920125;6093.4515893568905;6099.817005489843;6111.938880611226;6115.449609535163;6153.230846144232;6183.3816618337805;6224.06639004153;6226.159468471698;6123.326434092335;6125.2649682037545;6163.167972323679;6165.691715414211;6168.8766224675255;5996.790112346047;6119.167450728499;6192.535559832941;6194.38056194386;6198.101275315144;4740.051989602093;4774.140654682191;4790.7365094920115;4807.567318941242;4824.035192961422;4240.257954857949;4249.617534421917;4255.5957184067465;4275.123599662062;4303.806328715215;5691.9715401423555;5893.823185304448;5991.790287340009;6030.306514750772;6041.5468839837185;6217.7823776168525;6319.842003949846;6338.936610633927;6363.204599425072;6366.586171898812;6007.36922623127;6021.969890150521;6026.186464827279;6028.909566356509;6033.276006879202;5156.458571849902;5342.8664283392445;5464.562834973192;5467.289252397242;5469.124940009579;6380.680965951684;6490.740370385131;6491.805245842632;6493.707783149675;6495.058216558573;6205.968970155121;6325.4939604832425;6334.429901308904;6337;6341.682915854097;6147.600405973598;6268.843278918063;6467;6496.317886621837;6505.772297969737;6036.849078773066;6130.795626845537;6144.863200307957;6150.600210986271;6156.78451254204;6265;6266.2480502338185;6266.373362663697;6271.78048768291;6278.121063051085;5930.940690593211;6057.454829065406;6100.389961004042;6103.298120715996;6120.54095942815;6026.668533230714;6116.944513560587;6122.081687746873;6131.804871721949;6136.733532366248;6073.240844894388;6097.176881120992;6136.662483563268;6140.109684095871;6146.78486252979;5978.760849565969;6050.7882224123705;6099.176611157437;6185.350538193425;6223.3465486125115;5939.851503712442;6006.249218847644;6017.097435384552;6041.335453100243;6061.9393806063135 01:51:30 INFO - PROCESS | 18877 | |1;ext.html;573.4380307298848;607.3440684061214;630.9589876658017;633.0757094641823;678.3420082519954;41537.58462415375;47316.76341618291;47320.526794732;47353.28970065452;47381.289280660814;47161;47474.525254747445;47772.52227477724;47902;47937.28094078592;9153.862692059607;9223.769405764842;9253.6298548058;9264.44413335202;9281.396709213914;9252.953735231315;9259.814803703923;9338.859917101216;9340.813183736322;9352.438853668802;9307.255419566452;9324.8601270981;9369.625214991393;9379;9396.953015234914;9321.161095501404;9325.114114159178;9334.953325233364;9371.016043315421;9394.953025234898;9175.862362064574;9263.490508022056;9265.397749146285;9277.35058545902;9312.44125352481;44192;44680.77659611681;44960.3255951161;44976.77511612421;44992;3636.672699457035;3941.625545573195;4202.873913782591;4609.539046095397;4619.722816631013;35642.465363019575;35897;35993.82003089994;36236.818815906016;36330;4671.205894997858;4709.9764501177615;4723.527647235283;4739.549742774431;4764.166270902613;419191;420747;420834;422867;424096;68577;68739;68816.65591672009;68931.3106868935;69288.3071169292;40472;71313.6434317825;71412.2858771416;71436.64281678663;71563.64218178876;18539.258429662932;18882;18972.051397430074;19028.71456928147;19058.33295834667;15175.468858589897;15345.46290879814;15347.079175249297;15379.231038448033;15433.074015559103;21384.251551195634;21603.35189944304;21688.24091156802;21758.803265820214;21822;7595.962020189863;7653.349465295512;7692.42306826979;7738.380929525624;7799.766007019798;1059.9099076578577;1113.0094216147636;1160.5937921727327;1176.7822952753768;1194.2834299420451;34455;34680.826595866856;34691.653083468846;34847.30305393878;35176.82411587926;1221.1879100398314;1278.386374540226;1282.7434513097419;1298.0524217321408;1300.9349532523336;17675.11624418774;18080.18639161241;18726.812731872506;18778.53053673669;18851.622967540847;469291;658927;659584;669233;671965;67346.66326668335;68547.31452685509;68587;68837.3116268841;69022.65488672524;1486.1231873194868;1504.465914600323;1504.857038581333;1517.9013364131297;1518.3395223077775;35162.824185878904;35379.292414152085;35552;35937;36324;58915.41084589099;59528;59540.70229648824;59655.105173423304;59689.403105968384;582170;585606;586845;587702;588035;31775.36449271048;32490.675093248767;32626.184345390604;33051.17372065718;34159.658403416644;55018.72490637521;55038.724806375714;55092.72453637706;55322.44677553334;55781.44218557762;45058.32412513749;45643.54356456393;45905;46190.538094619966;47462.050758985315;46999.18997758821;47514.76242618765;47770.28344574904;48024;48040.557414715106;45774.77112614549;46245;46719.76640116778;47369.76315118402;48886.75556622194;39163.804180978914;39947;39994.20011599694;41219.7939010315;41254.1749165009;30898;31466.21334466565;31712.682873171896;32094.518582221754;32122.678773212905 01:51:30 INFO - PROCESS | 18877 | |2;jquery.html;392.980350982451;393.97636141831475;401.0855250029931;410.96095870892265;414.7117753161552;19449.124789384477;21337.893310533444;21365.145394184216;21732.3480295591;22246.443838904037;20221.797782022175;20613.38159855203;20665.896670516664;20866.791332086676;21060;6008.909866352008;6163.075538669213;6177.104319873616;6195.690215489208;6204.81385558434;5785.479306862381;6002.489788367994;6101.816945491621;6127.325994140663;6129.172561704179;6112.205413296265;6117.66352850593;6125.571210015279;6137.877242455148;6250.4999600032115;6614.768483103092;6660.733570657168;6829.760958366433;6988.091548098765;6989.370956613879;6002.339742628351;6048.153258543807;6116.571839971204;6140.109684095781;6157.568970172089;14404.495842645458;14582.197979111144;14795.334209960578;14926.70146597073;15102.622434439227;2441.072392490842;2471.616899380599;2648.8543130127837;2658.2025392382207;3092.9638571078717;10603.734906627318;10696.144308455383;10748.73128171794;10776.084032857216;10852.186086043499;2009.7990200979932;2040.9897950510301;2082.135913595856;2145.967810482844;2152.3865698275918;88941.55529222377;89370;90026.5498672509;90329.54835225781;91174;9863.901360986441;10215;10254.43600601959;11187.440627968568;11436.656900293005;16060;16064.518064458085;16072.196390180678;16073.517794466185;16085.758713619305;8436.198561136682;8627.611757470804;8733.126687331216;8882.955585222033;8897.866532002025;8445.831083378422;8544;8606.354523410644;8785.34109941757;8840.071792461698;4677.64917631179;4703.435587729495;4732.384789977281;4879.536444037811;4971.1797553404;2093.445237012193;2106.252280440452;2140.539783946459;2153.4616345913523;2166.9349919502442;1023.1967905194387;1042.2756184451885;1096.4298564746446;1105.4528008635616;1131.179894576442;20381.2866549673;20790;20830.791692082887;20949.47626309324;21051.8947405262;1049.7113293844254;1088.678839742278;1095.3865835132192;1133.8752737198902;1135.2280449294394;11042.171837112088;11501.42492875353;11545.249558778652;11560.710982225344;11566.65300041;533047;549999;553215;556407;556570;61408.692956534935;63301.36698632954;91440;96542;97819.51090244646;1053.8419237114495;1056.5456853552892;1082.783443311341;1085.5223701571442;1097.1935627313917;18471.90764046171;18679.533011674815;18783.34258300953;19025;19467.70798438053;44697.32954005757;44721.329180062974;44776.77611611921;45175.32237016513;45829.08341833211;62470.68764656148;63867;67341.3265867335;68273.65863170652;68693.65653171903;8940.86588701183;9032;9071.138241867013;9143.725688229364;9271.397359171633;9680.41917484925;12567.245965242115;12878.034147438791;13021.934890325489;13047.34763261814;11480.94259528697;11643;11795;11816.527338906692;11926.761464770829;11321.830172547583;11405.429728513374;11465;11498.367589782307;11724;11417.486213120595;11566.94216528912;11688.88311116878;11741.823872641746;11966.940165299118;8977.730668079968;9032.683856065007;9041.276697864023;9091.181793638605;9147.313951453802;9720.659776907774;11352.375619341015;11418.371989540656;11614.187006909435;11756.58851940178 01:51:30 INFO - PROCESS | 18877 | |3;mootools.html;1575.8109026916768;1606.903585784853;1791.3103455169755;1812.2841477616334;1867.9539457903584;8351.707690230844;8853.867191992125;9055.95472022639;9637.132658060782;9658.468784216866;9295.767605809859;9400.90599094009;9405.95297023514;9423.15191632753;9438.056194380559;2415.45652228249;2445.3764290105996;2461.569225385559;2462.938426539333;2471.480988992314;2407.830666799167;2424.248482970276;2438.2928950604305;2442.1086303499183;2444.9755502444928;2478.987605061981;2479.3553676044266;2483.1557270528015;2509.924702258926;2516.421223118684;2408.6025805742033;2412.7225369082516;2414.9275521734376;2471.9134830280855;2472.5920223163157;2431.294924471919;2439.731629520751;2444.69441319835;2459.114718701266;2460.0282888259217;9654.420734755937;9699.175570076552;9728.17310528606;9744.220462362993;9748.610055597768;5587.469190426903;5609.971950140264;5636.915446268309;5649.096144616883;5655.802046928337;3601.243738814852;3654.5979942206336;3686.1337585667347;3724.0689827543115;3842.846286148551;1211.7758214730216;1219.9024078073733;1260.6281147061638;1283.871612838718;1306.6537367597593;13524;13597.388117534734;13666.11170273929;13687.11033782801;13704.3833027514;4718.245080787057;4825.324454576362;4833.05755377698;4846.105433512108;4861.416630004422;11575.942120289345;11597.6520704379;11601.419929003685;11605.88394116065;11614.593489227836;5599.1041433370465;5624.3813180550105;5637.267155269788;5642.858928526737;5647.1529270609735;4652.302154676826;5109.56568691658;5140.871478213;5228.189630607262;5285.867853303698;4286.078493123931;4345.500267469219;4372.344148377705;4392.363107349416;4411.117776444724;1370.0683535195963;1378.9241591712353;1384.4531410092957;1385.1827421820972;1395.5673741140279;1084.1760262200785;1084.322298563398;1087.9292845964987;1103.051375816797;1111.8109921313235;6340.936590634127;6393.296737358887;6461.741530338828;6540;6584.407403333723;481.55456203012074;482.78998635593626;497.7834641930772;504.16308927180705;504.32924210799547;4456.242438785414;4580.97709511457;4617.284320930261;4660.930086048644;4707.693999889996;11127.721806954729;11362.829557556643;11498.42507874603;11545.538178472934;11571.479283432265;11378.203525753283;11476.483558239732;11478.426078696031;11488.54045838157;11512.884871151182;8572.185642363966;9079.727608171765;9090.136437038604;9090.909090909006;9106.954465227764;6627.635480048643;6659.800205993828;6799.456043516606;6807.3873351398615;6968.895466567904;10930.289531180448;11137.331760094421;11208.943955280172;11356.94321528387;11419.885801141882;11419.429028548706;11530.82703759461;11542.82685759731;11583.478743456397;11716.23844450108;8354.45696029756;8369.790755230924;8467.915320846712;8662.13378662154;8688.13118688126;9607.471589062388;9689.612415503583;9712.223022158352;9719.319647624881;9721.125098741148;8477;8495;8642.827143457222;8706.56467176626;8738.344624153087;8476.830463390821;8525.104863989192;8529.658813647633;8648.394612377073;8665.566721663765;7518.097828260644;7605.809854753676;7633;7689.42329325314;7691.846163076819;5727.770889164387;5791.449812267828;5841.153032810304;5887.61730487517;5983.461488466059;5787.884242315215;5840.269966254218;5873.060310350324;5883.35283118848;5906.852328691817 01:51:30 INFO - PROCESS | 18877 | |4;prototype.html;335.75657648205055;339.0641828553194;343.0258067089467;344.87239721303115;346.9132716820795;5728.877761619917;5846.064629659255;5859.033259512181;5868.677222752742;5883.88232235355;5742.54059675226;5750;5757.136429535572;5773.191753154561;5793.449622285887;4271.658267338606;4360.629346505535;4423.535528769481;4471.256210078072;4471.66462515311;4443.15580039793;4453.3987911632;4459.375687403766;4459.531749166339;4465.4641443026885;4388.868333949987;4401.449818772653;4404.515503294635;4413.293872980324;4413.7351758894565;4272.9359059614135;4403.273459879116;4444.577765112309;4447.666425018137;4455.1757924784015;3904.4728961590104;3909.960900390988;3912.2762288976633;3920.627540383659;3928.135810121769;5544.86137846553;5567.3040869891265;5578.187089196028;5578.721063946827;5590.720463976785;4923.606111511071;5087.745612719349;5105.2086926526445;5139.306193663845;5142.485751424828;5998.97000514999;5999.340072592011;6003.549733769941;6030.984507746127;6064.363241859585;4332.718373305725;4334.349847522865;4339.913201735948;4340.631046361063;4356.738595684269;6093.421124993163;6255.468285195764;6274.654893980829;6288.528360372945;6328.936710632881;4340.8697739067875;4344.653157521178;4354.867734389081;4355.649748577887;4356.1287742451;4415.757133357632;4419.381286619876;4419.558044195554;4434.2240107981615;4434.756088415168;4918.729469879191;5083.669561478492;5103.744812759347;5114.948850511521;5118.38579370478;6093.69531523422;6099.390060993954;6100.206973093469;6115.877682446326;6126.78556250538;1517.6661134550382;1530.594392485992;1541.714782765192;1543.5600853756625;1548.3961255110517;943.3302355327794;968.6803354892868;978.3445091788519;1003.6136087606324;1021.6781713760141;5763.394843541451;5822.359540450547;5830.271216097988;5859.736311865976;5882.3529411764675;6243.531735119885;6254.812355629248;6255.186825712628;6264.123022776815;6277.340879207617;5600.27196464457;5882.529397648178;5906.143609176645;6001.729922153513;6002.519798416116;3357.8824741133944;3376.7973921564294;3378.290558982592;3378.5607870977105;3399.847006884696;6242.531810114168;6444.77443289492;6462.159919210472;6506.804795856132;6516.165468021426;6349.206349206349;6378.681065946684;6406.199225096863;6424.453921416639;6433.876820649175;6272.435480806658;6341.524385671001;6421.715656868645;6433.011532849569;6458.773942911976;6277.108192920418;6332.398422149889;6336.683165841598;6345;6355.553112051801;6220.813375598739;6228.22147231596;6230.314665386713;6249.437550620466;6273.15312432834;6198.690065496798;6432.2602900667025;6469.644169570719;6485.740570377132;6491.318411566906;4352.325389564622;4362.189691278084;4393.340998850198;4397.670174736972;4402.603765661106;6058.636481811105;6276.152719383008;6285.214348206474;6299;6317.431431171217;6093.6039157454625;6097.140372186525;6102.694865256808;6109.434037704086;6116.5106791457465;6120.17377654011;6142.477889379453;6142.78500252489;6156.322804491592;6190.50475961913;5833.562482813721;5968.314258583568;6132.650816820293;6186.1648677427975;6194.225721784777;6151.50787936955;6190.288116866618;6200.3179650237535;6200.441960223601;6213.37866213375;5921.230240068763;6048.727807248596;6061.393860613904;6061.878762424815;6072.848178795565 01:51:30 INFO - PROCESS | 18877 | |5;yui.html;829.9128591497893;843.5065486690286;872.1191596487549;913.2511340700628;955.3793301454356;16437.42469013585;17723.29106835728;17830.554236144104;17836.197371118298;17863.553411164743;16571.66856662866;16593.66812663746;16936.915315423437;16946.576335591617;17424.56438589036;870.3776799588293;891.9554022298892;893.4014210478995;896.4307664632939;898.8427025270585;894.4186278918689;907.2787134228304;911.2300106410073;911.2709832134299;915.2997956563235;951.7097285327973;951.9762005949838;953.7710949372135;954.2985905359556;954.7899462118323;945.5792172483214;952.5046975572733;953.580424613168;954.9665761698342;958.5063692198523;697.7801992372437;698.598305974067;698.7833771530842;699.8495323505471;701.2006312803409;17711.114444277733;17854;17874.374396896175;17893.7315940261;17954.371596994042;15888.841111588852;16089;16509.422170223985;16732.498025059147;16861.662766744717;13034.869651303461;13241;13250.006249531323;13499.86500134996;13630.727385452235;4163.271427500205;4184.937225941613;4187.078842654641;4218.240716670998;4332.891677708082;59558.40441595615;61519.384806151815;62650.0602490963;62763.372366276664;91580.54209728909;12813.167144135601;12885.098043137077;13064.934675326562;13194.736105278032;13322.267275299952;23138;23223.883880580488;23224.4193895154;23237.186698465473;23349.766502334758;9411.294152938557;9548.092931171528;9607.231421486264;9626.855597166048;9703.07820757027;5103.18349064148;5113.207452844815;5127.282180494733;5165.380154381498;5247.134222853263;3954.9011274717786;4098.180363927227;4099.7745124017865;4100.507939047274;4432.911341773147;753.408574269196;766.9501482403626;770.8535378278109;779.7757520692459;791.1771757372384;766.6703317573497;768.1780494870546;797.1589972578869;829.6266679994027;840.14305408483;10416.947915260374;10734.355938643706;11176;11461.426928653535;11633.418329083512;711.0791524975169;761.1969372312153;769.3460558525321;813.507827764206;841.8821365008904;4755.762211889391;5143.974280128576;5178.741062946838;5254.97372513135;5275.683458992473;134102;136686;137046;138907.30546347203;140209.2989535046;119499;120485;121816;123050;124954;103335.4833225829;104065.47967260116;105051;105384;107904.46047769711;10201.84697229557;10376.584936602754;10437;10452.163826893679;10591.20565957541;58752;59271.703641481516;59337;60818;63168;76025.61987190029;78702;87100.56449717964;90589.54705226432;90707;13691.315434228049;15025.774613381027;15120.243987800346;15169.544913652608;15785.68428631444;16272;17090.470157517353;17095.316187352368;17147;17786.46640600784;13734.313284335944;14487.92756036213;15410.768838467657;15790.289436975134;16015.279312431201;15515.22423878779;15604;16024.543166485675;16062;16503.33986640521;12293.754124917272;12528.874711253135;12579.622611321674;15293.082415055133;15638.609034774221;12796.808047879103;13257.138286011379;13422.597322080353;13625.116841700068;13901.51344702971;11863.881361186623;11896.76206475883;12343.814842777187;12396.380180991095;12874.549390771279 01:51:30 INFO - PROCESS | 18877 | __end_tp_report 01:51:30 INFO - PROCESS | 18877 | __start_cc_report 01:51:30 INFO - PROCESS | 18877 | _x_x_mozilla_cycle_collect,355 01:51:30 INFO - PROCESS | 18877 | __end_cc_report 01:51:30 INFO - PROCESS | 18877 | __startTimestamp1474361490242__endTimestamp 01:51:30 INFO - PROCESS | 18877 | 01:51:30 INFO - PROCESS | 18877 | ------- Summary: start ------- 01:51:30 INFO - PROCESS | 18877 | Number of tests: 6 01:51:30 INFO - PROCESS | 18877 | 01:51:30 INFO - PROCESS | 18877 | [#0] dojo.html Cycles:165 Average:5774.32 Median:6033.28 stddev:665.47 (11.0%) stddev-sans-first:637.63 01:51:30 INFO - PROCESS | 18877 | Values: 3260.7 3292.4 3299.5 3303.0 3322.4 5737.3 5792.6 5805.2 5807.2 5829.0 5813.6 5815.4 5834.6 5835.3 5840.4 5178.9 5268.5 5300.6 5300.8 5304.9 5162.6 5171.9 5211.8 5276.1 5344.5 5214.3 5268.6 5278.0 5293.0 5293.6 5326.0 5352.1 5352.7 5358.2 5375.2 5078.7 5130.4 5164.8 5246.9 5260.4 5649.3 5670.9 5681.8 5697.3 5738.6 6080.8 6088.7 6135.8 6142.5 6155.4 6048.1 6117.3 6121.7 6191.8 6215.1 5745.2 5826.4 5843.1 5861.9 5898.9 6371.1 6387.5 6393.4 6395.9 6410.0 5867.4 5940.5 6093.5 6099.8 6111.9 6115.4 6153.2 6183.4 6224.1 6226.2 6123.3 6125.3 6163.2 6165.7 6168.9 5996.8 6119.2 6192.5 6194.4 6198.1 4740.1 4774.1 4790.7 4807.6 4824.0 4240.3 4249.6 4255.6 4275.1 4303.8 5692.0 5893.8 5991.8 6030.3 6041.5 6217.8 6319.8 6338.9 6363.2 6366.6 6007.4 6022.0 6026.2 6028.9 6033.3 5156.5 5342.9 5464.6 5467.3 5469.1 6380.7 6490.7 6491.8 6493.7 6495.1 6206.0 6325.5 6334.4 6337.0 6341.7 6147.6 6268.8 6467.0 6496.3 6505.8 6036.8 6130.8 6144.9 6150.6 6156.8 6265.0 6266.2 6266.4 6271.8 6278.1 5930.9 6057.5 6100.4 6103.3 6120.5 6026.7 6116.9 6122.1 6131.8 6136.7 6073.2 6097.2 6136.7 6140.1 6146.8 5978.8 6050.8 6099.2 6185.4 6223.3 5939.9 6006.2 6017.1 6041.3 6061.9 01:51:30 INFO - PROCESS | 18877 | 01:51:30 INFO - PROCESS | 18877 | [#1] ext.html Cycles:180 Average:71757.35 Median:258145.09 stddev:147271.94 (57.1%) stddev-sans-first:147588.10 01:51:30 INFO - PROCESS | 18877 | Values: 573.4 607.3 631.0 633.1 678.3 41537.6 47316.8 47320.5 47353.3 47381.3 47161.0 47474.5 47772.5 47902.0 47937.3 9153.9 9223.8 9253.6 9264.4 9281.4 9253.0 9259.8 9338.9 9340.8 9352.4 9307.3 9324.9 9369.6 9379.0 9397.0 9321.2 9325.1 9335.0 9371.0 9395.0 9175.9 9263.5 9265.4 9277.4 9312.4 44192.0 44680.8 44960.3 44976.8 44992.0 3636.7 3941.6 4202.9 4609.5 4619.7 35642.5 35897.0 35993.8 36236.8 36330.0 4671.2 4710.0 4723.5 4739.5 4764.2 419191.0 420747.0 420834.0 422867.0 424096.0 68577.0 68739.0 68816.7 68931.3 69288.3 40472.0 71313.6 71412.3 71436.6 71563.6 18539.3 18882.0 18972.1 19028.7 19058.3 15175.5 15345.5 15347.1 15379.2 15433.1 21384.3 21603.4 21688.2 21758.8 21822.0 7596.0 7653.3 7692.4 7738.4 7799.8 1059.9 1113.0 1160.6 1176.8 1194.3 34455.0 34680.8 34691.7 34847.3 35176.8 1221.2 1278.4 1282.7 1298.1 1300.9 17675.1 18080.2 18726.8 18778.5 18851.6 469291.0 658927.0 659584.0 669233.0 671965.0 67346.7 68547.3 68587.0 68837.3 69022.7 1486.1 1504.5 1504.9 1517.9 1518.3 35162.8 35379.3 35552.0 35937.0 36324.0 58915.4 59528.0 59540.7 59655.1 59689.4 582170.0 585606.0 586845.0 587702.0 588035.0 31775.4 32490.7 32626.2 33051.2 34159.7 55018.7 55038.7 55092.7 55322.4 55781.4 45058.3 45643.5 45905.0 46190.5 47462.1 46999.2 47514.8 47770.3 48024.0 48040.6 45774.8 46245.0 46719.8 47369.8 48886.8 39163.8 39947.0 39994.2 41219.8 41254.2 30898.0 31466.2 31712.7 32094.5 32122.7 01:51:30 INFO - PROCESS | 18877 | 01:51:30 INFO - PROCESS | 18877 | [#2] jquery.html Cycles:180 Average:31074.20 Median:21989.40 stddev:90451.40 (411.3%) stddev-sans-first:90675.81 01:51:30 INFO - PROCESS | 18877 | Values: 393.0 394.0 401.1 411.0 414.7 19449.1 21337.9 21365.1 21732.3 22246.4 20221.8 20613.4 20665.9 20866.8 21060.0 6008.9 6163.1 6177.1 6195.7 6204.8 5785.5 6002.5 6101.8 6127.3 6129.2 6112.2 6117.7 6125.6 6137.9 6250.5 6614.8 6660.7 6829.8 6988.1 6989.4 6002.3 6048.2 6116.6 6140.1 6157.6 14404.5 14582.2 14795.3 14926.7 15102.6 2441.1 2471.6 2648.9 2658.2 3093.0 10603.7 10696.1 10748.7 10776.1 10852.2 2009.8 2041.0 2082.1 2146.0 2152.4 88941.6 89370.0 90026.5 90329.5 91174.0 9863.9 10215.0 10254.4 11187.4 11436.7 16060.0 16064.5 16072.2 16073.5 16085.8 8436.2 8627.6 8733.1 8883.0 8897.9 8445.8 8544.0 8606.4 8785.3 8840.1 4677.6 4703.4 4732.4 4879.5 4971.2 2093.4 2106.3 2140.5 2153.5 2166.9 1023.2 1042.3 1096.4 1105.5 1131.2 20381.3 20790.0 20830.8 20949.5 21051.9 1049.7 1088.7 1095.4 1133.9 1135.2 11042.2 11501.4 11545.2 11560.7 11566.7 533047.0 549999.0 553215.0 556407.0 556570.0 61408.7 63301.4 91440.0 96542.0 97819.5 1053.8 1056.5 1082.8 1085.5 1097.2 18471.9 18679.5 18783.3 19025.0 19467.7 44697.3 44721.3 44776.8 45175.3 45829.1 62470.7 63867.0 67341.3 68273.7 68693.7 8940.9 9032.0 9071.1 9143.7 9271.4 9680.4 12567.2 12878.0 13021.9 13047.3 11480.9 11643.0 11795.0 11816.5 11926.8 11321.8 11405.4 11465.0 11498.4 11724.0 11417.5 11566.9 11688.9 11741.8 11966.9 8977.7 9032.7 9041.3 9091.2 9147.3 9720.7 11352.4 11418.4 11614.2 11756.6 01:51:30 INFO - PROCESS | 18877 | 01:51:30 INFO - PROCESS | 18877 | [#3] mootools.html Cycles:180 Average:6335.89 Median:4712.97 stddev:3646.39 (77.4%) stddev-sans-first:3639.07 01:51:30 INFO - PROCESS | 18877 | Values: 1575.8 1606.9 1791.3 1812.3 1868.0 8351.7 8853.9 9056.0 9637.1 9658.5 9295.8 9400.9 9406.0 9423.2 9438.1 2415.5 2445.4 2461.6 2462.9 2471.5 2407.8 2424.2 2438.3 2442.1 2445.0 2479.0 2479.4 2483.2 2509.9 2516.4 2408.6 2412.7 2414.9 2471.9 2472.6 2431.3 2439.7 2444.7 2459.1 2460.0 9654.4 9699.2 9728.2 9744.2 9748.6 5587.5 5610.0 5636.9 5649.1 5655.8 3601.2 3654.6 3686.1 3724.1 3842.8 1211.8 1219.9 1260.6 1283.9 1306.7 13524.0 13597.4 13666.1 13687.1 13704.4 4718.2 4825.3 4833.1 4846.1 4861.4 11575.9 11597.7 11601.4 11605.9 11614.6 5599.1 5624.4 5637.3 5642.9 5647.2 4652.3 5109.6 5140.9 5228.2 5285.9 4286.1 4345.5 4372.3 4392.4 4411.1 1370.1 1378.9 1384.5 1385.2 1395.6 1084.2 1084.3 1087.9 1103.1 1111.8 6340.9 6393.3 6461.7 6540.0 6584.4 481.6 482.8 497.8 504.2 504.3 4456.2 4581.0 4617.3 4660.9 4707.7 11127.7 11362.8 11498.4 11545.5 11571.5 11378.2 11476.5 11478.4 11488.5 11512.9 8572.2 9079.7 9090.1 9090.9 9107.0 6627.6 6659.8 6799.5 6807.4 6968.9 10930.3 11137.3 11208.9 11356.9 11419.9 11419.4 11530.8 11542.8 11583.5 11716.2 8354.5 8369.8 8467.9 8662.1 8688.1 9607.5 9689.6 9712.2 9719.3 9721.1 8477.0 8495.0 8642.8 8706.6 8738.3 8476.8 8525.1 8529.7 8648.4 8665.6 7518.1 7605.8 7633.0 7689.4 7691.8 5727.8 5791.4 5841.2 5887.6 5983.5 5787.9 5840.3 5873.1 5883.4 5906.9 01:51:30 INFO - PROCESS | 18877 | 01:51:30 INFO - PROCESS | 18877 | [#4] prototype.html Cycles:180 Average:5110.40 Median:5864.21 stddev:1529.08 (26.1%) stddev-sans-first:1490.79 01:51:30 INFO - PROCESS | 18877 | Values: 335.8 339.1 343.0 344.9 346.9 5728.9 5846.1 5859.0 5868.7 5883.9 5742.5 5750.0 5757.1 5773.2 5793.4 4271.7 4360.6 4423.5 4471.3 4471.7 4443.2 4453.4 4459.4 4459.5 4465.5 4388.9 4401.4 4404.5 4413.3 4413.7 4272.9 4403.3 4444.6 4447.7 4455.2 3904.5 3910.0 3912.3 3920.6 3928.1 5544.9 5567.3 5578.2 5578.7 5590.7 4923.6 5087.7 5105.2 5139.3 5142.5 5999.0 5999.3 6003.5 6031.0 6064.4 4332.7 4334.3 4339.9 4340.6 4356.7 6093.4 6255.5 6274.7 6288.5 6328.9 4340.9 4344.7 4354.9 4355.6 4356.1 4415.8 4419.4 4419.6 4434.2 4434.8 4918.7 5083.7 5103.7 5114.9 5118.4 6093.7 6099.4 6100.2 6115.9 6126.8 1517.7 1530.6 1541.7 1543.6 1548.4 943.3 968.7 978.3 1003.6 1021.7 5763.4 5822.4 5830.3 5859.7 5882.4 6243.5 6254.8 6255.2 6264.1 6277.3 5600.3 5882.5 5906.1 6001.7 6002.5 3357.9 3376.8 3378.3 3378.6 3399.8 6242.5 6444.8 6462.2 6506.8 6516.2 6349.2 6378.7 6406.2 6424.5 6433.9 6272.4 6341.5 6421.7 6433.0 6458.8 6277.1 6332.4 6336.7 6345.0 6355.6 6220.8 6228.2 6230.3 6249.4 6273.2 6198.7 6432.3 6469.6 6485.7 6491.3 4352.3 4362.2 4393.3 4397.7 4402.6 6058.6 6276.2 6285.2 6299.0 6317.4 6093.6 6097.1 6102.7 6109.4 6116.5 6120.2 6142.5 6142.8 6156.3 6190.5 5833.6 5968.3 6132.7 6186.2 6194.2 6151.5 6190.3 6200.3 6200.4 6213.4 5921.2 6048.7 6061.4 6061.9 6072.8 01:51:30 INFO - PROCESS | 18877 | 01:51:30 INFO - PROCESS | 18877 | [#5] yui.html Cycles:180 Average:23752.41 Median:23180.94 stddev:35082.92 (151.3%) stddev-sans-first:35139.12 01:51:30 INFO - PROCESS | 18877 | Values: 829.9 843.5 872.1 913.3 955.4 16437.4 17723.3 17830.6 17836.2 17863.6 16571.7 16593.7 16936.9 16946.6 17424.6 870.4 892.0 893.4 896.4 898.8 894.4 907.3 911.2 911.3 915.3 951.7 952.0 953.8 954.3 954.8 945.6 952.5 953.6 955.0 958.5 697.8 698.6 698.8 699.8 701.2 17711.1 17854.0 17874.4 17893.7 17954.4 15888.8 16089.0 16509.4 16732.5 16861.7 13034.9 13241.0 13250.0 13499.9 13630.7 4163.3 4184.9 4187.1 4218.2 4332.9 59558.4 61519.4 62650.1 62763.4 91580.5 12813.2 12885.1 13064.9 13194.7 13322.3 23138.0 23223.9 23224.4 23237.2 23349.8 9411.3 9548.1 9607.2 9626.9 9703.1 5103.2 5113.2 5127.3 5165.4 5247.1 3954.9 4098.2 4099.8 4100.5 4432.9 753.4 767.0 770.9 779.8 791.2 766.7 768.2 797.2 829.6 840.1 10416.9 10734.4 11176.0 11461.4 11633.4 711.1 761.2 769.3 813.5 841.9 4755.8 5144.0 5178.7 5255.0 5275.7 134102.0 136686.0 137046.0 138907.3 140209.3 119499.0 120485.0 121816.0 123050.0 124954.0 103335.5 104065.5 105051.0 105384.0 107904.5 10201.8 10376.6 10437.0 10452.2 10591.2 58752.0 59271.7 59337.0 60818.0 63168.0 76025.6 78702.0 87100.6 90589.5 90707.0 13691.3 15025.8 15120.2 15169.5 15785.7 16272.0 17090.5 17095.3 17147.0 17786.5 13734.3 14487.9 15410.8 15790.3 16015.3 15515.2 15604.0 16024.5 16062.0 16503.3 12293.8 12528.9 12579.6 15293.1 15638.6 12796.8 13257.1 13422.6 13625.1 13901.5 11863.9 11896.8 12343.8 12396.4 12874.5 01:51:30 INFO - PROCESS | 18877 | -------- Summary: end -------- 01:51:30 INFO - PROCESS | 18877 | 01:51:30 INFO - TEST-INFO | 18877: exit 0 01:51:30 INFO - TEST-OK | dromaeo_css | took 1110884ms 01:51:30 INFO - TEST-START | kraken 01:51:30 INFO - operating with platform_type : linux_ 01:51:30 INFO - Initialising browser for kraken test... 01:51:30 INFO - TEST-INFO | started process 19118 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIx8QGX/profile http://localhost:55314/getInfo.html) 01:51:35 INFO - PROCESS | 19118 | __metrics Screen width/height:1600/1200 01:51:35 INFO - PROCESS | 19118 | colorDepth:24 01:51:35 INFO - PROCESS | 19118 | Browser inner width/height: 1024/697 01:51:35 INFO - PROCESS | 19118 | __metrics 01:51:35 ERROR - PROCESS | 19118 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 01:51:36 INFO - TEST-INFO | 19118: exit 0 01:51:36 INFO - Browser initialized. 01:51:36 INFO - Running cycle 1/1 for kraken test... 01:51:36 INFO - TEST-INFO | started process 19242 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIx8QGX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:51:48 INFO - PROCESS | 19242 | RSS: Main: 150089728 01:51:48 INFO - PROCESS | 19242 | 01:51:51 INFO - PROCESS | 19242 | Cycle 1(1): loaded http://localhost:55314/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55314/tests/kraken/driver.html?testName=audio-beat-detection) 01:51:51 INFO - PROCESS | 19242 | RSS: Main: 156991488 01:51:51 INFO - PROCESS | 19242 | 01:51:54 INFO - PROCESS | 19242 | Cycle 1(1): loaded http://localhost:55314/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55314/tests/kraken/driver.html?testName=audio-dft) 01:51:55 INFO - PROCESS | 19242 | RSS: Main: 155381760 01:51:55 INFO - PROCESS | 19242 | 01:51:57 INFO - PROCESS | 19242 | Cycle 1(1): loaded http://localhost:55314/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55314/tests/kraken/driver.html?testName=audio-fft) 01:51:58 INFO - PROCESS | 19242 | RSS: Main: 156041216 01:51:58 INFO - PROCESS | 19242 | 01:52:00 INFO - PROCESS | 19242 | Cycle 1(1): loaded http://localhost:55314/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55314/tests/kraken/driver.html?testName=audio-oscillator) 01:52:01 INFO - PROCESS | 19242 | RSS: Main: 155987968 01:52:01 INFO - PROCESS | 19242 | 01:52:03 INFO - PROCESS | 19242 | Cycle 1(1): loaded http://localhost:55314/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55314/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:52:04 INFO - PROCESS | 19242 | RSS: Main: 156459008 01:52:04 INFO - PROCESS | 19242 | 01:52:07 INFO - PROCESS | 19242 | Cycle 1(1): loaded http://localhost:55314/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55314/tests/kraken/driver.html?testName=imaging-darkroom) 01:52:07 INFO - PROCESS | 19242 | RSS: Main: 156729344 01:52:07 INFO - PROCESS | 19242 | 01:52:11 INFO - PROCESS | 19242 | Cycle 1(1): loaded http://localhost:55314/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55314/tests/kraken/driver.html?testName=imaging-desaturate) 01:52:11 INFO - PROCESS | 19242 | RSS: Main: 156610560 01:52:11 INFO - PROCESS | 19242 | 01:52:14 INFO - PROCESS | 19242 | Cycle 1(1): loaded http://localhost:55314/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55314/tests/kraken/driver.html?testName=json-parse-financial) 01:52:15 INFO - PROCESS | 19242 | RSS: Main: 156250112 01:52:15 INFO - PROCESS | 19242 | 01:52:16 INFO - PROCESS | 19242 | Cycle 1(1): loaded http://localhost:55314/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55314/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:52:17 INFO - PROCESS | 19242 | RSS: Main: 155734016 01:52:17 INFO - PROCESS | 19242 | 01:52:19 INFO - PROCESS | 19242 | Cycle 1(1): loaded http://localhost:55314/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55314/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:52:19 INFO - PROCESS | 19242 | RSS: Main: 155832320 01:52:19 INFO - PROCESS | 19242 | 01:52:21 INFO - PROCESS | 19242 | Cycle 1(1): loaded http://localhost:55314/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55314/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:52:22 INFO - PROCESS | 19242 | RSS: Main: 156102656 01:52:22 INFO - PROCESS | 19242 | 01:52:24 INFO - PROCESS | 19242 | Cycle 1(1): loaded http://localhost:55314/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55314/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:52:25 INFO - PROCESS | 19242 | RSS: Main: 156332032 01:52:25 INFO - PROCESS | 19242 | 01:52:28 INFO - PROCESS | 19242 | Cycle 1(1): loaded http://localhost:55314/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55314/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:52:28 INFO - PROCESS | 19242 | RSS: Main: 156889088 01:52:28 INFO - PROCESS | 19242 | 01:52:30 INFO - PROCESS | 19242 | Cycle 1(1): loaded http://localhost:55314/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55314/tests/kraken/driver.html?testName=ai-astar) 01:52:30 INFO - PROCESS | 19242 | RSS: Main: 158728192 01:52:30 INFO - PROCESS | 19242 | 01:52:30 INFO - PROCESS | 19242 | __start_tp_report 01:52:30 INFO - PROCESS | 19242 | _x_x_mozilla_page_load 01:52:30 INFO - PROCESS | 19242 | _x_x_mozilla_page_load_details 01:52:30 INFO - PROCESS | 19242 | |i|pagename|runs| 01:52:30 INFO - PROCESS | 19242 | |0;ai-astar;102;115;106;113;113;113;111;112;112;115 01:52:30 INFO - PROCESS | 19242 | |1;audio-beat-detection;148;119;138;174;117;144;147;120;173;120 01:52:30 INFO - PROCESS | 19242 | |2;audio-dft;163;163;164;162;162;162;163;166;161;164 01:52:30 INFO - PROCESS | 19242 | |3;audio-fft;76;75;76;180;76;75;75;76;166;76 01:52:30 INFO - PROCESS | 19242 | |4;audio-oscillator;85;89;140;84;84;84;147;82;84;143 01:52:30 INFO - PROCESS | 19242 | |5;imaging-gaussian-blur;118;117;117;117;117;117;117;116;117;119 01:52:30 INFO - PROCESS | 19242 | |6;imaging-darkroom;148;148;148;147;149;147;147;147;146;148 01:52:30 INFO - PROCESS | 19242 | |7;imaging-desaturate;96;95;95;96;95;100;95;96;95;97 01:52:30 INFO - PROCESS | 19242 | |8;json-parse-financial;54;53;53;52;53;53;54;53;52;55 01:52:30 INFO - PROCESS | 19242 | |9;json-stringify-tinderbox;63;60;59;60;59;59;59;60;63;60 01:52:30 INFO - PROCESS | 19242 | |10;stanford-crypto-aes;72;72;79;74;73;74;85;73;76;74 01:52:30 INFO - PROCESS | 19242 | |11;stanford-crypto-ccm;117;122;119;118;132;127;125;132;149;122 01:52:30 INFO - PROCESS | 19242 | |12;stanford-crypto-pbkdf2;153;151;154;154;152;149;151;156;156;153 01:52:30 INFO - PROCESS | 19242 | |13;stanford-crypto-sha256-iterative;62;62;62;61;62;61;61;61;62;64 01:52:30 INFO - PROCESS | 19242 | __end_tp_report 01:52:30 INFO - PROCESS | 19242 | __start_cc_report 01:52:30 INFO - PROCESS | 19242 | _x_x_mozilla_cycle_collect,850 01:52:30 INFO - PROCESS | 19242 | __end_cc_report 01:52:30 INFO - PROCESS | 19242 | __startTimestamp1474361550651__endTimestamp 01:52:30 INFO - PROCESS | 19242 | 01:52:30 INFO - PROCESS | 19242 | ------- Summary: start ------- 01:52:30 INFO - PROCESS | 19242 | Number of tests: 14 01:52:30 INFO - PROCESS | 19242 | 01:52:30 INFO - PROCESS | 19242 | [#0] ai-astar Cycles:10 Average:111.20 Median:113.00 stddev:4.10 (3.6%) stddev-sans-first:2.68 01:52:30 INFO - PROCESS | 19242 | Values: 102.0 115.0 106.0 113.0 113.0 113.0 111.0 112.0 112.0 115.0 01:52:30 INFO - PROCESS | 19242 | 01:52:30 INFO - PROCESS | 19242 | [#1] audio-beat-detection Cycles:10 Average:140.00 Median:145.50 stddev:21.47 (14.8%) stddev-sans-first:22.57 01:52:30 INFO - PROCESS | 19242 | Values: 148.0 119.0 138.0 174.0 117.0 144.0 147.0 120.0 173.0 120.0 01:52:30 INFO - PROCESS | 19242 | 01:52:30 INFO - PROCESS | 19242 | [#2] audio-dft Cycles:10 Average:163.00 Median:163.00 stddev:1.41 (0.9%) stddev-sans-first:1.50 01:52:30 INFO - PROCESS | 19242 | Values: 163.0 163.0 164.0 162.0 162.0 162.0 163.0 166.0 161.0 164.0 01:52:30 INFO - PROCESS | 19242 | 01:52:30 INFO - PROCESS | 19242 | [#3] audio-fft Cycles:10 Average:95.10 Median:76.00 stddev:41.19 (54.2%) stddev-sans-first:43.11 01:52:30 INFO - PROCESS | 19242 | Values: 76.0 75.0 76.0 180.0 76.0 75.0 75.0 76.0 166.0 76.0 01:52:30 INFO - PROCESS | 19242 | 01:52:30 INFO - PROCESS | 19242 | [#4] audio-oscillator Cycles:10 Average:102.20 Median:84.00 stddev:28.49 (33.9%) stddev-sans-first:29.53 01:52:30 INFO - PROCESS | 19242 | Values: 85.0 89.0 140.0 84.0 84.0 84.0 147.0 82.0 84.0 143.0 01:52:30 INFO - PROCESS | 19242 | 01:52:30 INFO - PROCESS | 19242 | [#5] imaging-gaussian-blur Cycles:10 Average:117.20 Median:117.00 stddev:0.79 (0.7%) stddev-sans-first:0.78 01:52:30 INFO - PROCESS | 19242 | Values: 118.0 117.0 117.0 117.0 117.0 117.0 117.0 116.0 117.0 119.0 01:52:30 INFO - PROCESS | 19242 | 01:52:30 INFO - PROCESS | 19242 | [#6] imaging-darkroom Cycles:10 Average:147.50 Median:148.00 stddev:0.85 (0.6%) stddev-sans-first:0.88 01:52:30 INFO - PROCESS | 19242 | Values: 148.0 148.0 148.0 147.0 149.0 147.0 147.0 147.0 146.0 148.0 01:52:30 INFO - PROCESS | 19242 | 01:52:30 INFO - PROCESS | 19242 | [#7] imaging-desaturate Cycles:10 Average:96.00 Median:95.50 stddev:1.56 (1.6%) stddev-sans-first:1.66 01:52:30 INFO - PROCESS | 19242 | Values: 96.0 95.0 95.0 96.0 95.0 100.0 95.0 96.0 95.0 97.0 01:52:30 INFO - PROCESS | 19242 | 01:52:30 INFO - PROCESS | 19242 | [#8] json-parse-financial Cycles:10 Average:53.20 Median:53.00 stddev:0.92 (1.7%) stddev-sans-first:0.93 01:52:30 INFO - PROCESS | 19242 | Values: 54.0 53.0 53.0 52.0 53.0 53.0 54.0 53.0 52.0 55.0 01:52:30 INFO - PROCESS | 19242 | 01:52:30 INFO - PROCESS | 19242 | [#9] json-stringify-tinderbox Cycles:10 Average:60.20 Median:60.00 stddev:1.55 (2.6%) stddev-sans-first:1.27 01:52:30 INFO - PROCESS | 19242 | Values: 63.0 60.0 59.0 60.0 59.0 59.0 59.0 60.0 63.0 60.0 01:52:30 INFO - PROCESS | 19242 | 01:52:30 INFO - PROCESS | 19242 | [#10] stanford-crypto-aes Cycles:10 Average:75.20 Median:74.00 stddev:4.02 (5.4%) stddev-sans-first:4.10 01:52:30 INFO - PROCESS | 19242 | Values: 72.0 72.0 79.0 74.0 73.0 74.0 85.0 73.0 76.0 74.0 01:52:30 INFO - PROCESS | 19242 | 01:52:30 INFO - PROCESS | 19242 | [#11] stanford-crypto-ccm Cycles:10 Average:126.30 Median:126.00 stddev:9.59 (7.6%) stddev-sans-first:9.57 01:52:30 INFO - PROCESS | 19242 | Values: 117.0 122.0 119.0 118.0 132.0 127.0 125.0 132.0 149.0 122.0 01:52:30 INFO - PROCESS | 19242 | 01:52:30 INFO - PROCESS | 19242 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.90 Median:153.50 stddev:2.23 (1.5%) stddev-sans-first:2.37 01:52:30 INFO - PROCESS | 19242 | Values: 153.0 151.0 154.0 154.0 152.0 149.0 151.0 156.0 156.0 153.0 01:52:30 INFO - PROCESS | 19242 | 01:52:30 INFO - PROCESS | 19242 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.80 Median:62.00 stddev:0.92 (1.5%) stddev-sans-first:0.97 01:52:30 INFO - PROCESS | 19242 | Values: 62.0 62.0 62.0 61.0 62.0 61.0 61.0 61.0 62.0 64.0 01:52:30 INFO - PROCESS | 19242 | -------- Summary: end -------- 01:52:30 INFO - PROCESS | 19242 | 01:52:31 INFO - TEST-INFO | 19242: exit 0 01:52:31 INFO - TEST-OK | kraken | took 60740ms 01:52:31 INFO - SUITE-END | took 1171s 01:52:31 INFO - Completed test suite (00:19:31) 01:52:31 INFO - javascript benchmark 01:52:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7897.702539825568, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3260.739140868731, 3292.407366673998, 3299.5215693724417, 3302.950455743163, 3322.4019676458242, 5737.254156959594, 5792.565557583182, 5805.187273781668, 5807.1579620954935, 5828.970855145729, 5813.563982701304, 5815.38938411467, 5834.5915785895, 5835.299764028312, 5840.386759390267, 5178.896422071556, 5268.47315268472, 5300.602454815885, 5300.787968481256, 5304.92042619361, 5162.638615296931, 5171.896562068757, 5211.817586384477, 5276.12943864262, 5344.545713614348, 5214.270002199695, 5268.578513718893, 5278.0, 5293.04725149473, 5293.550048245885, 5325.973370133163, 5352.090144675414, 5352.705601191933, 5358.169483730008, 5375.220593013991, 5078.695278283278, 5130.435652078306, 5164.845054648366, 5246.921296180561, 5260.368755749335, 5649.265595472603, 5670.943290567083, 5681.829545113653, 5697.344805347397, 5738.598298119134, 6080.847978800522, 6088.695565221766, 6135.78524751636, 6142.508599312045, 6155.384461553854, 6048.092786082079, 6117.296510901214, 6121.666376154749, 6191.752329906798, 6215.067739839015, 5745.166950792153, 5826.388229235954, 5843.1235314703135, 5861.88276234473, 5898.941010589882, 6371.108044873721, 6387.457066149336, 6393.360663933569, 6395.936040639534, 6410.038494225811, 5867.442592953748, 5940.495057920125, 6093.4515893568905, 6099.817005489843, 6111.938880611226, 6115.449609535163, 6153.230846144232, 6183.3816618337805, 6224.06639004153, 6226.159468471698, 6123.326434092335, 6125.2649682037545, 6163.167972323679, 6165.691715414211, 6168.8766224675255, 5996.790112346047, 6119.167450728499, 6192.535559832941, 6194.38056194386, 6198.101275315144, 4740.051989602093, 4774.140654682191, 4790.7365094920115, 4807.567318941242, 4824.035192961422, 4240.257954857949, 4249.617534421917, 4255.5957184067465, 4275.123599662062, 4303.806328715215, 5691.9715401423555, 5893.823185304448, 5991.790287340009, 6030.306514750772, 6041.5468839837185, 6217.7823776168525, 6319.842003949846, 6338.936610633927, 6363.204599425072, 6366.586171898812, 6007.36922623127, 6021.969890150521, 6026.186464827279, 6028.909566356509, 6033.276006879202, 5156.458571849902, 5342.8664283392445, 5464.562834973192, 5467.289252397242, 5469.124940009579, 6380.680965951684, 6490.740370385131, 6491.805245842632, 6493.707783149675, 6495.058216558573, 6205.968970155121, 6325.4939604832425, 6334.429901308904, 6337.0, 6341.682915854097, 6147.600405973598, 6268.843278918063, 6467.0, 6496.317886621837, 6505.772297969737, 6036.849078773066, 6130.795626845537, 6144.863200307957, 6150.600210986271, 6156.78451254204, 6265.0, 6266.2480502338185, 6266.373362663697, 6271.78048768291, 6278.121063051085, 5930.940690593211, 6057.454829065406, 6100.389961004042, 6103.298120715996, 6120.54095942815, 6026.668533230714, 6116.944513560587, 6122.081687746873, 6131.804871721949, 6136.733532366248, 6073.240844894388, 6097.176881120992, 6136.662483563268, 6140.109684095871, 6146.78486252979, 5978.760849565969, 6050.7882224123705, 6099.176611157437, 6185.350538193425, 6223.3465486125115, 5939.851503712442, 6006.249218847644, 6017.097435384552, 6041.335453100243, 6061.9393806063135], "value": 5728.415512383249, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [573.4380307298848, 607.3440684061214, 630.9589876658017, 633.0757094641823, 678.3420082519954, 41537.58462415375, 47316.76341618291, 47320.526794732, 47353.28970065452, 47381.289280660814, 47161.0, 47474.525254747445, 47772.52227477724, 47902.0, 47937.28094078592, 9153.862692059607, 9223.769405764842, 9253.6298548058, 9264.44413335202, 9281.396709213914, 9252.953735231315, 9259.814803703923, 9338.859917101216, 9340.813183736322, 9352.438853668802, 9307.255419566452, 9324.8601270981, 9369.625214991393, 9379.0, 9396.953015234914, 9321.161095501404, 9325.114114159178, 9334.953325233364, 9371.016043315421, 9394.953025234898, 9175.862362064574, 9263.490508022056, 9265.397749146285, 9277.35058545902, 9312.44125352481, 44192.0, 44680.77659611681, 44960.3255951161, 44976.77511612421, 44992.0, 3636.672699457035, 3941.625545573195, 4202.873913782591, 4609.539046095397, 4619.722816631013, 35642.465363019575, 35897.0, 35993.82003089994, 36236.818815906016, 36330.0, 4671.205894997858, 4709.9764501177615, 4723.527647235283, 4739.549742774431, 4764.166270902613, 419191.0, 420747.0, 420834.0, 422867.0, 424096.0, 68577.0, 68739.0, 68816.65591672009, 68931.3106868935, 69288.3071169292, 40472.0, 71313.6434317825, 71412.2858771416, 71436.64281678663, 71563.64218178876, 18539.258429662932, 18882.0, 18972.051397430074, 19028.71456928147, 19058.33295834667, 15175.468858589897, 15345.46290879814, 15347.079175249297, 15379.231038448033, 15433.074015559103, 21384.251551195634, 21603.35189944304, 21688.24091156802, 21758.803265820214, 21822.0, 7595.962020189863, 7653.349465295512, 7692.42306826979, 7738.380929525624, 7799.766007019798, 1059.9099076578577, 1113.0094216147636, 1160.5937921727327, 1176.7822952753768, 1194.2834299420451, 34455.0, 34680.826595866856, 34691.653083468846, 34847.30305393878, 35176.82411587926, 1221.1879100398314, 1278.386374540226, 1282.7434513097419, 1298.0524217321408, 1300.9349532523336, 17675.11624418774, 18080.18639161241, 18726.812731872506, 18778.53053673669, 18851.622967540847, 469291.0, 658927.0, 659584.0, 669233.0, 671965.0, 67346.66326668335, 68547.31452685509, 68587.0, 68837.3116268841, 69022.65488672524, 1486.1231873194868, 1504.465914600323, 1504.857038581333, 1517.9013364131297, 1518.3395223077775, 35162.824185878904, 35379.292414152085, 35552.0, 35937.0, 36324.0, 58915.41084589099, 59528.0, 59540.70229648824, 59655.105173423304, 59689.403105968384, 582170.0, 585606.0, 586845.0, 587702.0, 588035.0, 31775.36449271048, 32490.675093248767, 32626.184345390604, 33051.17372065718, 34159.658403416644, 55018.72490637521, 55038.724806375714, 55092.72453637706, 55322.44677553334, 55781.44218557762, 45058.32412513749, 45643.54356456393, 45905.0, 46190.538094619966, 47462.050758985315, 46999.18997758821, 47514.76242618765, 47770.28344574904, 48024.0, 48040.557414715106, 45774.77112614549, 46245.0, 46719.76640116778, 47369.76315118402, 48886.75556622194, 39163.804180978914, 39947.0, 39994.20011599694, 41219.7939010315, 41254.1749165009, 30898.0, 31466.21334466565, 31712.682873171896, 32094.518582221754, 32122.678773212905], "value": 22532.406643616992, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [392.980350982451, 393.97636141831475, 401.0855250029931, 410.96095870892265, 414.7117753161552, 19449.124789384477, 21337.893310533444, 21365.145394184216, 21732.3480295591, 22246.443838904037, 20221.797782022175, 20613.38159855203, 20665.896670516664, 20866.791332086676, 21060.0, 6008.909866352008, 6163.075538669213, 6177.104319873616, 6195.690215489208, 6204.81385558434, 5785.479306862381, 6002.489788367994, 6101.816945491621, 6127.325994140663, 6129.172561704179, 6112.205413296265, 6117.66352850593, 6125.571210015279, 6137.877242455148, 6250.4999600032115, 6614.768483103092, 6660.733570657168, 6829.760958366433, 6988.091548098765, 6989.370956613879, 6002.339742628351, 6048.153258543807, 6116.571839971204, 6140.109684095781, 6157.568970172089, 14404.495842645458, 14582.197979111144, 14795.334209960578, 14926.70146597073, 15102.622434439227, 2441.072392490842, 2471.616899380599, 2648.8543130127837, 2658.2025392382207, 3092.9638571078717, 10603.734906627318, 10696.144308455383, 10748.73128171794, 10776.084032857216, 10852.186086043499, 2009.7990200979932, 2040.9897950510301, 2082.135913595856, 2145.967810482844, 2152.3865698275918, 88941.55529222377, 89370.0, 90026.5498672509, 90329.54835225781, 91174.0, 9863.901360986441, 10215.0, 10254.43600601959, 11187.440627968568, 11436.656900293005, 16060.0, 16064.518064458085, 16072.196390180678, 16073.517794466185, 16085.758713619305, 8436.198561136682, 8627.611757470804, 8733.126687331216, 8882.955585222033, 8897.866532002025, 8445.831083378422, 8544.0, 8606.354523410644, 8785.34109941757, 8840.071792461698, 4677.64917631179, 4703.435587729495, 4732.384789977281, 4879.536444037811, 4971.1797553404, 2093.445237012193, 2106.252280440452, 2140.539783946459, 2153.4616345913523, 2166.9349919502442, 1023.1967905194387, 1042.2756184451885, 1096.4298564746446, 1105.4528008635616, 1131.179894576442, 20381.2866549673, 20790.0, 20830.791692082887, 20949.47626309324, 21051.8947405262, 1049.7113293844254, 1088.678839742278, 1095.3865835132192, 1133.8752737198902, 1135.2280449294394, 11042.171837112088, 11501.42492875353, 11545.249558778652, 11560.710982225344, 11566.65300041, 533047.0, 549999.0, 553215.0, 556407.0, 556570.0, 61408.692956534935, 63301.36698632954, 91440.0, 96542.0, 97819.51090244646, 1053.8419237114495, 1056.5456853552892, 1082.783443311341, 1085.5223701571442, 1097.1935627313917, 18471.90764046171, 18679.533011674815, 18783.34258300953, 19025.0, 19467.70798438053, 44697.32954005757, 44721.329180062974, 44776.77611611921, 45175.32237016513, 45829.08341833211, 62470.68764656148, 63867.0, 67341.3265867335, 68273.65863170652, 68693.65653171903, 8940.86588701183, 9032.0, 9071.138241867013, 9143.725688229364, 9271.397359171633, 9680.41917484925, 12567.245965242115, 12878.034147438791, 13021.934890325489, 13047.34763261814, 11480.94259528697, 11643.0, 11795.0, 11816.527338906692, 11926.761464770829, 11321.830172547583, 11405.429728513374, 11465.0, 11498.367589782307, 11724.0, 11417.486213120595, 11566.94216528912, 11688.88311116878, 11741.823872641746, 11966.940165299118, 8977.730668079968, 9032.683856065007, 9041.276697864023, 9091.181793638605, 9147.313951453802, 9720.659776907774, 11352.375619341015, 11418.371989540656, 11614.187006909435, 11756.58851940178], "value": 9610.254573137569, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1575.8109026916768, 1606.903585784853, 1791.3103455169755, 1812.2841477616334, 1867.9539457903584, 8351.707690230844, 8853.867191992125, 9055.95472022639, 9637.132658060782, 9658.468784216866, 9295.767605809859, 9400.90599094009, 9405.95297023514, 9423.15191632753, 9438.056194380559, 2415.45652228249, 2445.3764290105996, 2461.569225385559, 2462.938426539333, 2471.480988992314, 2407.830666799167, 2424.248482970276, 2438.2928950604305, 2442.1086303499183, 2444.9755502444928, 2478.987605061981, 2479.3553676044266, 2483.1557270528015, 2509.924702258926, 2516.421223118684, 2408.6025805742033, 2412.7225369082516, 2414.9275521734376, 2471.9134830280855, 2472.5920223163157, 2431.294924471919, 2439.731629520751, 2444.69441319835, 2459.114718701266, 2460.0282888259217, 9654.420734755937, 9699.175570076552, 9728.17310528606, 9744.220462362993, 9748.610055597768, 5587.469190426903, 5609.971950140264, 5636.915446268309, 5649.096144616883, 5655.802046928337, 3601.243738814852, 3654.5979942206336, 3686.1337585667347, 3724.0689827543115, 3842.846286148551, 1211.7758214730216, 1219.9024078073733, 1260.6281147061638, 1283.871612838718, 1306.6537367597593, 13524.0, 13597.388117534734, 13666.11170273929, 13687.11033782801, 13704.3833027514, 4718.245080787057, 4825.324454576362, 4833.05755377698, 4846.105433512108, 4861.416630004422, 11575.942120289345, 11597.6520704379, 11601.419929003685, 11605.88394116065, 11614.593489227836, 5599.1041433370465, 5624.3813180550105, 5637.267155269788, 5642.858928526737, 5647.1529270609735, 4652.302154676826, 5109.56568691658, 5140.871478213, 5228.189630607262, 5285.867853303698, 4286.078493123931, 4345.500267469219, 4372.344148377705, 4392.363107349416, 4411.117776444724, 1370.0683535195963, 1378.9241591712353, 1384.4531410092957, 1385.1827421820972, 1395.5673741140279, 1084.1760262200785, 1084.322298563398, 1087.9292845964987, 1103.051375816797, 1111.8109921313235, 6340.936590634127, 6393.296737358887, 6461.741530338828, 6540.0, 6584.407403333723, 481.55456203012074, 482.78998635593626, 497.7834641930772, 504.16308927180705, 504.32924210799547, 4456.242438785414, 4580.97709511457, 4617.284320930261, 4660.930086048644, 4707.693999889996, 11127.721806954729, 11362.829557556643, 11498.42507874603, 11545.538178472934, 11571.479283432265, 11378.203525753283, 11476.483558239732, 11478.426078696031, 11488.54045838157, 11512.884871151182, 8572.185642363966, 9079.727608171765, 9090.136437038604, 9090.909090909006, 9106.954465227764, 6627.635480048643, 6659.800205993828, 6799.456043516606, 6807.3873351398615, 6968.895466567904, 10930.289531180448, 11137.331760094421, 11208.943955280172, 11356.94321528387, 11419.885801141882, 11419.429028548706, 11530.82703759461, 11542.82685759731, 11583.478743456397, 11716.23844450108, 8354.45696029756, 8369.790755230924, 8467.915320846712, 8662.13378662154, 8688.13118688126, 9607.471589062388, 9689.612415503583, 9712.223022158352, 9719.319647624881, 9721.125098741148, 8477.0, 8495.0, 8642.827143457222, 8706.56467176626, 8738.344624153087, 8476.830463390821, 8525.104863989192, 8529.658813647633, 8648.394612377073, 8665.566721663765, 7518.097828260644, 7605.809854753676, 7633.0, 7689.42329325314, 7691.846163076819, 5727.770889164387, 5791.449812267828, 5841.153032810304, 5887.61730487517, 5983.461488466059, 5787.884242315215, 5840.269966254218, 5873.060310350324, 5883.35283118848, 5906.852328691817], "value": 4969.076649981043, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [335.75657648205055, 339.0641828553194, 343.0258067089467, 344.87239721303115, 346.9132716820795, 5728.877761619917, 5846.064629659255, 5859.033259512181, 5868.677222752742, 5883.88232235355, 5742.54059675226, 5750.0, 5757.136429535572, 5773.191753154561, 5793.449622285887, 4271.658267338606, 4360.629346505535, 4423.535528769481, 4471.256210078072, 4471.66462515311, 4443.15580039793, 4453.3987911632, 4459.375687403766, 4459.531749166339, 4465.4641443026885, 4388.868333949987, 4401.449818772653, 4404.515503294635, 4413.293872980324, 4413.7351758894565, 4272.9359059614135, 4403.273459879116, 4444.577765112309, 4447.666425018137, 4455.1757924784015, 3904.4728961590104, 3909.960900390988, 3912.2762288976633, 3920.627540383659, 3928.135810121769, 5544.86137846553, 5567.3040869891265, 5578.187089196028, 5578.721063946827, 5590.720463976785, 4923.606111511071, 5087.745612719349, 5105.2086926526445, 5139.306193663845, 5142.485751424828, 5998.97000514999, 5999.340072592011, 6003.549733769941, 6030.984507746127, 6064.363241859585, 4332.718373305725, 4334.349847522865, 4339.913201735948, 4340.631046361063, 4356.738595684269, 6093.421124993163, 6255.468285195764, 6274.654893980829, 6288.528360372945, 6328.936710632881, 4340.8697739067875, 4344.653157521178, 4354.867734389081, 4355.649748577887, 4356.1287742451, 4415.757133357632, 4419.381286619876, 4419.558044195554, 4434.2240107981615, 4434.756088415168, 4918.729469879191, 5083.669561478492, 5103.744812759347, 5114.948850511521, 5118.38579370478, 6093.69531523422, 6099.390060993954, 6100.206973093469, 6115.877682446326, 6126.78556250538, 1517.6661134550382, 1530.594392485992, 1541.714782765192, 1543.5600853756625, 1548.3961255110517, 943.3302355327794, 968.6803354892868, 978.3445091788519, 1003.6136087606324, 1021.6781713760141, 5763.394843541451, 5822.359540450547, 5830.271216097988, 5859.736311865976, 5882.3529411764675, 6243.531735119885, 6254.812355629248, 6255.186825712628, 6264.123022776815, 6277.340879207617, 5600.27196464457, 5882.529397648178, 5906.143609176645, 6001.729922153513, 6002.519798416116, 3357.8824741133944, 3376.7973921564294, 3378.290558982592, 3378.5607870977105, 3399.847006884696, 6242.531810114168, 6444.77443289492, 6462.159919210472, 6506.804795856132, 6516.165468021426, 6349.206349206349, 6378.681065946684, 6406.199225096863, 6424.453921416639, 6433.876820649175, 6272.435480806658, 6341.524385671001, 6421.715656868645, 6433.011532849569, 6458.773942911976, 6277.108192920418, 6332.398422149889, 6336.683165841598, 6345.0, 6355.553112051801, 6220.813375598739, 6228.22147231596, 6230.314665386713, 6249.437550620466, 6273.15312432834, 6198.690065496798, 6432.2602900667025, 6469.644169570719, 6485.740570377132, 6491.318411566906, 4352.325389564622, 4362.189691278084, 4393.340998850198, 4397.670174736972, 4402.603765661106, 6058.636481811105, 6276.152719383008, 6285.214348206474, 6299.0, 6317.431431171217, 6093.6039157454625, 6097.140372186525, 6102.694865256808, 6109.434037704086, 6116.5106791457465, 6120.17377654011, 6142.477889379453, 6142.78500252489, 6156.322804491592, 6190.50475961913, 5833.562482813721, 5968.314258583568, 6132.650816820293, 6186.1648677427975, 6194.225721784777, 6151.50787936955, 6190.288116866618, 6200.3179650237535, 6200.441960223601, 6213.37866213375, 5921.230240068763, 6048.727807248596, 6061.393860613904, 6061.878762424815, 6072.848178795565], "value": 4613.377117324612, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [829.9128591497893, 843.5065486690286, 872.1191596487549, 913.2511340700628, 955.3793301454356, 16437.42469013585, 17723.29106835728, 17830.554236144104, 17836.197371118298, 17863.553411164743, 16571.66856662866, 16593.66812663746, 16936.915315423437, 16946.576335591617, 17424.56438589036, 870.3776799588293, 891.9554022298892, 893.4014210478995, 896.4307664632939, 898.8427025270585, 894.4186278918689, 907.2787134228304, 911.2300106410073, 911.2709832134299, 915.2997956563235, 951.7097285327973, 951.9762005949838, 953.7710949372135, 954.2985905359556, 954.7899462118323, 945.5792172483214, 952.5046975572733, 953.580424613168, 954.9665761698342, 958.5063692198523, 697.7801992372437, 698.598305974067, 698.7833771530842, 699.8495323505471, 701.2006312803409, 17711.114444277733, 17854.0, 17874.374396896175, 17893.7315940261, 17954.371596994042, 15888.841111588852, 16089.0, 16509.422170223985, 16732.498025059147, 16861.662766744717, 13034.869651303461, 13241.0, 13250.006249531323, 13499.86500134996, 13630.727385452235, 4163.271427500205, 4184.937225941613, 4187.078842654641, 4218.240716670998, 4332.891677708082, 59558.40441595615, 61519.384806151815, 62650.0602490963, 62763.372366276664, 91580.54209728909, 12813.167144135601, 12885.098043137077, 13064.934675326562, 13194.736105278032, 13322.267275299952, 23138.0, 23223.883880580488, 23224.4193895154, 23237.186698465473, 23349.766502334758, 9411.294152938557, 9548.092931171528, 9607.231421486264, 9626.855597166048, 9703.07820757027, 5103.18349064148, 5113.207452844815, 5127.282180494733, 5165.380154381498, 5247.134222853263, 3954.9011274717786, 4098.180363927227, 4099.7745124017865, 4100.507939047274, 4432.911341773147, 753.408574269196, 766.9501482403626, 770.8535378278109, 779.7757520692459, 791.1771757372384, 766.6703317573497, 768.1780494870546, 797.1589972578869, 829.6266679994027, 840.14305408483, 10416.947915260374, 10734.355938643706, 11176.0, 11461.426928653535, 11633.418329083512, 711.0791524975169, 761.1969372312153, 769.3460558525321, 813.507827764206, 841.8821365008904, 4755.762211889391, 5143.974280128576, 5178.741062946838, 5254.97372513135, 5275.683458992473, 134102.0, 136686.0, 137046.0, 138907.30546347203, 140209.2989535046, 119499.0, 120485.0, 121816.0, 123050.0, 124954.0, 103335.4833225829, 104065.47967260116, 105051.0, 105384.0, 107904.46047769711, 10201.84697229557, 10376.584936602754, 10437.0, 10452.163826893679, 10591.20565957541, 58752.0, 59271.703641481516, 59337.0, 60818.0, 63168.0, 76025.61987190029, 78702.0, 87100.56449717964, 90589.54705226432, 90707.0, 13691.315434228049, 15025.774613381027, 15120.243987800346, 15169.544913652608, 15785.68428631444, 16272.0, 17090.470157517353, 17095.316187352368, 17147.0, 17786.46640600784, 13734.313284335944, 14487.92756036213, 15410.768838467657, 15790.289436975134, 16015.279312431201, 15515.22423878779, 15604.0, 16024.543166485675, 16062.0, 16503.33986640521, 12293.754124917272, 12528.874711253135, 12579.622611321674, 15293.082415055133, 15638.609034774221, 12796.808047879103, 13257.138286011379, 13422.597322080353, 13625.116841700068, 13901.51344702971, 11863.881361186623, 11896.76206475883, 12343.814842777187, 12396.380180991095, 12874.549390771279], "value": 8532.773804862132, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1501.8000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 115.0, 106.0, 113.0, 113.0, 113.0, 111.0, 112.0, 112.0, 115.0], "value": 111.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 119.0, 138.0, 174.0, 117.0, 144.0, 147.0, 120.0, 173.0, 120.0], "value": 140.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 164.0, 162.0, 162.0, 162.0, 163.0, 166.0, 161.0, 164.0], "value": 163.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 76.0, 180.0, 76.0, 75.0, 75.0, 76.0, 166.0, 76.0], "value": 95.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 89.0, 140.0, 84.0, 84.0, 84.0, 147.0, 82.0, 84.0, 143.0], "value": 102.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 119.0], "value": 117.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 148.0, 147.0, 149.0, 147.0, 147.0, 147.0, 146.0, 148.0], "value": 147.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 95.0, 96.0, 95.0, 100.0, 95.0, 96.0, 95.0, 97.0], "value": 96.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 53.0, 52.0, 53.0, 53.0, 54.0, 53.0, 52.0, 55.0], "value": 53.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 60.0, 59.0, 60.0, 59.0, 59.0, 59.0, 60.0, 63.0, 60.0], "value": 60.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 72.0, 79.0, 74.0, 73.0, 74.0, 85.0, 73.0, 76.0, 74.0], "value": 75.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 122.0, 119.0, 118.0, 132.0, 127.0, 125.0, 132.0, 149.0, 122.0], "value": 126.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 151.0, 154.0, 154.0, 152.0, 149.0, 151.0, 156.0, 156.0, 153.0], "value": 152.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 62.0, 61.0, 62.0, 61.0, 61.0, 61.0, 62.0, 64.0], "value": 61.8, "unit": "score"}]}]} 01:52:31 INFO - Return code: 0 01:52:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 01:52:31 ERROR - # TBPL SUCCESS # 01:52:31 INFO - Running post-action listener: _resource_record_post_action 01:52:31 INFO - [mozharness: 2016-09-20 08:52:31.935249Z] Finished run-tests step (success) 01:52:31 INFO - Running post-run listener: _resource_record_post_run 01:52:32 INFO - Total resource usage - Wall time: 1185s; CPU: 13.0%; Read bytes: 8192; Write bytes: 482570240; Read time: 16; Write time: 451316 01:52:32 INFO - TinderboxPrint: CPU usage
13.2% 01:52:32 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 16 01:52:32 INFO - TinderboxPrint: I/O write bytes / time
482,570,240 / 451,316 01:52:32 INFO - TinderboxPrint: CPU idle
8,208.6 (86.8%) 01:52:32 INFO - TinderboxPrint: CPU user
1,192.1 (12.6%) 01:52:32 INFO - TinderboxPrint: Swap in / out
0 / 0 01:52:32 INFO - install - Wall time: 14s; CPU: 15.0%; Read bytes: 0; Write bytes: 255737856; Read time: 0; Write time: 324432 01:52:32 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 8192; Write bytes: 226832384; Read time: 16; Write time: 126884 01:52:32 INFO - Running post-run listener: _upload_blobber_files 01:52:32 INFO - Blob upload gear active. 01:52:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 01:52:32 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:52:32 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:52:32 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:52:32 INFO - (blobuploader) - INFO - Open directory for files ... 01:52:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 01:52:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:52:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:52:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 01:52:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:52:33 INFO - (blobuploader) - INFO - Done attempting. 01:52:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 01:52:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:52:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:52:38 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 01:52:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:52:38 INFO - (blobuploader) - INFO - Done attempting. 01:52:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 01:52:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:52:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:52:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 01:52:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:52:38 INFO - (blobuploader) - INFO - Done attempting. 01:52:38 INFO - (blobuploader) - INFO - Iteration through files over. 01:52:38 INFO - Return code: 0 01:52:38 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 01:52:38 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 01:52:38 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0a6fa65cf0615ea916d2dcb4e5c3d1ce9ce3c112b73d865f78213409d31da3850459979e16f5aee536d4dacae52157d2b9f968f20e9318438b328ca7e4d133e4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7640c4af1dea49907310b18d353dbb88d75d1fbcfc959dd27757d9c8db5447400bdcd99432b0b84e21bd86a4721b13f1d39188274a31ee8a38b8efaf27133fc9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0639645137c460740ac951093c5b7fb74a958a0e00eda0faf0e6365a4dd7c1fa195e74259e69dd6fd5667926ed1bd5f981461a06e6b9bbb56eb832d8e7e1b340"} 01:52:38 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 01:52:38 INFO - Writing to file /builds/slave/test/properties/blobber_files 01:52:38 INFO - Contents: 01:52:38 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0a6fa65cf0615ea916d2dcb4e5c3d1ce9ce3c112b73d865f78213409d31da3850459979e16f5aee536d4dacae52157d2b9f968f20e9318438b328ca7e4d133e4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7640c4af1dea49907310b18d353dbb88d75d1fbcfc959dd27757d9c8db5447400bdcd99432b0b84e21bd86a4721b13f1d39188274a31ee8a38b8efaf27133fc9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0639645137c460740ac951093c5b7fb74a958a0e00eda0faf0e6365a4dd7c1fa195e74259e69dd6fd5667926ed1bd5f981461a06e6b9bbb56eb832d8e7e1b340"} 01:52:38 INFO - Running post-run listener: copy_logs_to_upload_dir 01:52:38 INFO - Copying logs to upload dir... 01:52:38 INFO - mkdir: /builds/slave/test/build/upload/logs 01:52:38 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1219.704582 ========= master_lag: 0.58 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 20 secs) (at 2016-09-20 01:52:39.283757) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-20 01:52:39.291131) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1474360337.28753-1459457385 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/0a6fa65cf0615ea916d2dcb4e5c3d1ce9ce3c112b73d865f78213409d31da3850459979e16f5aee536d4dacae52157d2b9f968f20e9318438b328ca7e4d133e4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7640c4af1dea49907310b18d353dbb88d75d1fbcfc959dd27757d9c8db5447400bdcd99432b0b84e21bd86a4721b13f1d39188274a31ee8a38b8efaf27133fc9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0639645137c460740ac951093c5b7fb74a958a0e00eda0faf0e6365a4dd7c1fa195e74259e69dd6fd5667926ed1bd5f981461a06e6b9bbb56eb832d8e7e1b340"} 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.011225 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/0a6fa65cf0615ea916d2dcb4e5c3d1ce9ce3c112b73d865f78213409d31da3850459979e16f5aee536d4dacae52157d2b9f968f20e9318438b328ca7e4d133e4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7640c4af1dea49907310b18d353dbb88d75d1fbcfc959dd27757d9c8db5447400bdcd99432b0b84e21bd86a4721b13f1d39188274a31ee8a38b8efaf27133fc9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0639645137c460740ac951093c5b7fb74a958a0e00eda0faf0e6365a4dd7c1fa195e74259e69dd6fd5667926ed1bd5f981461a06e6b9bbb56eb832d8e7e1b340"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-20 01:52:39.320399) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 01:52:39.320778) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1474360337.28753-1459457385 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004597 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 01:52:39.375200) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-20 01:52:39.375813) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-20 01:52:39.376350) ========= ========= Total master_lag: 0.71 =========