builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-005 starttime: 1468754003.66 results: success (0) buildid: 20160717013004 builduid: 518866dec16b4b6e9b9afbc0bfc2eb0a revision: 1d657d984bed365b1c2bdc499532cf34c60b1edd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-17 04:13:23.662081) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-17 04:13:23.663059) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-17 04:13:23.663721) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-17 04:13:23.679888) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 04:13:23.680555) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1468754004.100960-1862850072 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-17 04:13:24-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 45.7M=0s 2016-07-17 04:13:24 (45.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.103269 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 04:13:23.802209) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 04:13:23.803162) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1468754004.100960-1862850072 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019682 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 04:13:23.856110) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-17 04:13:23.856819) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 1d657d984bed365b1c2bdc499532cf34c60b1edd --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 1d657d984bed365b1c2bdc499532cf34c60b1edd --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=7fc86f70bae03e018da0b1d8000001c4-1468754004.100960-1862850072 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-17 04:13:25,058 truncating revision to first 12 chars 2016-07-17 04:13:25,058 Setting DEBUG logging. 2016-07-17 04:13:25,058 attempt 1/10 2016-07-17 04:13:25,058 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/1d657d984bed?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-17 04:13:26,461 unpacking tar archive at: mozilla-inbound-1d657d984bed/testing/mozharness/ program finished with exit code 0 elapsedTime=1.741921 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-17 04:13:25.616866) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-17 04:13:25.617646) ========= script_repo_revision: 1d657d984bed365b1c2bdc499532cf34c60b1edd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-17 04:13:25.618300) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-17 04:13:25.618594) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-17 04:13:25.628946) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2016-07-17 04:13:25.629242) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] 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=7fc86f70bae03e018da0b1d8000001c4-1468754004.100960-1862850072 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:13:26 INFO - MultiFileLogger online at 20160717 04:13:26 in /builds/slave/test 04:13:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 04:13:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:13:26 INFO - {'append_to_log': False, 04:13:26 INFO - 'base_work_dir': '/builds/slave/test', 04:13:26 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 04:13:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:13:26 INFO - 'branch': 'Mozilla-Inbound', 04:13:26 INFO - 'buildbot_json_path': 'buildprops.json', 04:13:26 INFO - 'config_files': ('talos/linux_config.py',), 04:13:26 INFO - 'default_actions': ('clobber', 04:13:26 INFO - 'read-buildbot-config', 04:13:26 INFO - 'download-and-extract', 04:13:26 INFO - 'populate-webroot', 04:13:26 INFO - 'create-virtualenv', 04:13:26 INFO - 'install', 04:13:26 INFO - 'run-tests'), 04:13:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:13:26 INFO - 'download_minidump_stackwalk': True, 04:13:26 INFO - 'download_symbols': 'ondemand', 04:13:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:13:26 INFO - 'tooltool.py': '/tools/tooltool.py', 04:13:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:13:26 INFO - '/tools/misc-python/virtualenv.py')}, 04:13:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:13:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:13:26 INFO - 'installer_path': 'installer.exe', 04:13:26 INFO - 'log_level': 'info', 04:13:26 INFO - 'log_name': 'talos', 04:13:26 INFO - 'log_to_console': True, 04:13:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:13:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:13:26 INFO - 'opt_config_files': (), 04:13:26 INFO - 'pip_index': False, 04:13:26 INFO - 'sps_profile': False, 04:13:26 INFO - 'sps_profile_interval': 0, 04:13:26 INFO - 'suite': 'dromaeojs', 04:13:26 INFO - 'system_bits': '32', 04:13:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:13:26 INFO - 'title': 'talos-linux64-ix-005', 04:13:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:13:26 INFO - 'use_talos_json': True, 04:13:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:13:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:13:26 INFO - 'work_dir': 'build'} 04:13:26 INFO - ##### 04:13:26 INFO - ##### Running clobber step. 04:13:26 INFO - ##### 04:13:26 INFO - Running pre-action listener: _resource_record_pre_action 04:13:26 INFO - Running main action method: clobber 04:13:26 INFO - rmtree: /builds/slave/test/build 04:13:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:13:27 INFO - Running post-action listener: _resource_record_post_action 04:13:27 INFO - ##### 04:13:27 INFO - ##### Finished clobber step (success) 04:13:27 INFO - ##### 04:13:27 INFO - ##### 04:13:27 INFO - ##### Running read-buildbot-config step. 04:13:27 INFO - ##### 04:13:27 INFO - Running pre-action listener: _resource_record_pre_action 04:13:27 INFO - Running main action method: read_buildbot_config 04:13:27 INFO - Using buildbot properties: 04:13:27 INFO - { 04:13:27 INFO - "project": "", 04:13:27 INFO - "product": "firefox", 04:13:27 INFO - "script_repo_revision": "production", 04:13:27 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 04:13:27 INFO - "repository": "", 04:13:27 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 04:13:27 INFO - "buildid": "20160717013004", 04:13:27 INFO - "pgo_build": "True", 04:13:27 INFO - "basedir": "/builds/slave/test", 04:13:27 INFO - "buildnumber": 1354, 04:13:27 INFO - "slavename": "talos-linux64-ix-005", 04:13:27 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 04:13:27 INFO - "platform": "ubuntu64_hw", 04:13:27 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 04:13:27 INFO - "branch": "mozilla-inbound", 04:13:27 INFO - "repo_path": "integration/mozilla-inbound", 04:13:27 INFO - "revision": "1d657d984bed365b1c2bdc499532cf34c60b1edd", 04:13:27 INFO - "stage_platform": "linux64-pgo", 04:13:27 INFO - "builduid": "518866dec16b4b6e9b9afbc0bfc2eb0a", 04:13:27 INFO - "slavebuilddir": "test" 04:13:27 INFO - } 04:13:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/EA_HwJUJQGyHCCBifwJd-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 04:13:27 INFO - Running post-action listener: _resource_record_post_action 04:13:27 INFO - ##### 04:13:27 INFO - ##### Finished read-buildbot-config step (success) 04:13:27 INFO - ##### 04:13:27 INFO - ##### 04:13:27 INFO - ##### Running download-and-extract step. 04:13:27 INFO - ##### 04:13:27 INFO - Running pre-action listener: _resource_record_pre_action 04:13:27 INFO - Running main action method: download_and_extract 04:13:27 INFO - mkdir: /builds/slave/test/build/tests 04:13: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')]} 04:13:27 INFO - https://queue.taskcluster.net/v1/task/EA_HwJUJQGyHCCBifwJd-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 04:13:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EA_HwJUJQGyHCCBifwJd-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 04:13:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EA_HwJUJQGyHCCBifwJd-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 04:13:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EA_HwJUJQGyHCCBifwJd-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 04:13:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EA_HwJUJQGyHCCBifwJd-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 04:13:28 INFO - Downloaded 1361 bytes. 04:13:28 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 04:13:28 INFO - Using the following test package requirements: 04:13:28 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 04:13:28 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:13:28 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 04:13:28 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:13:28 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 04:13:28 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:13:28 INFO - u'jsshell-linux-x86_64.zip'], 04:13:28 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:13:28 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 04:13:28 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 04:13:28 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:13:28 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 04:13:28 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:13:28 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 04:13:28 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:13:28 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 04:13:28 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:13:28 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 04:13:28 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 04:13:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:28 INFO - https://queue.taskcluster.net/v1/task/EA_HwJUJQGyHCCBifwJd-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:13:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EA_HwJUJQGyHCCBifwJd-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 04:13:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EA_HwJUJQGyHCCBifwJd-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 04:13:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EA_HwJUJQGyHCCBifwJd-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 04:13:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EA_HwJUJQGyHCCBifwJd-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:13:29 INFO - Downloaded 24333965 bytes. 04:13:29 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:13:29 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 04:13:30 INFO - Return code: 0 04:13:30 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 04:13:30 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')]} 04:13:30 INFO - https://queue.taskcluster.net/v1/task/EA_HwJUJQGyHCCBifwJd-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:13:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EA_HwJUJQGyHCCBifwJd-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 04:13:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EA_HwJUJQGyHCCBifwJd-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 04:13:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EA_HwJUJQGyHCCBifwJd-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 04:13:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EA_HwJUJQGyHCCBifwJd-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:13:31 INFO - Downloaded 24333965 bytes. 04:13:31 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:13:31 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 04:13:33 INFO - Return code: 0 04:13:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:33 INFO - https://queue.taskcluster.net/v1/task/EA_HwJUJQGyHCCBifwJd-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 04:13:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EA_HwJUJQGyHCCBifwJd-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 04:13:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EA_HwJUJQGyHCCBifwJd-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 04:13:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EA_HwJUJQGyHCCBifwJd-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 04:13:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EA_HwJUJQGyHCCBifwJd-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 04:13:34 INFO - Downloaded 14403317 bytes. 04:13:34 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:13:34 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 04:13:34 INFO - Return code: 0 04:13: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')]} 04:13:34 INFO - https://queue.taskcluster.net/v1/task/EA_HwJUJQGyHCCBifwJd-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 04:13:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EA_HwJUJQGyHCCBifwJd-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 04:13:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EA_HwJUJQGyHCCBifwJd-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 04:13:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EA_HwJUJQGyHCCBifwJd-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 04:13:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EA_HwJUJQGyHCCBifwJd-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 04:13:35 INFO - Downloaded 60817956 bytes. 04:13:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EA_HwJUJQGyHCCBifwJd-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 04:13:35 INFO - mkdir: /builds/slave/test/properties 04:13:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:13:35 INFO - Writing to file /builds/slave/test/properties/build_url 04:13:35 INFO - Contents: 04:13:35 INFO - build_url:https://queue.taskcluster.net/v1/task/EA_HwJUJQGyHCCBifwJd-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 04:13:36 INFO - Running post-action listener: _resource_record_post_action 04:13:36 INFO - Running post-action listener: set_extra_try_arguments 04:13:36 INFO - ##### 04:13:36 INFO - ##### Finished download-and-extract step (success) 04:13:36 INFO - ##### 04:13:36 INFO - ##### 04:13:36 INFO - ##### Running populate-webroot step. 04:13:36 INFO - ##### 04:13:36 INFO - Running pre-action listener: _resource_record_pre_action 04:13:36 INFO - Running main action method: populate_webroot 04:13:36 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:13:36 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:13:36 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:13:36 INFO - '--tppagecycles', 04:13:36 INFO - '7'], 04:13:36 INFO - 'tests': ['tsvgm']}}, 04:13:36 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:13:36 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:13:36 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:13:36 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:13:36 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:13:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:13:36 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:13:36 INFO - 'talos_options': ['--disable-e10s'], 04:13:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:13:36 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:13:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:13:36 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:13:36 INFO - 'talos_options': ['--disable-e10s'], 04:13:36 INFO - 'tests': ['damp', 'tps']}, 04:13:36 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:13:36 INFO - 'tests': ['damp', 'tps']}, 04:13:36 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:13:36 INFO - 'tests': ['dromaeo_dom']}, 04:13:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:13:36 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:13:36 INFO - 'tests': ['basic_compositor_video']}, 04:13:36 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:13:36 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:13:36 INFO - 'tests': ['a11yr', 04:13:36 INFO - 'ts_paint', 04:13:36 INFO - 'tpaint', 04:13:36 INFO - 'sessionrestore', 04:13:36 INFO - 'sessionrestore_no_auto_restore', 04:13:36 INFO - 'tabpaint']}, 04:13:36 INFO - 'other-e10s': {'tests': ['a11yr', 04:13:36 INFO - 'ts_paint', 04:13:36 INFO - 'tpaint', 04:13:36 INFO - 'sessionrestore', 04:13:36 INFO - 'sessionrestore_no_auto_restore', 04:13:36 INFO - 'tabpaint']}, 04:13:36 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:13:36 INFO - 'ts_paint', 04:13:36 INFO - 'tpaint', 04:13:36 INFO - 'sessionrestore', 04:13:36 INFO - 'sessionrestore_no_auto_restore', 04:13:36 INFO - 'tabpaint']}, 04:13:36 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:13:36 INFO - 'ts_paint', 04:13:36 INFO - 'tpaint', 04:13:36 INFO - 'sessionrestore', 04:13:36 INFO - 'sessionrestore_no_auto_restore', 04:13:36 INFO - 'tabpaint']}, 04:13:36 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:13:36 INFO - 'tests': ['a11yr', 04:13:36 INFO - 'ts_paint', 04:13:36 INFO - 'tpaint', 04:13:36 INFO - 'sessionrestore', 04:13:36 INFO - 'sessionrestore_no_auto_restore', 04:13:36 INFO - 'tabpaint']}, 04:13:36 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:13:36 INFO - 'tests': ['a11yr', 04:13:36 INFO - 'ts_paint', 04:13:36 INFO - 'tpaint', 04:13:36 INFO - 'sessionrestore', 04:13:36 INFO - 'sessionrestore_no_auto_restore', 04:13:36 INFO - 'tabpaint']}, 04:13:36 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:13:36 INFO - 'tests': ['tsvgx', 04:13:36 INFO - 'tsvgr_opacity', 04:13:36 INFO - 'tart', 04:13:36 INFO - 'tscrollx', 04:13:36 INFO - 'cart']}, 04:13:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:13:36 INFO - 'tsvgr_opacity', 04:13:36 INFO - 'tart', 04:13:36 INFO - 'tscrollx', 04:13:36 INFO - 'cart']}, 04:13:36 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:13:36 INFO - 'talos_options': ['--disable-e10s'], 04:13:36 INFO - 'tests': ['tp5o']}, 04:13:36 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:13:36 INFO - 'tests': ['tp5o']}, 04:13:36 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:13:36 INFO - 'talos_options': ['--disable-e10s', 04:13:36 INFO - '--xperf_path', 04:13:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:13:36 INFO - 'tests': ['tp5n']}, 04:13:36 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:13:36 INFO - 'talos_options': ['--xperf_path', 04:13:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:13:36 INFO - 'tests': ['tp5n']}}, 04:13:36 INFO - 'talos.zip': {'path': '', 04:13:36 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:13:36 INFO - Running post-action listener: _resource_record_post_action 04:13:36 INFO - ##### 04:13:36 INFO - ##### Finished populate-webroot step (success) 04:13:36 INFO - ##### 04:13:36 INFO - ##### 04:13:36 INFO - ##### Running create-virtualenv step. 04:13:36 INFO - ##### 04:13:36 INFO - Running pre-action listener: _resource_record_pre_action 04:13:36 INFO - Running main action method: create_virtualenv 04:13:36 INFO - Creating virtualenv /builds/slave/test/build/venv 04:13:36 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 04:13:36 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:13:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:13:36 INFO - Using real prefix '/usr' 04:13:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:13:36 INFO - Installing distribute.............................................................................................................................................................................................done. 04:13:38 INFO - Installing pip.................done. 04:13:38 INFO - Return code: 0 04:13:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:13: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')]} 04:13:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13: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')]} 04:13:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:38 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 0x234e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x260f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x260d3b0>, '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 0x260e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x260e740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1468754004.100960-1862850072', '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 04:13:38 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 04:13:38 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 04:13:38 INFO - Using env: {'DISPLAY': ':0', 04:13:38 INFO - 'HOME': '/home/cltbld', 04:13:38 INFO - 'LANG': 'en_US.UTF-8', 04:13:38 INFO - 'LANGUAGE': 'en_US:en', 04:13:38 INFO - 'LOGNAME': 'cltbld', 04:13:38 INFO - 'MAIL': '/var/mail/cltbld', 04:13:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:38 INFO - 'MOZ_NO_REMOTE': '1', 04:13:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:38 INFO - 'NO_EM_RESTART': '1', 04:13:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:38 INFO - 'PWD': '/builds/slave/test', 04:13:38 INFO - 'SHELL': '/bin/bash', 04:13:38 INFO - 'SHLVL': '1', 04:13:38 INFO - 'TERM': 'linux', 04:13:38 INFO - 'TMOUT': '86400', 04:13:38 INFO - 'USER': 'cltbld', 04:13:38 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1468754004.100960-1862850072', 04:13:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:38 INFO - '_': '/tools/buildbot/bin/python'} 04:13:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:39 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 04:13:39 INFO - Cleaning up... 04:13:39 INFO - Return code: 0 04:13:39 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:13:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x234e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x260f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x260d3b0>, '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 0x260e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x260e740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1468754004.100960-1862850072', '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 04:13:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:13:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:13:39 INFO - Using env: {'DISPLAY': ':0', 04:13:39 INFO - 'HOME': '/home/cltbld', 04:13:39 INFO - 'LANG': 'en_US.UTF-8', 04:13:39 INFO - 'LANGUAGE': 'en_US:en', 04:13:39 INFO - 'LOGNAME': 'cltbld', 04:13:39 INFO - 'MAIL': '/var/mail/cltbld', 04:13:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:39 INFO - 'MOZ_NO_REMOTE': '1', 04:13:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:39 INFO - 'NO_EM_RESTART': '1', 04:13:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:39 INFO - 'PWD': '/builds/slave/test', 04:13:39 INFO - 'SHELL': '/bin/bash', 04:13:39 INFO - 'SHLVL': '1', 04:13:39 INFO - 'TERM': 'linux', 04:13:39 INFO - 'TMOUT': '86400', 04:13:39 INFO - 'USER': 'cltbld', 04:13:39 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1468754004.100960-1862850072', 04:13:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:39 INFO - '_': '/tools/buildbot/bin/python'} 04:13:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:39 INFO - Downloading/unpacking psutil>=3.1.1 04:13:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:13:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:13:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:13:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:13:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:13:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:13:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:13:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:13:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:13:41 INFO - Installing collected packages: psutil 04:13:41 INFO - Running setup.py install for psutil 04:13:41 INFO - building 'psutil._psutil_linux' extension 04:13:41 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 04:13:41 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 04:13:41 INFO - building 'psutil._psutil_posix' extension 04:13:41 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 04:13:41 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 04:13:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:13:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:13:41 INFO - Successfully installed psutil 04:13:41 INFO - Cleaning up... 04:13:41 INFO - Return code: 0 04:13:41 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:13: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')]} 04:13:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13: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')]} 04:13:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:41 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 0x234e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x260f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x260d3b0>, '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 0x260e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x260e740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1468754004.100960-1862850072', '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 04:13:41 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 04:13:41 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 04:13:41 INFO - Using env: {'DISPLAY': ':0', 04:13:41 INFO - 'HOME': '/home/cltbld', 04:13:41 INFO - 'LANG': 'en_US.UTF-8', 04:13:41 INFO - 'LANGUAGE': 'en_US:en', 04:13:41 INFO - 'LOGNAME': 'cltbld', 04:13:41 INFO - 'MAIL': '/var/mail/cltbld', 04:13:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:41 INFO - 'MOZ_NO_REMOTE': '1', 04:13:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:41 INFO - 'NO_EM_RESTART': '1', 04:13:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:41 INFO - 'PWD': '/builds/slave/test', 04:13:41 INFO - 'SHELL': '/bin/bash', 04:13:41 INFO - 'SHLVL': '1', 04:13:41 INFO - 'TERM': 'linux', 04:13:41 INFO - 'TMOUT': '86400', 04:13:41 INFO - 'USER': 'cltbld', 04:13:41 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1468754004.100960-1862850072', 04:13:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:41 INFO - '_': '/tools/buildbot/bin/python'} 04:13:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:42 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:13:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:13:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:13:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:13:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:13:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:13:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:13:44 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:13:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:13:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:13:44 INFO - Installing collected packages: mozsystemmonitor 04:13:44 INFO - Running setup.py install for mozsystemmonitor 04:13:44 INFO - Successfully installed mozsystemmonitor 04:13:44 INFO - Cleaning up... 04:13:44 INFO - Return code: 0 04:13:44 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:13:44 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')]} 04:13:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:44 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')]} 04:13:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:44 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 0x234e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x260f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x260d3b0>, '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 0x260e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x260e740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1468754004.100960-1862850072', '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 04:13:44 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 04:13:44 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 04:13:44 INFO - Using env: {'DISPLAY': ':0', 04:13:44 INFO - 'HOME': '/home/cltbld', 04:13:44 INFO - 'LANG': 'en_US.UTF-8', 04:13:44 INFO - 'LANGUAGE': 'en_US:en', 04:13:44 INFO - 'LOGNAME': 'cltbld', 04:13:44 INFO - 'MAIL': '/var/mail/cltbld', 04:13:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:44 INFO - 'MOZ_NO_REMOTE': '1', 04:13:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:44 INFO - 'NO_EM_RESTART': '1', 04:13:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:44 INFO - 'PWD': '/builds/slave/test', 04:13:44 INFO - 'SHELL': '/bin/bash', 04:13:44 INFO - 'SHLVL': '1', 04:13:44 INFO - 'TERM': 'linux', 04:13:44 INFO - 'TMOUT': '86400', 04:13:44 INFO - 'USER': 'cltbld', 04:13:44 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1468754004.100960-1862850072', 04:13:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:44 INFO - '_': '/tools/buildbot/bin/python'} 04:13:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:44 INFO - Downloading/unpacking jsonschema==2.5.1 04:13:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:13:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:13:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:13:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:13:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:13:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:13:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:13:46 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:13:46 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 04:13:46 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 04:13:46 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 04:13:46 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 04:13:46 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 04:13:46 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 04:13:46 INFO - Downloading functools32-3.2.3-2.tar.gz 04:13:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:13:46 INFO - warning: no files found matching '*.txt' 04:13:46 INFO - no previously-included directories found matching 'build' 04:13:46 INFO - no previously-included directories found matching 'dist' 04:13:46 INFO - no previously-included directories found matching '.git*' 04:13:46 INFO - Installing collected packages: jsonschema, functools32 04:13:46 INFO - Running setup.py install for functools32 04:13:46 INFO - warning: no files found matching '*.txt' 04:13:46 INFO - no previously-included directories found matching 'build' 04:13:46 INFO - no previously-included directories found matching 'dist' 04:13:46 INFO - no previously-included directories found matching '.git*' 04:13:46 INFO - Successfully installed jsonschema functools32 04:13:46 INFO - Cleaning up... 04:13:46 INFO - Return code: 0 04:13:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:13:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:46 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 0x234e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x260f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x260d3b0>, '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 0x260e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x260e740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1468754004.100960-1862850072', '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 04:13:46 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 04:13:46 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 04:13:46 INFO - Using env: {'DISPLAY': ':0', 04:13:46 INFO - 'HOME': '/home/cltbld', 04:13:46 INFO - 'LANG': 'en_US.UTF-8', 04:13:46 INFO - 'LANGUAGE': 'en_US:en', 04:13:46 INFO - 'LOGNAME': 'cltbld', 04:13:46 INFO - 'MAIL': '/var/mail/cltbld', 04:13:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:46 INFO - 'MOZ_NO_REMOTE': '1', 04:13:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:46 INFO - 'NO_EM_RESTART': '1', 04:13:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:46 INFO - 'PWD': '/builds/slave/test', 04:13:46 INFO - 'SHELL': '/bin/bash', 04:13:46 INFO - 'SHLVL': '1', 04:13:46 INFO - 'TERM': 'linux', 04:13:46 INFO - 'TMOUT': '86400', 04:13:46 INFO - 'USER': 'cltbld', 04:13:46 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1468754004.100960-1862850072', 04:13:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:46 INFO - '_': '/tools/buildbot/bin/python'} 04:13:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:47 INFO - Downloading/unpacking blobuploader==1.2.4 04:13:47 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 04:13:47 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 04:13:47 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 04:13:47 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 04:13:47 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 04:13:47 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 04:13:48 INFO - Downloading blobuploader-1.2.4.tar.gz 04:13:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:13:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:13:48 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 04:13:48 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 04:13:48 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 04:13:48 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 04:13:48 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 04:13:48 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 04:13:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:13:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:13:49 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 04:13:49 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 04:13:49 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 04:13:49 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 04:13:49 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 04:13:49 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 04:13:49 INFO - Downloading docopt-0.6.1.tar.gz 04:13:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:13:49 INFO - Installing collected packages: blobuploader, requests, docopt 04:13:49 INFO - Running setup.py install for blobuploader 04:13:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:13:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:13:49 INFO - Running setup.py install for requests 04:13:50 INFO - Running setup.py install for docopt 04:13:50 INFO - Successfully installed blobuploader requests docopt 04:13:50 INFO - Cleaning up... 04:13:50 INFO - Return code: 0 04:13:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:13:50 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')]} 04:13:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:50 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')]} 04:13:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:50 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 0x234e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x260f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x260d3b0>, '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 0x260e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x260e740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1468754004.100960-1862850072', '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 04:13:50 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 04:13:50 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 04:13:50 INFO - Using env: {'DISPLAY': ':0', 04:13:50 INFO - 'HOME': '/home/cltbld', 04:13:50 INFO - 'LANG': 'en_US.UTF-8', 04:13:50 INFO - 'LANGUAGE': 'en_US:en', 04:13:50 INFO - 'LOGNAME': 'cltbld', 04:13:50 INFO - 'MAIL': '/var/mail/cltbld', 04:13:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:50 INFO - 'MOZ_NO_REMOTE': '1', 04:13:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:50 INFO - 'NO_EM_RESTART': '1', 04:13:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:50 INFO - 'PWD': '/builds/slave/test', 04:13:50 INFO - 'SHELL': '/bin/bash', 04:13:50 INFO - 'SHLVL': '1', 04:13:50 INFO - 'TERM': 'linux', 04:13:50 INFO - 'TMOUT': '86400', 04:13:50 INFO - 'USER': 'cltbld', 04:13:50 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1468754004.100960-1862850072', 04:13:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:50 INFO - '_': '/tools/buildbot/bin/python'} 04:13:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:13:50 INFO - Running setup.py (path:/tmp/pip-03FVDp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:13:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:13:50 INFO - Running setup.py (path:/tmp/pip-WWTjNn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:13:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:13:50 INFO - Running setup.py (path:/tmp/pip-btKVnq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:13:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:13:50 INFO - Running setup.py (path:/tmp/pip-CajDBY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:13:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:13:50 INFO - Running setup.py (path:/tmp/pip-DN58MA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:13:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:13:50 INFO - Running setup.py (path:/tmp/pip-bcCm1t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:13:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:13:50 INFO - Running setup.py (path:/tmp/pip-zA1QXR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:13:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:13:51 INFO - Running setup.py (path:/tmp/pip-wT5w2J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:13:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:13:51 INFO - Running setup.py (path:/tmp/pip-IpfBK9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:13:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:13:51 INFO - Running setup.py (path:/tmp/pip-Ssk5Ml-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:13:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:13:51 INFO - Running setup.py (path:/tmp/pip-v47KzO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:13:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:13:51 INFO - Running setup.py (path:/tmp/pip-SoxSdU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:13:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:13:51 INFO - Running setup.py (path:/tmp/pip-nWnogf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:13:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:13:51 INFO - Running setup.py (path:/tmp/pip-e0gs_V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:13:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:13:51 INFO - Running setup.py (path:/tmp/pip-dyy_RP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:13:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:13:51 INFO - Running setup.py (path:/tmp/pip-KUFn5n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:13:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:13:51 INFO - Running setup.py (path:/tmp/pip-4tgruV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:13:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:13:51 INFO - Running setup.py install for manifestparser 04:13:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:13:51 INFO - Running setup.py install for mozcrash 04:13:51 INFO - Running setup.py install for mozdebug 04:13:52 INFO - Running setup.py install for mozdevice 04:13:52 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:13:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:13:52 INFO - Running setup.py install for mozfile 04:13:52 INFO - Running setup.py install for mozhttpd 04:13:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:13:52 INFO - Running setup.py install for mozinfo 04:13:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:13:52 INFO - Running setup.py install for mozInstall 04:13:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:13:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:13:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:13:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:13:52 INFO - Running setup.py install for mozleak 04:13:52 INFO - Running setup.py install for mozlog 04:13:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:13:52 INFO - Running setup.py install for moznetwork 04:13:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:13:52 INFO - Running setup.py install for mozprocess 04:13:52 INFO - Running setup.py install for mozprofile 04:13:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:13:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:13:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:13:53 INFO - Running setup.py install for mozrunner 04:13:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:13:53 INFO - Running setup.py install for mozscreenshot 04:13:53 INFO - Running setup.py install for moztest 04:13:53 INFO - Running setup.py install for mozversion 04:13:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:13:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:13:53 INFO - Cleaning up... 04:13:53 INFO - Return code: 0 04:13:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:13:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:53 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 0x234e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x260f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x260d3b0>, '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 0x260e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x260e740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1468754004.100960-1862850072', '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 04:13:53 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 04:13:53 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 04:13:53 INFO - Using env: {'DISPLAY': ':0', 04:13:53 INFO - 'HOME': '/home/cltbld', 04:13:53 INFO - 'LANG': 'en_US.UTF-8', 04:13:53 INFO - 'LANGUAGE': 'en_US:en', 04:13:53 INFO - 'LOGNAME': 'cltbld', 04:13:53 INFO - 'MAIL': '/var/mail/cltbld', 04:13:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:53 INFO - 'MOZ_NO_REMOTE': '1', 04:13:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:53 INFO - 'NO_EM_RESTART': '1', 04:13:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:53 INFO - 'PWD': '/builds/slave/test', 04:13:53 INFO - 'SHELL': '/bin/bash', 04:13:53 INFO - 'SHLVL': '1', 04:13:53 INFO - 'TERM': 'linux', 04:13:53 INFO - 'TMOUT': '86400', 04:13:53 INFO - 'USER': 'cltbld', 04:13:53 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1468754004.100960-1862850072', 04:13:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:53 INFO - '_': '/tools/buildbot/bin/python'} 04:13:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:13:53 INFO - Running setup.py (path:/tmp/pip-XcxYM6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:13:53 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)) 04:13:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:13:53 INFO - Running setup.py (path:/tmp/pip-DxboGx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:13:53 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)) 04:13:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:13:53 INFO - Running setup.py (path:/tmp/pip-5HgH2u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:13:53 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)) 04:13:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:13:53 INFO - Running setup.py (path:/tmp/pip-V0p87B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:13:54 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)) 04:13:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:13:54 INFO - Running setup.py (path:/tmp/pip-GlGKkk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:13:54 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)) 04:13:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:13:54 INFO - Running setup.py (path:/tmp/pip-fBUR9U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:13:54 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)) 04:13:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:13:54 INFO - Running setup.py (path:/tmp/pip-6Z4CSq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:13:54 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)) 04:13:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:13:54 INFO - Running setup.py (path:/tmp/pip-GmEKCY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:13:54 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)) 04:13:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:13:54 INFO - Running setup.py (path:/tmp/pip-YEmk7X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:13:54 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)) 04:13:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:13:54 INFO - Running setup.py (path:/tmp/pip-YTri6i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:13:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 04:13:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:13:54 INFO - Running setup.py (path:/tmp/pip-BlEPpu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:13:54 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)) 04:13:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:13:54 INFO - Running setup.py (path:/tmp/pip-zzSKvt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:13:54 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)) 04:13:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:13:54 INFO - Running setup.py (path:/tmp/pip-_rFHBQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:13:54 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)) 04:13:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:13:54 INFO - Running setup.py (path:/tmp/pip-Q18yMe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:13:54 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)) 04:13:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:13:54 INFO - Running setup.py (path:/tmp/pip-Zl3fIS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:13:54 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)) 04:13:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:13:54 INFO - Running setup.py (path:/tmp/pip-BjQbpy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:13:54 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)) 04:13:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:13:54 INFO - Running setup.py (path:/tmp/pip-Z0zJpb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:13:55 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)) 04:13:55 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)) 04:13:55 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)) 04:13:55 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)) 04:13:55 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)) 04:13:55 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)) 04:13:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:13:55 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 04:13:55 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 04:13:55 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 04:13:55 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 04:13:55 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 04:13:55 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 04:13:56 INFO - Downloading blessings-1.6.tar.gz 04:13:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:13:57 INFO - Installing collected packages: blessings 04:13:57 INFO - Running setup.py install for blessings 04:13:57 INFO - Successfully installed blessings 04:13:57 INFO - Cleaning up... 04:13:57 INFO - Return code: 0 04:13:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:13:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:13:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:13:57 INFO - Reading from file tmpfile_stdout 04:13:57 INFO - Current package versions: 04:13:57 INFO - argparse == 1.2.1 04:13:57 INFO - blessings == 1.6 04:13:57 INFO - blobuploader == 1.2.4 04:13:57 INFO - docopt == 0.6.1 04:13:57 INFO - functools32 == 3.2.3-2 04:13:57 INFO - jsonschema == 2.5.1 04:13:57 INFO - manifestparser == 1.1 04:13:57 INFO - mozInstall == 1.12 04:13:57 INFO - mozcrash == 0.17 04:13:57 INFO - mozdebug == 0.1 04:13:57 INFO - mozdevice == 0.48 04:13:57 INFO - mozfile == 1.2 04:13:57 INFO - mozhttpd == 0.7 04:13:57 INFO - mozinfo == 0.9 04:13:57 INFO - mozleak == 0.1 04:13:57 INFO - mozlog == 3.2 04:13:57 INFO - moznetwork == 0.27 04:13:57 INFO - mozprocess == 0.23 04:13:57 INFO - mozprofile == 0.28 04:13:57 INFO - mozrunner == 6.12 04:13:57 INFO - mozscreenshot == 0.1 04:13:57 INFO - mozsystemmonitor == 0.3 04:13:57 INFO - moztest == 0.7 04:13:57 INFO - mozversion == 1.4 04:13:57 INFO - psutil == 3.1.1 04:13:57 INFO - requests == 1.2.3 04:13:57 INFO - wsgiref == 0.1.2 04:13:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:13:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:57 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 0x234e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x260f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x260d3b0>, '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 0x260e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x260e740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1468754004.100960-1862850072', '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 04:13:57 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 04:13:57 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 04:13:57 INFO - Using env: {'DISPLAY': ':0', 04:13:57 INFO - 'HOME': '/home/cltbld', 04:13:57 INFO - 'LANG': 'en_US.UTF-8', 04:13:57 INFO - 'LANGUAGE': 'en_US:en', 04:13:57 INFO - 'LOGNAME': 'cltbld', 04:13:57 INFO - 'MAIL': '/var/mail/cltbld', 04:13:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:57 INFO - 'MOZ_NO_REMOTE': '1', 04:13:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:57 INFO - 'NO_EM_RESTART': '1', 04:13:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:57 INFO - 'PWD': '/builds/slave/test', 04:13:57 INFO - 'SHELL': '/bin/bash', 04:13:57 INFO - 'SHLVL': '1', 04:13:57 INFO - 'TERM': 'linux', 04:13:57 INFO - 'TMOUT': '86400', 04:13:57 INFO - 'USER': 'cltbld', 04:13:57 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1468754004.100960-1862850072', 04:13:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:57 INFO - '_': '/tools/buildbot/bin/python'} 04:13:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:57 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)) 04:13:57 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)) 04:13:57 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)) 04:13:57 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)) 04:13:57 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)) 04:13:57 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)) 04:13:57 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)) 04:13:57 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)) 04:13:57 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)) 04:13:57 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)) 04:13:57 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)) 04:13:57 INFO - Cleaning up... 04:13:57 INFO - Return code: 0 04:13:57 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:13:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:57 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 0x234e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x260f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x260d3b0>, '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 0x260e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x260e740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1468754004.100960-1862850072', '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 04:13:57 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 04:13:57 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 04:13:57 INFO - Using env: {'DISPLAY': ':0', 04:13:57 INFO - 'HOME': '/home/cltbld', 04:13:57 INFO - 'LANG': 'en_US.UTF-8', 04:13:57 INFO - 'LANGUAGE': 'en_US:en', 04:13:57 INFO - 'LOGNAME': 'cltbld', 04:13:57 INFO - 'MAIL': '/var/mail/cltbld', 04:13:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:57 INFO - 'MOZ_NO_REMOTE': '1', 04:13:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:57 INFO - 'NO_EM_RESTART': '1', 04:13:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:57 INFO - 'PWD': '/builds/slave/test', 04:13:57 INFO - 'SHELL': '/bin/bash', 04:13:57 INFO - 'SHLVL': '1', 04:13:57 INFO - 'TERM': 'linux', 04:13:57 INFO - 'TMOUT': '86400', 04:13:57 INFO - 'USER': 'cltbld', 04:13:57 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1468754004.100960-1862850072', 04:13:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:57 INFO - '_': '/tools/buildbot/bin/python'} 04:13:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:57 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:13:57 INFO - Cleaning up... 04:13:57 INFO - Return code: 0 04:13:57 INFO - Running post-action listener: _resource_record_post_action 04:13:57 INFO - Running post-action listener: _start_resource_monitoring 04:13:57 INFO - Starting resource monitoring. 04:13:57 INFO - ##### 04:13:57 INFO - ##### Finished create-virtualenv step (success) 04:13:57 INFO - ##### 04:13:57 INFO - ##### 04:13:57 INFO - ##### Running install step. 04:13:57 INFO - ##### 04:13:57 INFO - Running pre-action listener: _resource_record_pre_action 04:13:57 INFO - Running main action method: install 04:13:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:13:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:13:58 INFO - Reading from file tmpfile_stdout 04:13:58 INFO - Detecting whether we're running mozinstall >=1.0... 04:13:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:13:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:13:58 INFO - Reading from file tmpfile_stdout 04:13:58 INFO - Output received: 04:13:58 INFO - Usage: mozinstall [options] installer 04:13:58 INFO - Options: 04:13:58 INFO - -h, --help show this help message and exit 04:13:58 INFO - -d DEST, --destination=DEST 04:13:58 INFO - Directory to install application into. [default: 04:13:58 INFO - "/builds/slave/test"] 04:13:58 INFO - --app=APP Application being installed. [default: firefox] 04:13:58 INFO - mkdir: /builds/slave/test/build/application 04:13:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 04:13:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 04:14:15 INFO - Reading from file tmpfile_stdout 04:14:15 INFO - Output received: 04:14:15 INFO - /builds/slave/test/build/application/firefox/firefox 04:14:15 INFO - Running post-action listener: _resource_record_post_action 04:14:15 INFO - ##### 04:14:15 INFO - ##### Finished install step (success) 04:14:15 INFO - ##### 04:14:15 INFO - ##### 04:14:15 INFO - ##### Running run-tests step. 04:14:15 INFO - ##### 04:14:15 INFO - Running pre-action listener: _resource_record_pre_action 04:14:15 INFO - Running main action method: run_tests 04:14:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:14:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:14:15 INFO - Python 2.7.3 04:14:15 INFO - Return code: 0 04:14:15 INFO - grabbing minidump binary from tooltool 04:14:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:14:15 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 0x260d3b0>, '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 0x260e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x260e740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:14:15 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 04:14:15 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 04:14:15 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 04:14:15 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:14:15 INFO - Return code: 0 04:14:15 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:14:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:14:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:14:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:14:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:14:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EA_HwJUJQGyHCCBifwJd-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-005', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 04:14:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/EA_HwJUJQGyHCCBifwJd-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-005 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 04:14:15 INFO - Using env: {'DISPLAY': ':0', 04:14:15 INFO - 'HOME': '/home/cltbld', 04:14:15 INFO - 'LANG': 'en_US.UTF-8', 04:14:15 INFO - 'LANGUAGE': 'en_US:en', 04:14:15 INFO - 'LOGNAME': 'cltbld', 04:14:15 INFO - 'MAIL': '/var/mail/cltbld', 04:14:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:14:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:14:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:14:15 INFO - 'MOZ_NO_REMOTE': '1', 04:14:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:14:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:14:15 INFO - 'NO_EM_RESTART': '1', 04:14:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:14:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:14:15 INFO - 'PWD': '/builds/slave/test', 04:14:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:14:15 INFO - 'SHELL': '/bin/bash', 04:14:15 INFO - 'SHLVL': '1', 04:14:15 INFO - 'TERM': 'linux', 04:14:15 INFO - 'TMOUT': '86400', 04:14:15 INFO - 'USER': 'cltbld', 04:14:15 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1468754004.100960-1862850072', 04:14:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:14:15 INFO - '_': '/tools/buildbot/bin/python'} 04:14:15 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EA_HwJUJQGyHCCBifwJd-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-005', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 04:14:15 INFO - mozversion application_buildid: 20160717013004 04:14:15 INFO - mozversion application_changeset: 1d657d984bed365b1c2bdc499532cf34c60b1edd 04:14:15 INFO - mozversion application_display_name: Nightly 04:14:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:14:15 INFO - mozversion application_name: Firefox 04:14:15 INFO - mozversion application_remotingname: firefox 04:14:15 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 04:14:15 INFO - mozversion application_vendor: Mozilla 04:14:15 INFO - mozversion application_version: 50.0a1 04:14:15 INFO - mozversion platform_buildid: 20160717013004 04:14:15 INFO - mozversion platform_changeset: 1d657d984bed365b1c2bdc499532cf34c60b1edd 04:14:15 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 04:14:15 INFO - mozversion platform_version: 50.0a1 04:14:15 INFO - using testdate: 1468754055 04:14:15 INFO - actual date: 1468754055 04:14:15 INFO - starting webserver on 'localhost:52149' 04:14:15 INFO - SUITE-START | Running 2 tests 04:14:15 INFO - TEST-START | dromaeo_css 04:14:15 INFO - operating with platform_type : linux_ 04:14:15 INFO - Initialising browser for dromaeo_css test... 04:14:15 INFO - TEST-INFO | started process 19186 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmJMnHc/profile http://localhost:52149/getInfo.html) 04:14:18 INFO - PROCESS | 19186 | JavaScript error: , line 0: uncaught exception: undefined 04:14:19 INFO - PROCESS | 19186 | __metrics Screen width/height:1600/1200 04:14:19 INFO - PROCESS | 19186 | colorDepth:24 04:14:19 INFO - PROCESS | 19186 | Browser inner width/height: 1024/697 04:14:19 INFO - PROCESS | 19186 | __metrics 04:14:20 INFO - PROCESS | 19186 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:14:20 INFO - PROCESS | 19186 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:14:21 INFO - TEST-INFO | 19186: exit 0 04:14:21 INFO - Browser initialized. 04:14:21 INFO - Running cycle 1/1 for dromaeo_css test... 04:14:21 INFO - TEST-INFO | started process 19269 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmJMnHc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:14:32 INFO - PROCESS | 19269 | RSS: Main: 158244864 04:14:32 INFO - PROCESS | 19269 | 04:15:24 INFO - PROCESS | 19269 | 1468754124423 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] 04:17:32 INFO - PROCESS | 19269 | Cycle 1(1): loaded http://localhost:52149/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52149/tests/dromaeo/cssquery-ext.html) 04:17:33 INFO - PROCESS | 19269 | RSS: Main: 238989312 04:17:33 INFO - PROCESS | 19269 | 04:20:35 INFO - PROCESS | 19269 | Cycle 1(1): loaded http://localhost:52149/tests/dromaeo/cssquery-ext.html (next: http://localhost:52149/tests/dromaeo/cssquery-jquery.html) 04:20:36 INFO - PROCESS | 19269 | RSS: Main: 245493760 04:20:36 INFO - PROCESS | 19269 | 04:23:38 INFO - PROCESS | 19269 | Cycle 1(1): loaded http://localhost:52149/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52149/tests/dromaeo/cssquery-mootools.html) 04:23:38 INFO - PROCESS | 19269 | RSS: Main: 236847104 04:23:38 INFO - PROCESS | 19269 | 04:26:40 INFO - PROCESS | 19269 | Cycle 1(1): loaded http://localhost:52149/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52149/tests/dromaeo/cssquery-prototype.html) 04:26:41 INFO - PROCESS | 19269 | RSS: Main: 237580288 04:26:41 INFO - PROCESS | 19269 | 04:29:44 INFO - PROCESS | 19269 | Cycle 1(1): loaded http://localhost:52149/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52149/tests/dromaeo/cssquery-yui.html) 04:29:44 INFO - PROCESS | 19269 | RSS: Main: 227573760 04:29:44 INFO - PROCESS | 19269 | 04:32:47 INFO - PROCESS | 19269 | Cycle 1(1): loaded http://localhost:52149/tests/dromaeo/cssquery-yui.html (next: http://localhost:52149/tests/dromaeo/cssquery-dojo.html) 04:32:47 INFO - PROCESS | 19269 | RSS: Main: 293941248 04:32:47 INFO - PROCESS | 19269 | 04:32:47 INFO - PROCESS | 19269 | __start_tp_report 04:32:47 INFO - PROCESS | 19269 | _x_x_mozilla_page_load 04:32:47 INFO - PROCESS | 19269 | _x_x_mozilla_page_load_details 04:32:47 INFO - PROCESS | 19269 | |i|pagename|runs| 04:32:48 INFO - PROCESS | 19269 | |0;dojo.html;3055.9083227503174;3073.9846300768495;3080.5225190095543;3102.8913988010418;3113.2372568720666;5211.983663185681;5243.056249875021;5293.8941221175555;5342.305500284962;5346.11789054806;5328.600354973373;5360.705161216131;5381.19282107684;5381.19282107684;5392.460753924605;4803.351547541081;4863.975680121594;4884.780184891671;4893.657443978923;5035.521625445577;4895.5838753705975;4935.161022626145;4958.107540642683;4972.651914365996;4986.127427700156;4738.407699037621;4795.424549054118;4823.782929768169;4911.729854857981;4924.901501969976;4792.424909010906;4800.135975524404;4801.5438533339275;4823.9517604824205;4902.8774280643265;4778.569928706398;4812.037592481518;4832.081904438145;4843.39457567804;4845.806167753286;5195.558377537914;5293.364796224478;5355.973220133875;5364;5365.114756065244;5795.507381872546;5804.100364443518;5817.272840894888;5825.854353641151;5829.650220986712;5768.884622307531;5811.593188476808;5833.979053665634;5866.648001119904;5867.735951882176;5263.210518422267;5375.16684913839;5413.53984911283;5428.67427954324;5512.228288039678;6003.849903752441;6007.849803754853;6019.939800601938;6021.277446706424;6022.45797878193;5519.69641669704;5764.5676574257095;5772.9711351442975;5777.30672319316;5778.075507918797;5689.630174038757;5741.110127930177;5763.971180144073;5849.561282903799;5854.7072646367515;5760.164776107441;5769.538436925036;5788.305403351625;5839;5839.328477225174;5527.170924361378;5696.458836410535;5771.307443106854;5780.132980053026;5795.8551036224435;4339.284018136972;4339.7830108494445;4359.629431498358;4377;4390.1000294940095;3915.432262322004;3931.8427262909736;3943.152222272225;3945;3967.028078120821;5309.601779866525;5442.891142177214;5471.562275017989;5489.615726899079;5534.944650553523;5675.914861277084;5691.9715401423555;5703.258576385043;5712.65724056558;5722.570807189478;5391.056565101092;5395.406505284415;5399;5400.405955344909;5404.648697834628;4848.321235027099;4965.726885021286;4979.576735977483;4982.676126051795;5018.623603229699;5954.434328738763;5989.910151347821;5993.160957465958;6011.398860113954;6046.969765151146;5825.854353641193;5827.067669172912;5828.825135245949;5829.621074630136;5853.707314634336;5724.398938111438;5792.420757924342;5859.824205273849;5984.940150598438;5991.3409524953095;5613.887722245614;5622.634528755618;5633.577481688972;5676.34722006958;5684.971575142098;5629.127485239795;5691.3170419549915;5726.370099289158;5732.742026608896;5739.081746920473;5500.972495137498;5582.385937546947;5587.972060139673;5607.635503692247;5629.4652008059165;5670.914936276042;5699.772009119589;5709.25779648643;5712.314522257356;5745.396733342939;5610.382857885711;5614.494695477427;5621.241132447068;5623.212750215055;5625.5218306442775;5484.204790305464;5638.097904335286;5662.178984047372;5669.716514174357;5717.113847353667;5577.191307260506;5586.608937374344;5593.860153496033;5595;5595.748191331319 04:32:48 INFO - PROCESS | 19269 | |1;ext.html;598.4733434577571;621.4127649371344;633.9603050996367;645.183842439314;646.9029645553169;30685.386292274143;43417.56582434175;44224.115517689665;44328.556714432845;44351;44820;45492.31761523571;45523.544764552345;45617.77191114048;45643.543564564345;8221.671133154696;8999.28005759541;9146.542672866364;10611.522481488313;10824.945875270612;10746;10854.45727713611;10858.131349492021;10861.891381086167;10881.401522916236;10899.618513352034;10913.563457461692;10937.726556836064;10945.726356841064;10949.671509854717;10853.620123295726;10857.077148442391;10862.72843178919;10912;10913.508892099875;10715.46422678863;10730.946345268301;10762.246642735012;10771.192160587987;10789.676309710721;41506.37740433896;41749.791251043855;41919;41934.161316773796;41948.1610367794;3745;3747.306748251555;4097.180563887235;4279.764612946286;4458.197524445599;31949.361012779613;32023.51964720531;32435.837820810983;32449.582364162343;33437.498437523456;4539.84110556129;4557.088582283556;4641.780014780177;4723.220668589679;4741.241401375798;336081.3195934029;336253;337541.24587541295;339143;339891;58972.41027589669;59590;59590;59633.701831490565;59802.70098649479;62481.68759156175;62971.685141574;62980.68509657423;63000.36999630037;63157.684211579566;15144.924275378773;15414.768778468331;15416;15422.305996230196;15432.84567154314;12905.483780648856;13077.673058173621;13078.149920255153;13120.475180992844;13121.212727236396;6759.290274521203;7904.446688731734;7916.208379162153;8010.279074883289;8016.679332826621;3127.3588914272973;3150.57467241924;3188.537662038991;3195.3769015041844;3195.376901504231;1047.8041971985415;1085.5820509103894;1117.8546788917388;1155.8844115588374;1171.1041053594008;32391.51412728811;32938.17654558655;33027.174320642174;33200.868491294896;33224.33551329008;1118.9048930840806;1123.0622430270816;1213.4296880466168;1216.4951545108859;1227.711487800384;16911.23899424529;17345.47963561095;17395.65208695819;17433.825661743474;17479.65040699179;517356;521520;524234;524313;526263;57347.713261433426;58388.708056460295;58615.70692146512;58655.30757539387;58810;1285.9807102893465;1347.690031292808;1392.7493051250872;1406.8100806391415;1414.5826981040618;31525.684743152276;32473;33060.504092439114;33338.833305833316;34444.48333274953;56152.71923640356;56251;56823.71588142033;56839.431605685066;56886;473205;476019;477401;477417;477685;25133;27284.31789205286;27888.86055569709;27952;29596.556051658812;48832.75583622059;49017.36300325508;49650.50349496603;50044.74977625089;50172.749136255545;41117.79441102895;41809.1638167241;41834.163316732884;42653.786731066146;43029.78485107554;41971;42102.789486052374;42400.57599423966;42876.78561607172;43445.348319774595;41064.38403424011;41824.79087604542;42186;42264.577354227295;42455;34815.82592087024;36401.45397818982;37575.81212093922;37712;37857.62142378651;28670.713292866803;28941.710582893902;28984.275393115342;29175.416491670472;29177.416451671274 04:32:48 INFO - PROCESS | 19269 | |2;jquery.html;359.31909032383624;370.94064949608054;371.2408125383591;377.9470874077628;382.7837271941353;18745;20187.495312617193;20256.594868102628;21117.683234751472;21130.049147788348;19830;19980.00099994998;20053.29813456529;20278.594428111428;20421.591568168664;5378.677279363231;5520.530754885839;5639.2668953036045;5675.552734052811;5723.599348045638;5639.6334238274585;5678.659280443166;5696.943030569703;5720;5722.141678748179;5472.315960504937;5542.972285138569;5546.69493177875;5630.268065151545;5661.46216109469;5568.582356323272;5576.86057848555;5577.05190117681;5586.888262234773;5610.438956104398;4836.540528649773;4889.731064791435;4890.657653964224;4913.901721965576;4914.4839791821705;23391.532169356517;23515.7648423517;23569.528609427885;23933.760662393328;23948.401289967715;2378.500514891875;2446.437319416544;2471.653968444401;2492.3395300245447;3000.0199270669464;14469.131852088909;14586.270686465634;14715.264236788225;14719.55841324762;14868.256587170707;1748.5104170832187;1804.7834259888898;1909.551255454967;1916.32928475034;1973.6743437332966;51065.74467127678;51492.227616585784;52679.47320526784;86217;86657.56671216666;9855.90144098564;9984.154515962915;9997.200223982063;10094.495275236355;10224.057429088598;13336.866631333562;13372.130811497416;13442.126261792951;13460.528881489297;13460.596182114552;8039.196080392031;8082.434229603871;8093.757187284391;8160.3471722262075;8308.252257296872;8310.792230194173;8341.45780524264;8366.74498825181;8499.107593702696;8504.617292221865;6246.718897649616;6313.96843015782;6347.174867267226;6376.649284289316;6397.648129352837;1665.5170000699661;1727.6285598596362;1728.7234042553382;1744.6772347115825;1775.8135395783256;1014.7868947521104;1044.8902865199198;1100.7468282295117;1106.3251416635846;1125.6604640477806;19538;20343.28798492046;20353.898230508752;20367.287144949856;20526.589468210852;973.1484950668165;1012.8075665623508;1034.912032477248;1035.963741269052;1097.7475180708482;10372.80712718032;11616.477258523219;11687.415629218505;11693.123015773852;11718.88281117195;478723;503113;507877;508614;508956;83781;84116;84387;85222.14777852266;86271;924.4822899176482;929.1916033051424;945.5933948402115;945.9716208513755;947.0813311088233;17575.560610984827;17724.91137544304;17861.464156075337;17941.551461213574;18103;40603.1879362417;41290.58709412988;42149;42596.5740342605;43133.56866431421;56878.14682779679;57272;59672;60757.39242607694;61314;7986;8076.919230807852;8279.089300176855;8321.708740194064;8415.200555947175;9056.094390560891;12813.93593032029;13591.932040339734;13861.792073119113;14239.430422783387;6957;11699.824502632637;12058.758824823279;12236.75526489483;12358.62924112278;11843.52625894989;12012.099092568267;12327.445264962918;12493.812592811297;12738.68153296175;12344.259344439362;12629.621111366674;12658.303793291458;12694.492220311084;12805.74388512206;8377.204165604258;8384.496930184208;8487.490750554987;8637.740867773977;8666.480011199348;11664.76670466569;11915.285082895054;12025.759484810429;12129.75740485203;12677.429515672002 04:32:48 INFO - PROCESS | 19269 | |3;mootools.html;1371.3486094105294;1472.0284612155972;1530.5867415797734;1653.8676905847533;1810.900400477973;8623.353248506364;8932.508712020835;8949.105089491055;9152.816943661122;9170.266378689705;8873.112688731124;9093.227075698573;9109.134632209945;9113.589888455019;9118.954405227965;2296.322584837477;2330.5338932213626;2331.25399872041;2332.4518738096617;2332.54515369503;2275.5562665280195;2287.462446325112;2329.6272596384583;2334.393057804966;2334.533093381322;2300.4133945843787;2324.2910912171865;2327.487952650423;2328.2200462844967;2331.4171457135717;2215.435064058655;2220.522587643656;2223.1996481266733;2225.4436390902356;2228.0976204636986;2255.5827171973237;2259.175400978631;2265.0939624150305;2267.138487374787;2271.057511132878;9021.594028268744;9096.545172741337;9099;9101.499417532033;9106.499142547158;5117.334746482933;5297.708626025567;5300.973495132538;5309.840704778863;5310.176922577007;3386.271951530433;3386.8137252451106;3428.7599868009247;3430.9485357719655;3437.724982001434;1118.815395459748;1121.0863146535628;1122.1752012270974;1128.6049882541126;1145.5131569083105;12651.0511711622;12781.233126012376;12789.48842046317;12790.872091279061;12796.61610151697;4231.851885184004;4339.500957389941;4490.595846373842;4510.391097201901;4511.729296242236;9639.855402168972;9647.855282170773;9677.225821934227;9685.564149613143;9696.9030309696;5207.062728708793;5207.297014903015;5207.479252074838;5212.296339994128;5213.895722085537;4231.280682284019;4692.554207350297;4842.031593681278;4857.077155340545;4871.14754917888;3751.737378383487;3814;3848.249591329664;3884.0484081400236;3886.1839013806853;1081.7566047639218;1127.6729748373043;1129.3957732613012;1135.7103938495795;1137.0448822988728;730.5507113125495;891.1355984694835;965.3396158607325;988.3229987458614;1001.384148748529;6040.848978775478;6217.9689101555105;6349.746010159634;6366.331535188739;6446.930873193798;448.50888277336264;451.3973844917007;453.43094416507074;457.22728588685015;622.8912535686485;4105.835766569364;4257.084726783756;4269.530351661315;4277.486701595828;4291.163223171514;10721.142308615292;11007.339559626447;11027.338359698444;11102.555897764;11105.555777768961;10979.341239525495;10979.890201098047;10988.780224395467;10990.615328463466;11000.394978276112;8209.589520524069;8437.57812109392;8443.36674749384;8678.609462574073;8686.261667758185;5848.444397782374;5903.822885313448;6144.600600961102;6286.402791734778;6286.779962701284;10147.18822494184;10562.102221311274;10564.683059508227;10699.465026748787;10721.571137154426;10632.468376580986;10799.622013229498;10804.459777011276;10845.728856778644;10916.836247456136;7913.643886025257;7934.642941067781;8109.189081091844;8125.918740812516;8156.102828688958;8979.371443999118;8988.370814042953;9024.819503610022;9063.954680226556;9066.863997040182;8033.758987230392;8273.669053237803;8276.958615206886;8365.456245344034;8388.496690198608;8015.398845086525;8068.233517815798;8069.112397636372;8271.54506502148;8302.294304984143;7215.098112735908;7224.602646854473;7236.529625574532;7267.164276108315;7341.59621220838;5068.797248110034;5289.413176047247;5342.813001544927;5419.214213939035;5483.506484416422;5317.920231196613;5351.9464805351445;5394.541463975449;5503.11950087984;5520.171974203901 04:32:48 INFO - PROCESS | 19269 | |4;prototype.html;289.00149981812837;294.8559588795473;297.743940211418;311.52181401548626;312.0591416878113;5221.765020574074;5314.441983591725;5393.838184854456;5416.864578385537;5445.264889239957;5175.593349092671;5321.36143662761;5348.893022139555;5355.277037599933;5375.973120134394;4144.963759060235;4156.189543039109;4162;4162.708610397258;4184.644305234044;3885.1841113366227;3943.842246310144;4125.669946404296;4149.481314835645;4150.792460376969;4094.4267802507675;4097;4117.649999750025;4125.608067233623;4128.772917489537;4089.652379547727;4101.815418306169;4111.650509706678;4128.1330920506725;4137.027798467358;3467.9422776053293;3662.450632405134;3666.79992004795;3678.2091850252054;3684.7420680552373;5186.455422180692;5195.974020129913;5212.661177023481;5213.6089793265655;5228.058949389108;4664.416947881515;4704.129735998851;4709;4718.079974404993;4719.716816990957;5405.27028851104;5578.748956296977;5592.133219351007;5596.076647353182;5614.887702245973;3944.2900277949957;3957.406389041638;3967.98016009921;3983.661388781957;4009.3384591542363;5853.7365818538265;5895.437709007074;5903.822885313404;5911.231539899785;5922.378150294243;4019.0909318074264;4087.529934057562;4106.856260030884;4111.7944102794745;4113.547509773923;4161.0845613964875;4166.104287578125;4173.10278290169;4179.665626749853;4180.414741936132;4747.406574178228;4759.333693282943;4785.449673287547;4803.495632958489;4803.975980120077;5488.353493951799;5598.832035038955;5649.774009039675;5652.74562644682;5712.628969047399;1334.8531661517225;1385.411200239894;1416.7379034878375;1425.9305520859355;1427.957161285163;933.7478880702308;960.4525420510382;966.8839739231337;969.3021024862088;969.345691658128;5410.891782164414;5414.214938833847;5415.83752487426;5439.184122381595;5464.153056276284;5688.175214593944;5716;5730.426957304237;5730.914036289459;5771.5671324649975;5154.355705536808;5395.271638328775;5416.485433883775;5426.321709786277;5440.6735595864375;2698.7706044986003;2717.6275980630053;2734.7283513166076;2740.3423178437033;2748.818008256469;5190.97404512975;5226.432070378871;5226.712530810842;5256.922330922152;5275.736213189325;4954.801807927714;5091.621344840251;5163.915577728644;5174.43081261061;5183.300254465675;5178.456262092426;5213.3222681050465;5229.346331708536;5229.450907654717;5237.3453318335205;5069.467705890828;5072.264521644414;5085.110105731408;5088.389393272831;5109.974450127875;5365.919511207257;5789.45000224978;5798.420157984167;5808.73860676279;5808.970955145197;5981.222441082631;5996.850078748066;6013.9699301503215;6027.668478233739;6059.939510585542;3822.980885095668;4003.2193722223888;4084.4894388201474;4095.549489556087;4101.343784994387;5548;5694.202811606461;5721.971390143022;5789.652620842763;5839.649621022752;5501.477359650827;5596.468335508121;5606.897413469948;5624.606277560695;5697.287839020122;5674;5681.715914204356;5688.208214412447;5743.305724811275;5748.735278238781;5391.541718953776;5621.638911657032;5630.774769009357;5639.943600564106;5647.85880352978;5716.42835716425;5721.914171287351;5727.198192253005;5746.339170995389;5800.158976948234;5498.80754173602;5596.692181930033;5611.438856114356;5681;5703.7433315500075 04:32:48 INFO - PROCESS | 19269 | |5;yui.html;788.3496115704544;828.6685325869652;857.4983634573774;869.8608222684368;885.4908427654101;15723.921380393096;16995.74506382405;17045.232964516596;17060.317587296508;17083.658326833473;14732.779008314883;15927.044377337368;15951;15965.281562329694;15988.12065336406;678.1286047429055;804.5012092502657;825.5624519004922;837.2422957223722;847.2120927537388;824.2499325613693;835.4402550291319;836.0970152235601;836.4270474724833;838.2120806441925;881.5592203898051;885.7254251182122;888.7111688701166;889.8309321228978;891.2869704236617;697.6651207420441;845.3279642684081;867.0679020053468;882.5146169606722;882.9690960816373;639.5299454900644;642.3319747462633;642.5084810120262;643.5398689936718;643.6427782580666;16017;16032.679346413006;16072.678546429122;16131.677366452723;16179;13879.861201387957;14280.642983925485;14408.13551186932;14634.926825365912;14799.112053276838;12832;13072.346382680924;13263.469461221635;13389.598312050653;13527.864721352758;4422.800973956179;4485.1254005468945;4502.459704835408;4533.682642215046;4611.527828697698;73419;73976;74057.6297118511;74070.25929740687;74355.25644743537;11029.724256893482;11180.161487888441;11419.371934543513;11525.596604118815;11964.341961192218;18994.240230390908;19002;19007.33474328392;19015.239390424504;19027.714584281246;8089.110197878229;8431.19903609156;8597.828043439096;8608.397412181212;8745.518996455256;4579.862604121881;4750.477447480775;4798.832040878552;4911.901761964741;4912.533309335608;3513.4378499440477;3633.364161271767;3639.4176931690804;3641.85432582699;3642.581103173169;531.9521243088141;627.0907184582283;631.1416473595906;633.1705465839765;633.7908490198223;690.7685925215009;702.7259368846154;767.0143865133277;770.3452065744073;824.2664029014182;10351.585936562604;10405.375677459377;10421.322287022485;10730.2488825783;11150.49822757978;636.1316802564559;694.5795847491892;766.9884951725728;768.5696010234286;821.334718877711;4915.555820634374;4989.99717041547;5007.173816320339;5342.439043900412;5375.3280839895015;120132;125211.3739431297;125800;125863;126053.36973315074;101135;102707.4864625672;103821.48089259505;104124;104670;77945.61027194827;78346.6082669583;79038.20961790424;92691;98341.5082924595;9847.556859941176;10112.068277897506;10465.215108866712;10519.368837869752;10605.575776968835;46302.30546541872;48079;50411.243831341824;52002;52063.219051715016;51329;59734.701326494825;70978.29021709716;72827.63586182035;75171.24828751643;12094.818577721166;12313.507459701512;12657.367131643565;12854.935725321313;14125.576232713034;15723.213839308217;15894;16583.834161658713;16621.75067373966;16799.160041998097;15230.314635841192;15498.767518487457;15678.9216053919;15999;16245.268962896462;13699.45202191901;13969.161850289016;15240.237988100329;15353.923230383776;15544.989575677544;14180.503682371067;14576.34406451691;14749.262536873328;14997.400103995718;15668.843311566738;10720.624778132727;11164.44177791091;11365.48855301497;12152.453139608562;12261.509539618315;10754.247202695737;12316.384180791103;12531.12282140278;12629.11596188258;12787.80818287745 04:32:48 INFO - PROCESS | 19269 | __end_tp_report 04:32:48 INFO - PROCESS | 19269 | __start_cc_report 04:32:48 INFO - PROCESS | 19269 | _x_x_mozilla_cycle_collect,1039 04:32:48 INFO - PROCESS | 19269 | __end_cc_report 04:32:48 INFO - PROCESS | 19269 | __startTimestamp1468755167994__endTimestamp 04:32:48 INFO - PROCESS | 19269 | 04:32:48 INFO - PROCESS | 19269 | ------- Summary: start ------- 04:32:48 INFO - PROCESS | 19269 | Number of tests: 6 04:32:48 INFO - PROCESS | 19269 | 04:32:48 INFO - PROCESS | 19269 | [#0] dojo.html Cycles:165 Average:5347.15 Median:5587.97 stddev:623.18 (11.2%) stddev-sans-first:598.61 04:32:48 INFO - PROCESS | 19269 | Values: 3055.9 3074.0 3080.5 3102.9 3113.2 5212.0 5243.1 5293.9 5342.3 5346.1 5328.6 5360.7 5381.2 5381.2 5392.5 4803.4 4864.0 4884.8 4893.7 5035.5 4895.6 4935.2 4958.1 4972.7 4986.1 4738.4 4795.4 4823.8 4911.7 4924.9 4792.4 4800.1 4801.5 4824.0 4902.9 4778.6 4812.0 4832.1 4843.4 4845.8 5195.6 5293.4 5356.0 5364.0 5365.1 5795.5 5804.1 5817.3 5825.9 5829.7 5768.9 5811.6 5834.0 5866.6 5867.7 5263.2 5375.2 5413.5 5428.7 5512.2 6003.8 6007.8 6019.9 6021.3 6022.5 5519.7 5764.6 5773.0 5777.3 5778.1 5689.6 5741.1 5764.0 5849.6 5854.7 5760.2 5769.5 5788.3 5839.0 5839.3 5527.2 5696.5 5771.3 5780.1 5795.9 4339.3 4339.8 4359.6 4377.0 4390.1 3915.4 3931.8 3943.2 3945.0 3967.0 5309.6 5442.9 5471.6 5489.6 5534.9 5675.9 5692.0 5703.3 5712.7 5722.6 5391.1 5395.4 5399.0 5400.4 5404.6 4848.3 4965.7 4979.6 4982.7 5018.6 5954.4 5989.9 5993.2 6011.4 6047.0 5825.9 5827.1 5828.8 5829.6 5853.7 5724.4 5792.4 5859.8 5984.9 5991.3 5613.9 5622.6 5633.6 5676.3 5685.0 5629.1 5691.3 5726.4 5732.7 5739.1 5501.0 5582.4 5588.0 5607.6 5629.5 5670.9 5699.8 5709.3 5712.3 5745.4 5610.4 5614.5 5621.2 5623.2 5625.5 5484.2 5638.1 5662.2 5669.7 5717.1 5577.2 5586.6 5593.9 5595.0 5595.7 04:32:48 INFO - PROCESS | 19269 | 04:32:48 INFO - PROCESS | 19269 | [#1] ext.html Cycles:180 Average:60929.24 Median:3746.15 stddev:120016.65 (3203.7%) stddev-sans-first:120267.85 04:32:48 INFO - PROCESS | 19269 | Values: 598.5 621.4 634.0 645.2 646.9 30685.4 43417.6 44224.1 44328.6 44351.0 44820.0 45492.3 45523.5 45617.8 45643.5 8221.7 8999.3 9146.5 10611.5 10824.9 10746.0 10854.5 10858.1 10861.9 10881.4 10899.6 10913.6 10937.7 10945.7 10949.7 10853.6 10857.1 10862.7 10912.0 10913.5 10715.5 10730.9 10762.2 10771.2 10789.7 41506.4 41749.8 41919.0 41934.2 41948.2 3745.0 3747.3 4097.2 4279.8 4458.2 31949.4 32023.5 32435.8 32449.6 33437.5 4539.8 4557.1 4641.8 4723.2 4741.2 336081.3 336253.0 337541.2 339143.0 339891.0 58972.4 59590.0 59590.0 59633.7 59802.7 62481.7 62971.7 62980.7 63000.4 63157.7 15144.9 15414.8 15416.0 15422.3 15432.8 12905.5 13077.7 13078.1 13120.5 13121.2 6759.3 7904.4 7916.2 8010.3 8016.7 3127.4 3150.6 3188.5 3195.4 3195.4 1047.8 1085.6 1117.9 1155.9 1171.1 32391.5 32938.2 33027.2 33200.9 33224.3 1118.9 1123.1 1213.4 1216.5 1227.7 16911.2 17345.5 17395.7 17433.8 17479.7 517356.0 521520.0 524234.0 524313.0 526263.0 57347.7 58388.7 58615.7 58655.3 58810.0 1286.0 1347.7 1392.7 1406.8 1414.6 31525.7 32473.0 33060.5 33338.8 34444.5 56152.7 56251.0 56823.7 56839.4 56886.0 473205.0 476019.0 477401.0 477417.0 477685.0 25133.0 27284.3 27888.9 27952.0 29596.6 48832.8 49017.4 49650.5 50044.7 50172.7 41117.8 41809.2 41834.2 42653.8 43029.8 41971.0 42102.8 42400.6 42876.8 43445.3 41064.4 41824.8 42186.0 42264.6 42455.0 34815.8 36401.5 37575.8 37712.0 37857.6 28670.7 28941.7 28984.3 29175.4 29177.4 04:32:48 INFO - PROCESS | 19269 | 04:32:48 INFO - PROCESS | 19269 | [#2] jquery.html Cycles:180 Average:28942.03 Median:371.09 stddev:82293.62 (22176.1%) stddev-sans-first:82496.49 04:32:48 INFO - PROCESS | 19269 | Values: 359.3 370.9 371.2 377.9 382.8 18745.0 20187.5 20256.6 21117.7 21130.0 19830.0 19980.0 20053.3 20278.6 20421.6 5378.7 5520.5 5639.3 5675.6 5723.6 5639.6 5678.7 5696.9 5720.0 5722.1 5472.3 5543.0 5546.7 5630.3 5661.5 5568.6 5576.9 5577.1 5586.9 5610.4 4836.5 4889.7 4890.7 4913.9 4914.5 23391.5 23515.8 23569.5 23933.8 23948.4 2378.5 2446.4 2471.7 2492.3 3000.0 14469.1 14586.3 14715.3 14719.6 14868.3 1748.5 1804.8 1909.6 1916.3 1973.7 51065.7 51492.2 52679.5 86217.0 86657.6 9855.9 9984.2 9997.2 10094.5 10224.1 13336.9 13372.1 13442.1 13460.5 13460.6 8039.2 8082.4 8093.8 8160.3 8308.3 8310.8 8341.5 8366.7 8499.1 8504.6 6246.7 6314.0 6347.2 6376.6 6397.6 1665.5 1727.6 1728.7 1744.7 1775.8 1014.8 1044.9 1100.7 1106.3 1125.7 19538.0 20343.3 20353.9 20367.3 20526.6 973.1 1012.8 1034.9 1036.0 1097.7 10372.8 11616.5 11687.4 11693.1 11718.9 478723.0 503113.0 507877.0 508614.0 508956.0 83781.0 84116.0 84387.0 85222.1 86271.0 924.5 929.2 945.6 946.0 947.1 17575.6 17724.9 17861.5 17941.6 18103.0 40603.2 41290.6 42149.0 42596.6 43133.6 56878.1 57272.0 59672.0 60757.4 61314.0 7986.0 8076.9 8279.1 8321.7 8415.2 9056.1 12813.9 13591.9 13861.8 14239.4 6957.0 11699.8 12058.8 12236.8 12358.6 11843.5 12012.1 12327.4 12493.8 12738.7 12344.3 12629.6 12658.3 12694.5 12805.7 8377.2 8384.5 8487.5 8637.7 8666.5 11664.8 11915.3 12025.8 12129.8 12677.4 04:32:48 INFO - PROCESS | 19269 | 04:32:48 INFO - PROCESS | 19269 | [#3] mootools.html Cycles:180 Average:5915.60 Median:2574.89 stddev:3442.87 (133.7%) stddev-sans-first:3435.59 04:32:48 INFO - PROCESS | 19269 | Values: 1371.3 1472.0 1530.6 1653.9 1810.9 8623.4 8932.5 8949.1 9152.8 9170.3 8873.1 9093.2 9109.1 9113.6 9119.0 2296.3 2330.5 2331.3 2332.5 2332.5 2275.6 2287.5 2329.6 2334.4 2334.5 2300.4 2324.3 2327.5 2328.2 2331.4 2215.4 2220.5 2223.2 2225.4 2228.1 2255.6 2259.2 2265.1 2267.1 2271.1 9021.6 9096.5 9099.0 9101.5 9106.5 5117.3 5297.7 5301.0 5309.8 5310.2 3386.3 3386.8 3428.8 3430.9 3437.7 1118.8 1121.1 1122.2 1128.6 1145.5 12651.1 12781.2 12789.5 12790.9 12796.6 4231.9 4339.5 4490.6 4510.4 4511.7 9639.9 9647.9 9677.2 9685.6 9696.9 5207.1 5207.3 5207.5 5212.3 5213.9 4231.3 4692.6 4842.0 4857.1 4871.1 3751.7 3814.0 3848.2 3884.0 3886.2 1081.8 1127.7 1129.4 1135.7 1137.0 730.6 891.1 965.3 988.3 1001.4 6040.8 6218.0 6349.7 6366.3 6446.9 448.5 451.4 453.4 457.2 622.9 4105.8 4257.1 4269.5 4277.5 4291.2 10721.1 11007.3 11027.3 11102.6 11105.6 10979.3 10979.9 10988.8 10990.6 11000.4 8209.6 8437.6 8443.4 8678.6 8686.3 5848.4 5903.8 6144.6 6286.4 6286.8 10147.2 10562.1 10564.7 10699.5 10721.6 10632.5 10799.6 10804.5 10845.7 10916.8 7913.6 7934.6 8109.2 8125.9 8156.1 8979.4 8988.4 9024.8 9064.0 9066.9 8033.8 8273.7 8277.0 8365.5 8388.5 8015.4 8068.2 8069.1 8271.5 8302.3 7215.1 7224.6 7236.5 7267.2 7341.6 5068.8 5289.4 5342.8 5419.2 5483.5 5317.9 5351.9 5394.5 5503.1 5520.2 04:32:48 INFO - PROCESS | 19269 | 04:32:48 INFO - PROCESS | 19269 | [#4] prototype.html Cycles:180 Average:4639.86 Median:5226.57 stddev:1374.86 (26.3%) stddev-sans-first:1339.37 04:32:48 INFO - PROCESS | 19269 | Values: 289.0 294.9 297.7 311.5 312.1 5221.8 5314.4 5393.8 5416.9 5445.3 5175.6 5321.4 5348.9 5355.3 5376.0 4145.0 4156.2 4162.0 4162.7 4184.6 3885.2 3943.8 4125.7 4149.5 4150.8 4094.4 4097.0 4117.6 4125.6 4128.8 4089.7 4101.8 4111.7 4128.1 4137.0 3467.9 3662.5 3666.8 3678.2 3684.7 5186.5 5196.0 5212.7 5213.6 5228.1 4664.4 4704.1 4709.0 4718.1 4719.7 5405.3 5578.7 5592.1 5596.1 5614.9 3944.3 3957.4 3968.0 3983.7 4009.3 5853.7 5895.4 5903.8 5911.2 5922.4 4019.1 4087.5 4106.9 4111.8 4113.5 4161.1 4166.1 4173.1 4179.7 4180.4 4747.4 4759.3 4785.4 4803.5 4804.0 5488.4 5598.8 5649.8 5652.7 5712.6 1334.9 1385.4 1416.7 1425.9 1428.0 933.7 960.5 966.9 969.3 969.3 5410.9 5414.2 5415.8 5439.2 5464.2 5688.2 5716.0 5730.4 5730.9 5771.6 5154.4 5395.3 5416.5 5426.3 5440.7 2698.8 2717.6 2734.7 2740.3 2748.8 5191.0 5226.4 5226.7 5256.9 5275.7 4954.8 5091.6 5163.9 5174.4 5183.3 5178.5 5213.3 5229.3 5229.5 5237.3 5069.5 5072.3 5085.1 5088.4 5110.0 5365.9 5789.5 5798.4 5808.7 5809.0 5981.2 5996.9 6014.0 6027.7 6059.9 3823.0 4003.2 4084.5 4095.5 4101.3 5548.0 5694.2 5722.0 5789.7 5839.6 5501.5 5596.5 5606.9 5624.6 5697.3 5674.0 5681.7 5688.2 5743.3 5748.7 5391.5 5621.6 5630.8 5639.9 5647.9 5716.4 5721.9 5727.2 5746.3 5800.2 5498.8 5596.7 5611.4 5681.0 5703.7 04:32:48 INFO - PROCESS | 19269 | 04:32:48 INFO - PROCESS | 19269 | [#5] yui.html Cycles:180 Average:21171.16 Median:3636.39 stddev:30475.09 (838.1%) stddev-sans-first:30522.15 04:32:48 INFO - PROCESS | 19269 | Values: 788.3 828.7 857.5 869.9 885.5 15723.9 16995.7 17045.2 17060.3 17083.7 14732.8 15927.0 15951.0 15965.3 15988.1 678.1 804.5 825.6 837.2 847.2 824.2 835.4 836.1 836.4 838.2 881.6 885.7 888.7 889.8 891.3 697.7 845.3 867.1 882.5 883.0 639.5 642.3 642.5 643.5 643.6 16017.0 16032.7 16072.7 16131.7 16179.0 13879.9 14280.6 14408.1 14634.9 14799.1 12832.0 13072.3 13263.5 13389.6 13527.9 4422.8 4485.1 4502.5 4533.7 4611.5 73419.0 73976.0 74057.6 74070.3 74355.3 11029.7 11180.2 11419.4 11525.6 11964.3 18994.2 19002.0 19007.3 19015.2 19027.7 8089.1 8431.2 8597.8 8608.4 8745.5 4579.9 4750.5 4798.8 4911.9 4912.5 3513.4 3633.4 3639.4 3641.9 3642.6 532.0 627.1 631.1 633.2 633.8 690.8 702.7 767.0 770.3 824.3 10351.6 10405.4 10421.3 10730.2 11150.5 636.1 694.6 767.0 768.6 821.3 4915.6 4990.0 5007.2 5342.4 5375.3 120132.0 125211.4 125800.0 125863.0 126053.4 101135.0 102707.5 103821.5 104124.0 104670.0 77945.6 78346.6 79038.2 92691.0 98341.5 9847.6 10112.1 10465.2 10519.4 10605.6 46302.3 48079.0 50411.2 52002.0 52063.2 51329.0 59734.7 70978.3 72827.6 75171.2 12094.8 12313.5 12657.4 12854.9 14125.6 15723.2 15894.0 16583.8 16621.8 16799.2 15230.3 15498.8 15678.9 15999.0 16245.3 13699.5 13969.2 15240.2 15353.9 15545.0 14180.5 14576.3 14749.3 14997.4 15668.8 10720.6 11164.4 11365.5 12152.5 12261.5 10754.2 12316.4 12531.1 12629.1 12787.8 04:32:48 INFO - PROCESS | 19269 | -------- Summary: end -------- 04:32:48 INFO - PROCESS | 19269 | 04:32:48 INFO - TEST-INFO | 19269: exit 0 04:32:48 INFO - TEST-OK | dromaeo_css | took 1113091ms 04:32:48 INFO - TEST-START | kraken 04:32:48 INFO - operating with platform_type : linux_ 04:32:48 INFO - Initialising browser for kraken test... 04:32:48 INFO - TEST-INFO | started process 19465 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpThG6NI/profile http://localhost:52149/getInfo.html) 04:32:51 INFO - PROCESS | 19465 | JavaScript error: , line 0: uncaught exception: undefined 04:32:52 INFO - PROCESS | 19465 | __metrics Screen width/height:1600/1200 04:32:52 INFO - PROCESS | 19465 | colorDepth:24 04:32:52 INFO - PROCESS | 19465 | Browser inner width/height: 1024/697 04:32:52 INFO - PROCESS | 19465 | __metrics 04:32:53 INFO - PROCESS | 19465 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:32:53 INFO - PROCESS | 19465 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:32:54 INFO - TEST-INFO | 19465: exit 0 04:32:54 INFO - Browser initialized. 04:32:54 INFO - Running cycle 1/1 for kraken test... 04:32:54 INFO - TEST-INFO | started process 19550 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpThG6NI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:33:06 INFO - PROCESS | 19550 | RSS: Main: 158588928 04:33:06 INFO - PROCESS | 19550 | 04:33:08 INFO - PROCESS | 19550 | Cycle 1(1): loaded http://localhost:52149/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52149/tests/kraken/driver.html?testName=audio-beat-detection) 04:33:09 INFO - PROCESS | 19550 | RSS: Main: 217534464 04:33:09 INFO - PROCESS | 19550 | 04:33:12 INFO - PROCESS | 19550 | Cycle 1(1): loaded http://localhost:52149/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52149/tests/kraken/driver.html?testName=audio-dft) 04:33:12 INFO - PROCESS | 19550 | RSS: Main: 206233600 04:33:12 INFO - PROCESS | 19550 | 04:33:15 INFO - PROCESS | 19550 | Cycle 1(1): loaded http://localhost:52149/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52149/tests/kraken/driver.html?testName=audio-fft) 04:33:16 INFO - PROCESS | 19550 | RSS: Main: 270573568 04:33:16 INFO - PROCESS | 19550 | 04:33:18 INFO - PROCESS | 19550 | Cycle 1(1): loaded http://localhost:52149/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52149/tests/kraken/driver.html?testName=audio-oscillator) 04:33:19 INFO - PROCESS | 19550 | RSS: Main: 181035008 04:33:19 INFO - PROCESS | 19550 | 04:33:21 INFO - PROCESS | 19550 | Cycle 1(1): loaded http://localhost:52149/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52149/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:33:21 INFO - PROCESS | 19550 | RSS: Main: 185319424 04:33:21 INFO - PROCESS | 19550 | 04:33:24 INFO - PROCESS | 19550 | Cycle 1(1): loaded http://localhost:52149/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52149/tests/kraken/driver.html?testName=imaging-darkroom) 04:33:25 INFO - PROCESS | 19550 | RSS: Main: 220110848 04:33:25 INFO - PROCESS | 19550 | 04:33:28 INFO - PROCESS | 19550 | Cycle 1(1): loaded http://localhost:52149/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52149/tests/kraken/driver.html?testName=imaging-desaturate) 04:33:29 INFO - PROCESS | 19550 | RSS: Main: 221728768 04:33:29 INFO - PROCESS | 19550 | 04:33:32 INFO - PROCESS | 19550 | Cycle 1(1): loaded http://localhost:52149/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52149/tests/kraken/driver.html?testName=json-parse-financial) 04:33:32 INFO - PROCESS | 19550 | RSS: Main: 222138368 04:33:32 INFO - PROCESS | 19550 | 04:33:34 INFO - PROCESS | 19550 | Cycle 1(1): loaded http://localhost:52149/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52149/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:33:34 INFO - PROCESS | 19550 | RSS: Main: 210677760 04:33:34 INFO - PROCESS | 19550 | 04:33:36 INFO - PROCESS | 19550 | Cycle 1(1): loaded http://localhost:52149/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52149/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:33:37 INFO - PROCESS | 19550 | RSS: Main: 214212608 04:33:37 INFO - PROCESS | 19550 | 04:33:39 INFO - PROCESS | 19550 | Cycle 1(1): loaded http://localhost:52149/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52149/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:33:39 INFO - PROCESS | 19550 | RSS: Main: 211091456 04:33:39 INFO - PROCESS | 19550 | 04:33:42 INFO - PROCESS | 19550 | Cycle 1(1): loaded http://localhost:52149/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52149/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:33:42 INFO - PROCESS | 19550 | RSS: Main: 224030720 04:33:42 INFO - PROCESS | 19550 | 04:33:45 INFO - PROCESS | 19550 | Cycle 1(1): loaded http://localhost:52149/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52149/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:33:46 INFO - PROCESS | 19550 | RSS: Main: 225304576 04:33:46 INFO - PROCESS | 19550 | 04:33:47 INFO - PROCESS | 19550 | Cycle 1(1): loaded http://localhost:52149/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52149/tests/kraken/driver.html?testName=ai-astar) 04:33:48 INFO - PROCESS | 19550 | RSS: Main: 267911168 04:33:48 INFO - PROCESS | 19550 | 04:33:48 INFO - PROCESS | 19550 | __start_tp_report 04:33:48 INFO - PROCESS | 19550 | _x_x_mozilla_page_load 04:33:48 INFO - PROCESS | 19550 | _x_x_mozilla_page_load_details 04:33:48 INFO - PROCESS | 19550 | |i|pagename|runs| 04:33:48 INFO - PROCESS | 19550 | |0;ai-astar;102;111;110;111;110;111;109;111;110;109 04:33:48 INFO - PROCESS | 19550 | |1;audio-beat-detection;142;118;119;172;118;117;143;117;155;116 04:33:48 INFO - PROCESS | 19550 | |2;audio-dft;160;160;160;160;160;161;161;161;161;161 04:33:48 INFO - PROCESS | 19550 | |3;audio-fft;74;76;74;124;76;75;74;74;101;75 04:33:48 INFO - PROCESS | 19550 | |4;audio-oscillator;83;83;116;83;83;84;126;84;85;128 04:33:48 INFO - PROCESS | 19550 | |5;imaging-gaussian-blur;117;117;117;117;116;117;117;117;117;117 04:33:48 INFO - PROCESS | 19550 | |6;imaging-darkroom;145;147;145;146;145;146;146;145;145;146 04:33:48 INFO - PROCESS | 19550 | |7;imaging-desaturate;94;94;94;95;94;95;95;95;95;95 04:33:48 INFO - PROCESS | 19550 | |8;json-parse-financial;52;51;52;53;50;50;51;50;51;51 04:33:48 INFO - PROCESS | 19550 | |9;json-stringify-tinderbox;61;62;62;61;62;62;62;63;62;62 04:33:48 INFO - PROCESS | 19550 | |10;stanford-crypto-aes;75;75;74;75;82;75;75;74;74;73 04:33:48 INFO - PROCESS | 19550 | |11;stanford-crypto-ccm;126;119;125;122;117;117;127;119;161;126 04:33:48 INFO - PROCESS | 19550 | |12;stanford-crypto-pbkdf2;158;160;154;155;155;155;154;156;158;155 04:33:48 INFO - PROCESS | 19550 | |13;stanford-crypto-sha256-iterative;62;73;63;62;64;66;62;62;61;61 04:33:48 INFO - PROCESS | 19550 | __end_tp_report 04:33:48 INFO - PROCESS | 19550 | __start_cc_report 04:33:48 INFO - PROCESS | 19550 | _x_x_mozilla_cycle_collect,1627 04:33:48 INFO - PROCESS | 19550 | __end_cc_report 04:33:48 INFO - PROCESS | 19550 | __startTimestamp1468755228137__endTimestamp 04:33:48 INFO - PROCESS | 19550 | 04:33:48 INFO - PROCESS | 19550 | ------- Summary: start ------- 04:33:48 INFO - PROCESS | 19550 | Number of tests: 14 04:33:48 INFO - PROCESS | 19550 | 04:33:48 INFO - PROCESS | 19550 | [#0] ai-astar Cycles:10 Average:109.40 Median:110.50 stddev:2.72 (2.5%) stddev-sans-first:0.83 04:33:48 INFO - PROCESS | 19550 | Values: 102.0 111.0 110.0 111.0 110.0 111.0 109.0 111.0 110.0 109.0 04:33:48 INFO - PROCESS | 19550 | 04:33:48 INFO - PROCESS | 19550 | [#1] audio-beat-detection Cycles:10 Average:131.70 Median:130.50 stddev:20.04 (15.4%) stddev-sans-first:20.91 04:33:48 INFO - PROCESS | 19550 | Values: 142.0 118.0 119.0 172.0 118.0 117.0 143.0 117.0 155.0 116.0 04:33:48 INFO - PROCESS | 19550 | 04:33:48 INFO - PROCESS | 19550 | [#2] audio-dft Cycles:10 Average:160.50 Median:161.00 stddev:0.53 (0.3%) stddev-sans-first:0.53 04:33:48 INFO - PROCESS | 19550 | Values: 160.0 160.0 160.0 160.0 160.0 161.0 161.0 161.0 161.0 161.0 04:33:48 INFO - PROCESS | 19550 | 04:33:48 INFO - PROCESS | 19550 | [#3] audio-fft Cycles:10 Average:82.30 Median:74.50 stddev:16.83 (22.6%) stddev-sans-first:17.58 04:33:48 INFO - PROCESS | 19550 | Values: 74.0 76.0 74.0 124.0 76.0 75.0 74.0 74.0 101.0 75.0 04:33:48 INFO - PROCESS | 19550 | 04:33:48 INFO - PROCESS | 19550 | [#4] audio-oscillator Cycles:10 Average:95.50 Median:83.00 stddev:19.46 (23.4%) stddev-sans-first:20.10 04:33:48 INFO - PROCESS | 19550 | Values: 83.0 83.0 116.0 83.0 83.0 84.0 126.0 84.0 85.0 128.0 04:33:48 INFO - PROCESS | 19550 | 04:33:48 INFO - PROCESS | 19550 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 04:33:48 INFO - PROCESS | 19550 | Values: 117.0 117.0 117.0 117.0 116.0 117.0 117.0 117.0 117.0 117.0 04:33:48 INFO - PROCESS | 19550 | 04:33:48 INFO - PROCESS | 19550 | [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.70 (0.5%) stddev-sans-first:0.71 04:33:48 INFO - PROCESS | 19550 | Values: 145.0 147.0 145.0 146.0 145.0 146.0 146.0 145.0 145.0 146.0 04:33:48 INFO - PROCESS | 19550 | 04:33:48 INFO - PROCESS | 19550 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.52 (0.5%) stddev-sans-first:0.50 04:33:48 INFO - PROCESS | 19550 | Values: 94.0 94.0 94.0 95.0 94.0 95.0 95.0 95.0 95.0 95.0 04:33:48 INFO - PROCESS | 19550 | 04:33:48 INFO - PROCESS | 19550 | [#8] json-parse-financial Cycles:10 Average:51.10 Median:51.00 stddev:0.99 (1.9%) stddev-sans-first:1.00 04:33:48 INFO - PROCESS | 19550 | Values: 52.0 51.0 52.0 53.0 50.0 50.0 51.0 50.0 51.0 51.0 04:33:48 INFO - PROCESS | 19550 | 04:33:48 INFO - PROCESS | 19550 | [#9] json-stringify-tinderbox Cycles:10 Average:61.90 Median:62.00 stddev:0.57 (0.9%) stddev-sans-first:0.50 04:33:48 INFO - PROCESS | 19550 | Values: 61.0 62.0 62.0 61.0 62.0 62.0 62.0 63.0 62.0 62.0 04:33:48 INFO - PROCESS | 19550 | 04:33:48 INFO - PROCESS | 19550 | [#10] stanford-crypto-aes Cycles:10 Average:75.20 Median:75.00 stddev:2.49 (3.3%) stddev-sans-first:2.64 04:33:48 INFO - PROCESS | 19550 | Values: 75.0 75.0 74.0 75.0 82.0 75.0 75.0 74.0 74.0 73.0 04:33:48 INFO - PROCESS | 19550 | 04:33:48 INFO - PROCESS | 19550 | [#11] stanford-crypto-ccm Cycles:10 Average:125.90 Median:125.50 stddev:12.92 (10.3%) stddev-sans-first:13.71 04:33:48 INFO - PROCESS | 19550 | Values: 126.0 119.0 125.0 122.0 117.0 117.0 127.0 119.0 161.0 126.0 04:33:48 INFO - PROCESS | 19550 | 04:33:48 INFO - PROCESS | 19550 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:156.00 Median:155.50 stddev:2.00 (1.3%) stddev-sans-first:1.99 04:33:48 INFO - PROCESS | 19550 | Values: 158.0 160.0 154.0 155.0 155.0 155.0 154.0 156.0 158.0 155.0 04:33:48 INFO - PROCESS | 19550 | 04:33:48 INFO - PROCESS | 19550 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.60 Median:62.50 stddev:3.63 (5.8%) stddev-sans-first:3.80 04:33:48 INFO - PROCESS | 19550 | Values: 62.0 73.0 63.0 62.0 64.0 66.0 62.0 62.0 61.0 61.0 04:33:48 INFO - PROCESS | 19550 | -------- Summary: end -------- 04:33:48 INFO - PROCESS | 19550 | 04:33:49 INFO - TEST-INFO | 19550: exit 0 04:33:49 INFO - TEST-OK | kraken | took 60395ms 04:33:49 INFO - SUITE-END | took 1173s 04:33:49 INFO - Completed test suite (00:19:33) 04:33:49 INFO - javascript benchmark 04:33:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3055.9083227503174, 3073.9846300768495, 3080.5225190095543, 3102.8913988010418, 3113.2372568720666, 5211.983663185681, 5243.056249875021, 5293.8941221175555, 5342.305500284962, 5346.11789054806, 5328.600354973373, 5360.705161216131, 5381.19282107684, 5381.19282107684, 5392.460753924605, 4803.351547541081, 4863.975680121594, 4884.780184891671, 4893.657443978923, 5035.521625445577, 4895.5838753705975, 4935.161022626145, 4958.107540642683, 4972.651914365996, 4986.127427700156, 4738.407699037621, 4795.424549054118, 4823.782929768169, 4911.729854857981, 4924.901501969976, 4792.424909010906, 4800.135975524404, 4801.5438533339275, 4823.9517604824205, 4902.8774280643265, 4778.569928706398, 4812.037592481518, 4832.081904438145, 4843.39457567804, 4845.806167753286, 5195.558377537914, 5293.364796224478, 5355.973220133875, 5364.0, 5365.114756065244, 5795.507381872546, 5804.100364443518, 5817.272840894888, 5825.854353641151, 5829.650220986712, 5768.884622307531, 5811.593188476808, 5833.979053665634, 5866.648001119904, 5867.735951882176, 5263.210518422267, 5375.16684913839, 5413.53984911283, 5428.67427954324, 5512.228288039678, 6003.849903752441, 6007.849803754853, 6019.939800601938, 6021.277446706424, 6022.45797878193, 5519.69641669704, 5764.5676574257095, 5772.9711351442975, 5777.30672319316, 5778.075507918797, 5689.630174038757, 5741.110127930177, 5763.971180144073, 5849.561282903799, 5854.7072646367515, 5760.164776107441, 5769.538436925036, 5788.305403351625, 5839.0, 5839.328477225174, 5527.170924361378, 5696.458836410535, 5771.307443106854, 5780.132980053026, 5795.8551036224435, 4339.284018136972, 4339.7830108494445, 4359.629431498358, 4377.0, 4390.1000294940095, 3915.432262322004, 3931.8427262909736, 3943.152222272225, 3945.0, 3967.028078120821, 5309.601779866525, 5442.891142177214, 5471.562275017989, 5489.615726899079, 5534.944650553523, 5675.914861277084, 5691.9715401423555, 5703.258576385043, 5712.65724056558, 5722.570807189478, 5391.056565101092, 5395.406505284415, 5399.0, 5400.405955344909, 5404.648697834628, 4848.321235027099, 4965.726885021286, 4979.576735977483, 4982.676126051795, 5018.623603229699, 5954.434328738763, 5989.910151347821, 5993.160957465958, 6011.398860113954, 6046.969765151146, 5825.854353641193, 5827.067669172912, 5828.825135245949, 5829.621074630136, 5853.707314634336, 5724.398938111438, 5792.420757924342, 5859.824205273849, 5984.940150598438, 5991.3409524953095, 5613.887722245614, 5622.634528755618, 5633.577481688972, 5676.34722006958, 5684.971575142098, 5629.127485239795, 5691.3170419549915, 5726.370099289158, 5732.742026608896, 5739.081746920473, 5500.972495137498, 5582.385937546947, 5587.972060139673, 5607.635503692247, 5629.4652008059165, 5670.914936276042, 5699.772009119589, 5709.25779648643, 5712.314522257356, 5745.396733342939, 5610.382857885711, 5614.494695477427, 5621.241132447068, 5623.212750215055, 5625.5218306442775, 5484.204790305464, 5638.097904335286, 5662.178984047372, 5669.716514174357, 5717.113847353667, 5577.191307260506, 5586.608937374344, 5593.860153496033, 5595.0, 5595.748191331319], "value": 5304.075284153358, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [598.4733434577571, 621.4127649371344, 633.9603050996367, 645.183842439314, 646.9029645553169, 30685.386292274143, 43417.56582434175, 44224.115517689665, 44328.556714432845, 44351.0, 44820.0, 45492.31761523571, 45523.544764552345, 45617.77191114048, 45643.543564564345, 8221.671133154696, 8999.28005759541, 9146.542672866364, 10611.522481488313, 10824.945875270612, 10746.0, 10854.45727713611, 10858.131349492021, 10861.891381086167, 10881.401522916236, 10899.618513352034, 10913.563457461692, 10937.726556836064, 10945.726356841064, 10949.671509854717, 10853.620123295726, 10857.077148442391, 10862.72843178919, 10912.0, 10913.508892099875, 10715.46422678863, 10730.946345268301, 10762.246642735012, 10771.192160587987, 10789.676309710721, 41506.37740433896, 41749.791251043855, 41919.0, 41934.161316773796, 41948.1610367794, 3745.0, 3747.306748251555, 4097.180563887235, 4279.764612946286, 4458.197524445599, 31949.361012779613, 32023.51964720531, 32435.837820810983, 32449.582364162343, 33437.498437523456, 4539.84110556129, 4557.088582283556, 4641.780014780177, 4723.220668589679, 4741.241401375798, 336081.3195934029, 336253.0, 337541.24587541295, 339143.0, 339891.0, 58972.41027589669, 59590.0, 59590.0, 59633.701831490565, 59802.70098649479, 62481.68759156175, 62971.685141574, 62980.68509657423, 63000.36999630037, 63157.684211579566, 15144.924275378773, 15414.768778468331, 15416.0, 15422.305996230196, 15432.84567154314, 12905.483780648856, 13077.673058173621, 13078.149920255153, 13120.475180992844, 13121.212727236396, 6759.290274521203, 7904.446688731734, 7916.208379162153, 8010.279074883289, 8016.679332826621, 3127.3588914272973, 3150.57467241924, 3188.537662038991, 3195.3769015041844, 3195.376901504231, 1047.8041971985415, 1085.5820509103894, 1117.8546788917388, 1155.8844115588374, 1171.1041053594008, 32391.51412728811, 32938.17654558655, 33027.174320642174, 33200.868491294896, 33224.33551329008, 1118.9048930840806, 1123.0622430270816, 1213.4296880466168, 1216.4951545108859, 1227.711487800384, 16911.23899424529, 17345.47963561095, 17395.65208695819, 17433.825661743474, 17479.65040699179, 517356.0, 521520.0, 524234.0, 524313.0, 526263.0, 57347.713261433426, 58388.708056460295, 58615.70692146512, 58655.30757539387, 58810.0, 1285.9807102893465, 1347.690031292808, 1392.7493051250872, 1406.8100806391415, 1414.5826981040618, 31525.684743152276, 32473.0, 33060.504092439114, 33338.833305833316, 34444.48333274953, 56152.71923640356, 56251.0, 56823.71588142033, 56839.431605685066, 56886.0, 473205.0, 476019.0, 477401.0, 477417.0, 477685.0, 25133.0, 27284.31789205286, 27888.86055569709, 27952.0, 29596.556051658812, 48832.75583622059, 49017.36300325508, 49650.50349496603, 50044.74977625089, 50172.749136255545, 41117.79441102895, 41809.1638167241, 41834.163316732884, 42653.786731066146, 43029.78485107554, 41971.0, 42102.789486052374, 42400.57599423966, 42876.78561607172, 43445.348319774595, 41064.38403424011, 41824.79087604542, 42186.0, 42264.577354227295, 42455.0, 34815.82592087024, 36401.45397818982, 37575.81212093922, 37712.0, 37857.62142378651, 28670.713292866803, 28941.710582893902, 28984.275393115342, 29175.416491670472, 29177.416451671274], "value": 20061.284646418422, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [359.31909032383624, 370.94064949608054, 371.2408125383591, 377.9470874077628, 382.7837271941353, 18745.0, 20187.495312617193, 20256.594868102628, 21117.683234751472, 21130.049147788348, 19830.0, 19980.00099994998, 20053.29813456529, 20278.594428111428, 20421.591568168664, 5378.677279363231, 5520.530754885839, 5639.2668953036045, 5675.552734052811, 5723.599348045638, 5639.6334238274585, 5678.659280443166, 5696.943030569703, 5720.0, 5722.141678748179, 5472.315960504937, 5542.972285138569, 5546.69493177875, 5630.268065151545, 5661.46216109469, 5568.582356323272, 5576.86057848555, 5577.05190117681, 5586.888262234773, 5610.438956104398, 4836.540528649773, 4889.731064791435, 4890.657653964224, 4913.901721965576, 4914.4839791821705, 23391.532169356517, 23515.7648423517, 23569.528609427885, 23933.760662393328, 23948.401289967715, 2378.500514891875, 2446.437319416544, 2471.653968444401, 2492.3395300245447, 3000.0199270669464, 14469.131852088909, 14586.270686465634, 14715.264236788225, 14719.55841324762, 14868.256587170707, 1748.5104170832187, 1804.7834259888898, 1909.551255454967, 1916.32928475034, 1973.6743437332966, 51065.74467127678, 51492.227616585784, 52679.47320526784, 86217.0, 86657.56671216666, 9855.90144098564, 9984.154515962915, 9997.200223982063, 10094.495275236355, 10224.057429088598, 13336.866631333562, 13372.130811497416, 13442.126261792951, 13460.528881489297, 13460.596182114552, 8039.196080392031, 8082.434229603871, 8093.757187284391, 8160.3471722262075, 8308.252257296872, 8310.792230194173, 8341.45780524264, 8366.74498825181, 8499.107593702696, 8504.617292221865, 6246.718897649616, 6313.96843015782, 6347.174867267226, 6376.649284289316, 6397.648129352837, 1665.5170000699661, 1727.6285598596362, 1728.7234042553382, 1744.6772347115825, 1775.8135395783256, 1014.7868947521104, 1044.8902865199198, 1100.7468282295117, 1106.3251416635846, 1125.6604640477806, 19538.0, 20343.28798492046, 20353.898230508752, 20367.287144949856, 20526.589468210852, 973.1484950668165, 1012.8075665623508, 1034.912032477248, 1035.963741269052, 1097.7475180708482, 10372.80712718032, 11616.477258523219, 11687.415629218505, 11693.123015773852, 11718.88281117195, 478723.0, 503113.0, 507877.0, 508614.0, 508956.0, 83781.0, 84116.0, 84387.0, 85222.14777852266, 86271.0, 924.4822899176482, 929.1916033051424, 945.5933948402115, 945.9716208513755, 947.0813311088233, 17575.560610984827, 17724.91137544304, 17861.464156075337, 17941.551461213574, 18103.0, 40603.1879362417, 41290.58709412988, 42149.0, 42596.5740342605, 43133.56866431421, 56878.14682779679, 57272.0, 59672.0, 60757.39242607694, 61314.0, 7986.0, 8076.919230807852, 8279.089300176855, 8321.708740194064, 8415.200555947175, 9056.094390560891, 12813.93593032029, 13591.932040339734, 13861.792073119113, 14239.430422783387, 6957.0, 11699.824502632637, 12058.758824823279, 12236.75526489483, 12358.62924112278, 11843.52625894989, 12012.099092568267, 12327.445264962918, 12493.812592811297, 12738.68153296175, 12344.259344439362, 12629.621111366674, 12658.303793291458, 12694.492220311084, 12805.74388512206, 8377.204165604258, 8384.496930184208, 8487.490750554987, 8637.740867773977, 8666.480011199348, 11664.76670466569, 11915.285082895054, 12025.759484810429, 12129.75740485203, 12677.429515672002], "value": 9280.159152028373, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1371.3486094105294, 1472.0284612155972, 1530.5867415797734, 1653.8676905847533, 1810.900400477973, 8623.353248506364, 8932.508712020835, 8949.105089491055, 9152.816943661122, 9170.266378689705, 8873.112688731124, 9093.227075698573, 9109.134632209945, 9113.589888455019, 9118.954405227965, 2296.322584837477, 2330.5338932213626, 2331.25399872041, 2332.4518738096617, 2332.54515369503, 2275.5562665280195, 2287.462446325112, 2329.6272596384583, 2334.393057804966, 2334.533093381322, 2300.4133945843787, 2324.2910912171865, 2327.487952650423, 2328.2200462844967, 2331.4171457135717, 2215.435064058655, 2220.522587643656, 2223.1996481266733, 2225.4436390902356, 2228.0976204636986, 2255.5827171973237, 2259.175400978631, 2265.0939624150305, 2267.138487374787, 2271.057511132878, 9021.594028268744, 9096.545172741337, 9099.0, 9101.499417532033, 9106.499142547158, 5117.334746482933, 5297.708626025567, 5300.973495132538, 5309.840704778863, 5310.176922577007, 3386.271951530433, 3386.8137252451106, 3428.7599868009247, 3430.9485357719655, 3437.724982001434, 1118.815395459748, 1121.0863146535628, 1122.1752012270974, 1128.6049882541126, 1145.5131569083105, 12651.0511711622, 12781.233126012376, 12789.48842046317, 12790.872091279061, 12796.61610151697, 4231.851885184004, 4339.500957389941, 4490.595846373842, 4510.391097201901, 4511.729296242236, 9639.855402168972, 9647.855282170773, 9677.225821934227, 9685.564149613143, 9696.9030309696, 5207.062728708793, 5207.297014903015, 5207.479252074838, 5212.296339994128, 5213.895722085537, 4231.280682284019, 4692.554207350297, 4842.031593681278, 4857.077155340545, 4871.14754917888, 3751.737378383487, 3814.0, 3848.249591329664, 3884.0484081400236, 3886.1839013806853, 1081.7566047639218, 1127.6729748373043, 1129.3957732613012, 1135.7103938495795, 1137.0448822988728, 730.5507113125495, 891.1355984694835, 965.3396158607325, 988.3229987458614, 1001.384148748529, 6040.848978775478, 6217.9689101555105, 6349.746010159634, 6366.331535188739, 6446.930873193798, 448.50888277336264, 451.3973844917007, 453.43094416507074, 457.22728588685015, 622.8912535686485, 4105.835766569364, 4257.084726783756, 4269.530351661315, 4277.486701595828, 4291.163223171514, 10721.142308615292, 11007.339559626447, 11027.338359698444, 11102.555897764, 11105.555777768961, 10979.341239525495, 10979.890201098047, 10988.780224395467, 10990.615328463466, 11000.394978276112, 8209.589520524069, 8437.57812109392, 8443.36674749384, 8678.609462574073, 8686.261667758185, 5848.444397782374, 5903.822885313448, 6144.600600961102, 6286.402791734778, 6286.779962701284, 10147.18822494184, 10562.102221311274, 10564.683059508227, 10699.465026748787, 10721.571137154426, 10632.468376580986, 10799.622013229498, 10804.459777011276, 10845.728856778644, 10916.836247456136, 7913.643886025257, 7934.642941067781, 8109.189081091844, 8125.918740812516, 8156.102828688958, 8979.371443999118, 8988.370814042953, 9024.819503610022, 9063.954680226556, 9066.863997040182, 8033.758987230392, 8273.669053237803, 8276.958615206886, 8365.456245344034, 8388.496690198608, 8015.398845086525, 8068.233517815798, 8069.112397636372, 8271.54506502148, 8302.294304984143, 7215.098112735908, 7224.602646854473, 7236.529625574532, 7267.164276108315, 7341.59621220838, 5068.797248110034, 5289.413176047247, 5342.813001544927, 5419.214213939035, 5483.506484416422, 5317.920231196613, 5351.9464805351445, 5394.541463975449, 5503.11950087984, 5520.171974203901], "value": 4601.902440492181, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [289.00149981812837, 294.8559588795473, 297.743940211418, 311.52181401548626, 312.0591416878113, 5221.765020574074, 5314.441983591725, 5393.838184854456, 5416.864578385537, 5445.264889239957, 5175.593349092671, 5321.36143662761, 5348.893022139555, 5355.277037599933, 5375.973120134394, 4144.963759060235, 4156.189543039109, 4162.0, 4162.708610397258, 4184.644305234044, 3885.1841113366227, 3943.842246310144, 4125.669946404296, 4149.481314835645, 4150.792460376969, 4094.4267802507675, 4097.0, 4117.649999750025, 4125.608067233623, 4128.772917489537, 4089.652379547727, 4101.815418306169, 4111.650509706678, 4128.1330920506725, 4137.027798467358, 3467.9422776053293, 3662.450632405134, 3666.79992004795, 3678.2091850252054, 3684.7420680552373, 5186.455422180692, 5195.974020129913, 5212.661177023481, 5213.6089793265655, 5228.058949389108, 4664.416947881515, 4704.129735998851, 4709.0, 4718.079974404993, 4719.716816990957, 5405.27028851104, 5578.748956296977, 5592.133219351007, 5596.076647353182, 5614.887702245973, 3944.2900277949957, 3957.406389041638, 3967.98016009921, 3983.661388781957, 4009.3384591542363, 5853.7365818538265, 5895.437709007074, 5903.822885313404, 5911.231539899785, 5922.378150294243, 4019.0909318074264, 4087.529934057562, 4106.856260030884, 4111.7944102794745, 4113.547509773923, 4161.0845613964875, 4166.104287578125, 4173.10278290169, 4179.665626749853, 4180.414741936132, 4747.406574178228, 4759.333693282943, 4785.449673287547, 4803.495632958489, 4803.975980120077, 5488.353493951799, 5598.832035038955, 5649.774009039675, 5652.74562644682, 5712.628969047399, 1334.8531661517225, 1385.411200239894, 1416.7379034878375, 1425.9305520859355, 1427.957161285163, 933.7478880702308, 960.4525420510382, 966.8839739231337, 969.3021024862088, 969.345691658128, 5410.891782164414, 5414.214938833847, 5415.83752487426, 5439.184122381595, 5464.153056276284, 5688.175214593944, 5716.0, 5730.426957304237, 5730.914036289459, 5771.5671324649975, 5154.355705536808, 5395.271638328775, 5416.485433883775, 5426.321709786277, 5440.6735595864375, 2698.7706044986003, 2717.6275980630053, 2734.7283513166076, 2740.3423178437033, 2748.818008256469, 5190.97404512975, 5226.432070378871, 5226.712530810842, 5256.922330922152, 5275.736213189325, 4954.801807927714, 5091.621344840251, 5163.915577728644, 5174.43081261061, 5183.300254465675, 5178.456262092426, 5213.3222681050465, 5229.346331708536, 5229.450907654717, 5237.3453318335205, 5069.467705890828, 5072.264521644414, 5085.110105731408, 5088.389393272831, 5109.974450127875, 5365.919511207257, 5789.45000224978, 5798.420157984167, 5808.73860676279, 5808.970955145197, 5981.222441082631, 5996.850078748066, 6013.9699301503215, 6027.668478233739, 6059.939510585542, 3822.980885095668, 4003.2193722223888, 4084.4894388201474, 4095.549489556087, 4101.343784994387, 5548.0, 5694.202811606461, 5721.971390143022, 5789.652620842763, 5839.649621022752, 5501.477359650827, 5596.468335508121, 5606.897413469948, 5624.606277560695, 5697.287839020122, 5674.0, 5681.715914204356, 5688.208214412447, 5743.305724811275, 5748.735278238781, 5391.541718953776, 5621.638911657032, 5630.774769009357, 5639.943600564106, 5647.85880352978, 5716.42835716425, 5721.914171287351, 5727.198192253005, 5746.339170995389, 5800.158976948234, 5498.80754173602, 5596.692181930033, 5611.438856114356, 5681.0, 5703.7433315500075], "value": 4193.006409156048, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [788.3496115704544, 828.6685325869652, 857.4983634573774, 869.8608222684368, 885.4908427654101, 15723.921380393096, 16995.74506382405, 17045.232964516596, 17060.317587296508, 17083.658326833473, 14732.779008314883, 15927.044377337368, 15951.0, 15965.281562329694, 15988.12065336406, 678.1286047429055, 804.5012092502657, 825.5624519004922, 837.2422957223722, 847.2120927537388, 824.2499325613693, 835.4402550291319, 836.0970152235601, 836.4270474724833, 838.2120806441925, 881.5592203898051, 885.7254251182122, 888.7111688701166, 889.8309321228978, 891.2869704236617, 697.6651207420441, 845.3279642684081, 867.0679020053468, 882.5146169606722, 882.9690960816373, 639.5299454900644, 642.3319747462633, 642.5084810120262, 643.5398689936718, 643.6427782580666, 16017.0, 16032.679346413006, 16072.678546429122, 16131.677366452723, 16179.0, 13879.861201387957, 14280.642983925485, 14408.13551186932, 14634.926825365912, 14799.112053276838, 12832.0, 13072.346382680924, 13263.469461221635, 13389.598312050653, 13527.864721352758, 4422.800973956179, 4485.1254005468945, 4502.459704835408, 4533.682642215046, 4611.527828697698, 73419.0, 73976.0, 74057.6297118511, 74070.25929740687, 74355.25644743537, 11029.724256893482, 11180.161487888441, 11419.371934543513, 11525.596604118815, 11964.341961192218, 18994.240230390908, 19002.0, 19007.33474328392, 19015.239390424504, 19027.714584281246, 8089.110197878229, 8431.19903609156, 8597.828043439096, 8608.397412181212, 8745.518996455256, 4579.862604121881, 4750.477447480775, 4798.832040878552, 4911.901761964741, 4912.533309335608, 3513.4378499440477, 3633.364161271767, 3639.4176931690804, 3641.85432582699, 3642.581103173169, 531.9521243088141, 627.0907184582283, 631.1416473595906, 633.1705465839765, 633.7908490198223, 690.7685925215009, 702.7259368846154, 767.0143865133277, 770.3452065744073, 824.2664029014182, 10351.585936562604, 10405.375677459377, 10421.322287022485, 10730.2488825783, 11150.49822757978, 636.1316802564559, 694.5795847491892, 766.9884951725728, 768.5696010234286, 821.334718877711, 4915.555820634374, 4989.99717041547, 5007.173816320339, 5342.439043900412, 5375.3280839895015, 120132.0, 125211.3739431297, 125800.0, 125863.0, 126053.36973315074, 101135.0, 102707.4864625672, 103821.48089259505, 104124.0, 104670.0, 77945.61027194827, 78346.6082669583, 79038.20961790424, 92691.0, 98341.5082924595, 9847.556859941176, 10112.068277897506, 10465.215108866712, 10519.368837869752, 10605.575776968835, 46302.30546541872, 48079.0, 50411.243831341824, 52002.0, 52063.219051715016, 51329.0, 59734.701326494825, 70978.29021709716, 72827.63586182035, 75171.24828751643, 12094.818577721166, 12313.507459701512, 12657.367131643565, 12854.935725321313, 14125.576232713034, 15723.213839308217, 15894.0, 16583.834161658713, 16621.75067373966, 16799.160041998097, 15230.314635841192, 15498.767518487457, 15678.9216053919, 15999.0, 16245.268962896462, 13699.45202191901, 13969.161850289016, 15240.237988100329, 15353.923230383776, 15544.989575677544, 14180.503682371067, 14576.34406451691, 14749.262536873328, 14997.400103995718, 15668.843311566738, 10720.624778132727, 11164.44177791091, 11365.48855301497, 12152.453139608562, 12261.509539618315, 10754.247202695737, 12316.384180791103, 12531.12282140278, 12629.11596188258, 12787.80818287745], "value": 7846.795509488708, "unit": "score"}], "name": "dromaeo_css", "value": 7285.412984575657, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 111.0, 110.0, 111.0, 110.0, 111.0, 109.0, 111.0, 110.0, 109.0], "value": 109.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 118.0, 119.0, 172.0, 118.0, 117.0, 143.0, 117.0, 155.0, 116.0], "value": 131.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 160.0, 160.0, 160.0, 161.0, 161.0, 161.0, 161.0, 161.0], "value": 160.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 74.0, 124.0, 76.0, 75.0, 74.0, 74.0, 101.0, 75.0], "value": 82.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 116.0, 83.0, 83.0, 84.0, 126.0, 84.0, 85.0, 128.0], "value": 95.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 147.0, 145.0, 146.0, 145.0, 146.0, 146.0, 145.0, 145.0, 146.0], "value": 145.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 52.0, 53.0, 50.0, 50.0, 51.0, 50.0, 51.0, 51.0], "value": 51.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 62.0, 61.0, 62.0, 62.0, 62.0, 63.0, 62.0, 62.0], "value": 61.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 74.0, 75.0, 82.0, 75.0, 75.0, 74.0, 74.0, 73.0], "value": 75.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 119.0, 125.0, 122.0, 117.0, 117.0, 127.0, 119.0, 161.0, 126.0], "value": 125.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 160.0, 154.0, 155.0, 155.0, 155.0, 154.0, 156.0, 158.0, 155.0], "value": 156.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 73.0, 63.0, 62.0, 64.0, 66.0, 62.0, 62.0, 61.0, 61.0], "value": 63.6, "unit": "score"}], "name": "kraken", "value": 1470.2000000000003, "alertThreshold": 2.0}]} 04:33:49 INFO - Return code: 0 04:33:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:33:49 INFO - # TBPL SUCCESS # 04:33:49 INFO - Running post-action listener: _resource_record_post_action 04:33:49 INFO - ##### 04:33:49 INFO - ##### Finished run-tests step (success) 04:33:49 INFO - ##### 04:33:49 INFO - Running post-run listener: _resource_record_post_run 04:33:49 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 4096; Write bytes: 565956608; Read time: 8; Write time: 457252 04:33:49 INFO - TinderboxPrint: CPU usage
13.0% 04:33:49 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 04:33:49 INFO - TinderboxPrint: I/O write bytes / time
565,956,608 / 457,252 04:33:49 INFO - TinderboxPrint: CPU idle
8,267.8 (87.0%) 04:33:49 INFO - TinderboxPrint: CPU user
1,196.8 (12.6%) 04:33:49 INFO - TinderboxPrint: Swap in / out
0 / 0 04:33:49 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 264376320; Read time: 0; Write time: 260800 04:33:49 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 297000960; Read time: 0; Write time: 195844 04:33:50 INFO - Running post-run listener: _upload_blobber_files 04:33:50 INFO - Blob upload gear active. 04:33:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:33:50 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 04:33:50 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-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:33:50 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-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:33:50 INFO - (blobuploader) - INFO - Open directory for files ... 04:33:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:33:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:33:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:33:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:33:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:33:51 INFO - (blobuploader) - INFO - Done attempting. 04:33:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:33:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:33:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:33:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:33:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:33:51 INFO - (blobuploader) - INFO - Done attempting. 04:33:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:33:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:33:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:34:00 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:34:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:34:00 INFO - (blobuploader) - INFO - Done attempting. 04:34:00 INFO - (blobuploader) - INFO - Iteration through files over. 04:34:00 INFO - Return code: 0 04:34:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:34:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:34:00 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/f814adfd241db75ab5c2acc74787a6cc49f5cec639bae323c4d32776ef8af0f0273139981b950b89aa45fa8bc70d047f27b521ff125c0293683319180b918969", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d5c7c22f1545f4f49c5126ed88895f104dbc34f2afa2f0caf113d196f1a81ef3e714f51bed59a0ba1998fde9fdb3013970dc39aa0ab338fca3de34143e18b485", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/02d78cb4009ba13f6ca2f7bd62defbbe03b20b254d2f620f2c44a5311816b1dd96a742c1d9471a86eb852464cf469726396b6939b0c47df0daa9b187edf8b613"} 04:34:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:34:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:34:00 INFO - Contents: 04:34:00 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/f814adfd241db75ab5c2acc74787a6cc49f5cec639bae323c4d32776ef8af0f0273139981b950b89aa45fa8bc70d047f27b521ff125c0293683319180b918969", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d5c7c22f1545f4f49c5126ed88895f104dbc34f2afa2f0caf113d196f1a81ef3e714f51bed59a0ba1998fde9fdb3013970dc39aa0ab338fca3de34143e18b485", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/02d78cb4009ba13f6ca2f7bd62defbbe03b20b254d2f620f2c44a5311816b1dd96a742c1d9471a86eb852464cf469726396b6939b0c47df0daa9b187edf8b613"} 04:34:00 INFO - Running post-run listener: copy_logs_to_upload_dir 04:34:00 INFO - Copying logs to upload dir... 04:34:00 INFO - mkdir: /builds/slave/test/build/upload/logs 04:34:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1233.737571 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2016-07-17 04:33:59.386402) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-17 04:33:59.390331) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1468754004.100960-1862850072 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/f814adfd241db75ab5c2acc74787a6cc49f5cec639bae323c4d32776ef8af0f0273139981b950b89aa45fa8bc70d047f27b521ff125c0293683319180b918969", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d5c7c22f1545f4f49c5126ed88895f104dbc34f2afa2f0caf113d196f1a81ef3e714f51bed59a0ba1998fde9fdb3013970dc39aa0ab338fca3de34143e18b485", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/02d78cb4009ba13f6ca2f7bd62defbbe03b20b254d2f620f2c44a5311816b1dd96a742c1d9471a86eb852464cf469726396b6939b0c47df0daa9b187edf8b613"} build_url:https://queue.taskcluster.net/v1/task/EA_HwJUJQGyHCCBifwJd-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011214 build_url: 'https://queue.taskcluster.net/v1/task/EA_HwJUJQGyHCCBifwJd-g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/f814adfd241db75ab5c2acc74787a6cc49f5cec639bae323c4d32776ef8af0f0273139981b950b89aa45fa8bc70d047f27b521ff125c0293683319180b918969", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d5c7c22f1545f4f49c5126ed88895f104dbc34f2afa2f0caf113d196f1a81ef3e714f51bed59a0ba1998fde9fdb3013970dc39aa0ab338fca3de34143e18b485", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/02d78cb4009ba13f6ca2f7bd62defbbe03b20b254d2f620f2c44a5311816b1dd96a742c1d9471a86eb852464cf469726396b6939b0c47df0daa9b187edf8b613"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-17 04:33:59.419457) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 04:33:59.420198) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1468754004.100960-1862850072 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004635 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 04:33:59.502828) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-17 04:33:59.506215) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-17 04:33:59.506532) ========= ========= Total master_lag: 0.19 =========