builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-058 starttime: 1479020587.95 results: success (0) buildid: 20161112201920 builduid: 0c53cb301a4b4ff5b590a9ed824c4c3a revision: 950df3cfb7798d1c6b1f422457a0e398a2b74587 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-12 23:03:07.947984) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-12 23:03:07.948403) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-12 23:03:07.948709) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-12 23:03:08.025483) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-12 23:03:08.025781) ========= 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=0e2a256762fed810e2f0f810000001b3-1479020533.603996-940162487 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-11-12 23:03:08-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 217M=0s 2016-11-12 23:03:08 (217 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.183190 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-12 23:03:08.269837) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-12 23:03:08.270271) ========= 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=0e2a256762fed810e2f0f810000001b3-1479020533.603996-940162487 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.092328 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-12 23:03:08.377994) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-12 23:03:08.378343) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 950df3cfb7798d1c6b1f422457a0e398a2b74587 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 950df3cfb7798d1c6b1f422457a0e398a2b74587 --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=0e2a256762fed810e2f0f810000001b3-1479020533.603996-940162487 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-11-12 23:03:08,432 truncating revision to first 12 chars 2016-11-12 23:03:08,432 Setting DEBUG logging. 2016-11-12 23:03:08,432 attempt 1/10 2016-11-12 23:03:08,433 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/950df3cfb779?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-12 23:03:09,235 unpacking tar archive at: mozilla-aurora-950df3cfb779/testing/mozharness/ program finished with exit code 0 elapsedTime=1.143598 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-12 23:03:09.539684) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-12 23:03:09.540380) ========= script_repo_revision: 950df3cfb7798d1c6b1f422457a0e398a2b74587 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-12 23:03:09.540842) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-12 23:03:09.541192) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-12 23:03:09.552715) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-11-12 23:03:09.553149) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=0e2a256762fed810e2f0f810000001b3-1479020533.603996-940162487 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 23:03:09 INFO - MultiFileLogger online at 20161112 23:03:09 in /builds/slave/test 23:03:09 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 23:03:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:03:09 INFO - {'append_to_log': False, 23:03:09 INFO - 'base_work_dir': '/builds/slave/test', 23:03:09 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 23:03:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:03:09 INFO - 'branch': 'Mozilla-Aurora', 23:03:09 INFO - 'buildbot_json_path': 'buildprops.json', 23:03:09 INFO - 'config_files': ('talos/linux_config.py',), 23:03:09 INFO - 'default_actions': ('clobber', 23:03:09 INFO - 'read-buildbot-config', 23:03:09 INFO - 'download-and-extract', 23:03:09 INFO - 'populate-webroot', 23:03:09 INFO - 'create-virtualenv', 23:03:09 INFO - 'install', 23:03:09 INFO - 'run-tests'), 23:03:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:03:09 INFO - 'download_minidump_stackwalk': True, 23:03:09 INFO - 'download_symbols': 'ondemand', 23:03:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:03:09 INFO - 'tooltool.py': '/tools/tooltool.py', 23:03:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:03:09 INFO - '/tools/misc-python/virtualenv.py')}, 23:03:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:03:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:03:09 INFO - 'installer_path': 'installer.exe', 23:03:09 INFO - 'log_level': 'info', 23:03:09 INFO - 'log_name': 'talos', 23:03:09 INFO - 'log_to_console': True, 23:03:09 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 23:03:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 23:03:09 INFO - 'opt_config_files': (), 23:03:09 INFO - 'pip_index': False, 23:03:09 INFO - 'sps_profile': False, 23:03:09 INFO - 'sps_profile_interval': 0, 23:03:09 INFO - 'suite': 'dromaeojs', 23:03:09 INFO - 'system_bits': '32', 23:03:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:03:09 INFO - 'title': 'talos-linux64-ix-058', 23:03:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:03:09 INFO - 'use_talos_json': True, 23:03:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:03:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:03:09 INFO - 'work_dir': 'build'} 23:03:09 INFO - [mozharness: 2016-11-13 07:03:09.820817Z] Running clobber step. 23:03:09 INFO - Running pre-action listener: _resource_record_pre_action 23:03:09 INFO - Running main action method: clobber 23:03:09 INFO - rmtree: /builds/slave/test/build 23:03:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:03:10 INFO - Running post-action listener: _resource_record_post_action 23:03:10 INFO - [mozharness: 2016-11-13 07:03:10.757645Z] Finished clobber step (success) 23:03:10 INFO - [mozharness: 2016-11-13 07:03:10.757771Z] Running read-buildbot-config step. 23:03:10 INFO - Running pre-action listener: _resource_record_pre_action 23:03:10 INFO - Running main action method: read_buildbot_config 23:03:10 INFO - Using buildbot properties: 23:03:10 INFO - { 23:03:10 INFO - "project": "", 23:03:10 INFO - "product": "firefox", 23:03:10 INFO - "script_repo_revision": "production", 23:03:10 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 23:03:10 INFO - "repository": "", 23:03:10 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 23:03:10 INFO - "buildid": "20161112201920", 23:03:10 INFO - "pgo_build": "True", 23:03:10 INFO - "basedir": "/builds/slave/test", 23:03:10 INFO - "buildnumber": 618, 23:03:10 INFO - "slavename": "talos-linux64-ix-058", 23:03:10 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 23:03:10 INFO - "platform": "ubuntu64_hw", 23:03:10 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 23:03:10 INFO - "branch": "mozilla-aurora", 23:03:10 INFO - "repo_path": "releases/mozilla-aurora", 23:03:10 INFO - "revision": "950df3cfb7798d1c6b1f422457a0e398a2b74587", 23:03:10 INFO - "stage_platform": "linux64-pgo", 23:03:10 INFO - "builduid": "0c53cb301a4b4ff5b590a9ed824c4c3a", 23:03:10 INFO - "slavebuilddir": "test" 23:03:10 INFO - } 23:03:10 INFO - Found installer url https://queue.taskcluster.net/v1/task/E_1Ylpp7Scy-axU0GHGprw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2. 23:03:10 INFO - Running post-action listener: _resource_record_post_action 23:03:10 INFO - [mozharness: 2016-11-13 07:03:10.761039Z] Finished read-buildbot-config step (success) 23:03:10 INFO - [mozharness: 2016-11-13 07:03:10.761153Z] Running download-and-extract step. 23:03:10 INFO - Running pre-action listener: _resource_record_pre_action 23:03:10 INFO - Running main action method: download_and_extract 23:03:10 INFO - mkdir: /builds/slave/test/build/tests 23:03:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:03:10 INFO - trying https://queue.taskcluster.net/v1/task/E_1Ylpp7Scy-axU0GHGprw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 23:03:10 INFO - Downloading https://queue.taskcluster.net/v1/task/E_1Ylpp7Scy-axU0GHGprw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 23:03:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/E_1Ylpp7Scy-axU0GHGprw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 23:03:11 INFO - Downloaded 1361 bytes. 23:03:11 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 23:03:11 INFO - Using the following test package requirements: 23:03:11 INFO - {u'common': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 23:03:11 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 23:03:11 INFO - u'firefox-51.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 23:03:11 INFO - u'gtest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 23:03:11 INFO - u'firefox-51.0a2.en-US.linux-x86_64.gtest.tests.zip'], 23:03:11 INFO - u'jittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 23:03:11 INFO - u'jsshell-linux-x86_64.zip'], 23:03:11 INFO - u'mochitest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 23:03:11 INFO - u'firefox-51.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 23:03:11 INFO - u'mozbase': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 23:03:11 INFO - u'reftest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 23:03:11 INFO - u'firefox-51.0a2.en-US.linux-x86_64.reftest.tests.zip'], 23:03:11 INFO - u'talos': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 23:03:11 INFO - u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'], 23:03:11 INFO - u'web-platform': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 23:03:11 INFO - u'firefox-51.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 23:03:11 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 23:03:11 INFO - u'firefox-51.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 23:03:11 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 23:03:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/E_1Ylpp7Scy-axU0GHGprw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 23:03:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/E_1Ylpp7Scy-axU0GHGprw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 23:03:11 INFO - Fetch https://queue.taskcluster.net/v1/task/E_1Ylpp7Scy-axU0GHGprw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 23:03:11 INFO - Expected file size: 21962988 23:03:16 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 23:03:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/E_1Ylpp7Scy-axU0GHGprw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 23:03:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/E_1Ylpp7Scy-axU0GHGprw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 23:03:16 INFO - Fetch https://queue.taskcluster.net/v1/task/E_1Ylpp7Scy-axU0GHGprw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 23:03:16 INFO - Expected file size: 21962988 23:03:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/E_1Ylpp7Scy-axU0GHGprw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip 23:03:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/E_1Ylpp7Scy-axU0GHGprw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 23:03:21 INFO - Fetch https://queue.taskcluster.net/v1/task/E_1Ylpp7Scy-axU0GHGprw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip into memory 23:03:22 INFO - Expected file size: 14414089 23:03:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:03:23 INFO - trying https://queue.taskcluster.net/v1/task/E_1Ylpp7Scy-axU0GHGprw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 23:03:23 INFO - Downloading https://queue.taskcluster.net/v1/task/E_1Ylpp7Scy-axU0GHGprw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 23:03:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/E_1Ylpp7Scy-axU0GHGprw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 23:03:24 INFO - Downloaded 64060872 bytes. 23:03:24 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/E_1Ylpp7Scy-axU0GHGprw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 23:03:24 INFO - mkdir: /builds/slave/test/properties 23:03:24 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:03:24 INFO - Writing to file /builds/slave/test/properties/build_url 23:03:24 INFO - Contents: 23:03:24 INFO - build_url:https://queue.taskcluster.net/v1/task/E_1Ylpp7Scy-axU0GHGprw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 23:03:24 INFO - Running post-action listener: _resource_record_post_action 23:03:24 INFO - Running post-action listener: set_extra_try_arguments 23:03:24 INFO - [mozharness: 2016-11-13 07:03:24.956129Z] Finished download-and-extract step (success) 23:03:24 INFO - [mozharness: 2016-11-13 07:03:24.956361Z] Running populate-webroot step. 23:03:24 INFO - Running pre-action listener: _resource_record_pre_action 23:03:24 INFO - Running main action method: populate_webroot 23:03:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:03:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:03:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:03:24 INFO - '--tppagecycles', 23:03:24 INFO - '7'], 23:03:24 INFO - 'tests': ['tsvgm']}}, 23:03:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:03:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:03:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:03:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:03:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:03:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:03:24 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:03:24 INFO - 'talos_options': ['--disable-e10s'], 23:03:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:03:24 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:03:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:03:24 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:03:24 INFO - 'talos_options': ['--disable-e10s'], 23:03:24 INFO - 'tests': ['damp', 'tps']}, 23:03:24 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:03:24 INFO - 'tests': ['damp', 'tps']}, 23:03:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:03:24 INFO - 'tests': ['dromaeo_dom']}, 23:03:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:03:24 INFO - 'g4': {'talos_options': ['--disable-e10s'], 23:03:24 INFO - 'tests': ['basic_compositor_video']}, 23:03:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 23:03:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:03:24 INFO - 'tests': ['a11yr', 23:03:24 INFO - 'ts_paint', 23:03:24 INFO - 'tpaint', 23:03:24 INFO - 'sessionrestore', 23:03:24 INFO - 'sessionrestore_no_auto_restore', 23:03:24 INFO - 'tabpaint']}, 23:03:24 INFO - 'other-e10s': {'tests': ['a11yr', 23:03:24 INFO - 'ts_paint', 23:03:24 INFO - 'tpaint', 23:03:24 INFO - 'sessionrestore', 23:03:24 INFO - 'sessionrestore_no_auto_restore', 23:03:24 INFO - 'tabpaint']}, 23:03:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:03:24 INFO - 'ts_paint', 23:03:24 INFO - 'tpaint', 23:03:24 INFO - 'sessionrestore', 23:03:24 INFO - 'sessionrestore_no_auto_restore', 23:03:24 INFO - 'tabpaint']}, 23:03:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:03:24 INFO - 'ts_paint', 23:03:24 INFO - 'tpaint', 23:03:24 INFO - 'sessionrestore', 23:03:24 INFO - 'sessionrestore_no_auto_restore', 23:03:24 INFO - 'tabpaint']}, 23:03:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:03:24 INFO - 'tests': ['a11yr', 23:03:24 INFO - 'ts_paint', 23:03:24 INFO - 'tpaint', 23:03:24 INFO - 'sessionrestore', 23:03:24 INFO - 'sessionrestore_no_auto_restore', 23:03:24 INFO - 'tabpaint']}, 23:03:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:03:24 INFO - 'tests': ['a11yr', 23:03:24 INFO - 'ts_paint', 23:03:24 INFO - 'tpaint', 23:03:24 INFO - 'sessionrestore', 23:03:24 INFO - 'sessionrestore_no_auto_restore', 23:03:24 INFO - 'tabpaint']}, 23:03:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:03:24 INFO - 'tests': ['tsvgx', 23:03:24 INFO - 'tsvgr_opacity', 23:03:24 INFO - 'tart', 23:03:24 INFO - 'tscrollx', 23:03:24 INFO - 'cart']}, 23:03:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:03:24 INFO - 'tsvgr_opacity', 23:03:24 INFO - 'tart', 23:03:24 INFO - 'tscrollx', 23:03:24 INFO - 'cart']}, 23:03:24 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:03:24 INFO - 'talos_options': ['--disable-e10s'], 23:03:24 INFO - 'tests': ['tp5o']}, 23:03:24 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:03:24 INFO - 'tests': ['tp5o']}, 23:03:24 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:03:24 INFO - 'talos_options': ['--disable-e10s', 23:03:24 INFO - '--xperf_path', 23:03:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:03:24 INFO - 'tests': ['tp5n']}, 23:03:24 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:03:24 INFO - 'talos_options': ['--xperf_path', 23:03:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:03:24 INFO - 'tests': ['tp5n']}}, 23:03:24 INFO - 'talos.zip': {'path': '', 23:03:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:03:24 INFO - Running post-action listener: _resource_record_post_action 23:03:24 INFO - [mozharness: 2016-11-13 07:03:24.978998Z] Finished populate-webroot step (success) 23:03:24 INFO - [mozharness: 2016-11-13 07:03:24.979087Z] Running create-virtualenv step. 23:03:24 INFO - Running pre-action listener: _resource_record_pre_action 23:03:24 INFO - Running main action method: create_virtualenv 23:03:24 INFO - Creating virtualenv /builds/slave/test/build/venv 23:03:24 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 23:03:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:03:25 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:03:25 INFO - Using real prefix '/usr' 23:03:25 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:03:25 INFO - Installing distribute.............................................................................................................................................................................................done. 23:03:27 INFO - Installing pip.................done. 23:03:27 INFO - Return code: 0 23:03:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:03: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:03:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:03:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:03: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:03:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:03:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:03:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f6007e71f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007f60>, '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 0x1008d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10091d0>, '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': '0e2a256762fed810e2f0f810000001b3-1479020533.603996-940162487', '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 23:03:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 23:03:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 23:03:27 INFO - Using env: {'DISPLAY': ':0', 23:03:27 INFO - 'HOME': '/home/cltbld', 23:03:27 INFO - 'LANG': 'en_US.UTF-8', 23:03:27 INFO - 'LANGUAGE': 'en_US:en', 23:03:27 INFO - 'LOGNAME': 'cltbld', 23:03:27 INFO - 'MAIL': '/var/mail/cltbld', 23:03:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:03:27 INFO - 'MOZ_NO_REMOTE': '1', 23:03:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:03:27 INFO - 'NO_EM_RESTART': '1', 23:03:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:03:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:03:27 INFO - 'PWD': '/builds/slave/test', 23:03:27 INFO - 'SHELL': '/bin/bash', 23:03:27 INFO - 'SHLVL': '1', 23:03:27 INFO - 'TERM': 'linux', 23:03:27 INFO - 'TMOUT': '86400', 23:03:27 INFO - 'USER': 'cltbld', 23:03:27 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1479020533.603996-940162487', 23:03:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:03:27 INFO - '_': '/tools/buildbot/bin/python'} 23:03:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:03:28 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 23:03:28 INFO - Cleaning up... 23:03:28 INFO - Return code: 0 23:03:28 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:03: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:03:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:03:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:03: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:03:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:03:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:03:28 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 0x7f6007e71f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007f60>, '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 0x1008d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10091d0>, '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': '0e2a256762fed810e2f0f810000001b3-1479020533.603996-940162487', '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 23:03:28 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 23:03:28 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 23:03:28 INFO - Using env: {'DISPLAY': ':0', 23:03:28 INFO - 'HOME': '/home/cltbld', 23:03:28 INFO - 'LANG': 'en_US.UTF-8', 23:03:28 INFO - 'LANGUAGE': 'en_US:en', 23:03:28 INFO - 'LOGNAME': 'cltbld', 23:03:28 INFO - 'MAIL': '/var/mail/cltbld', 23:03:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:03:28 INFO - 'MOZ_NO_REMOTE': '1', 23:03:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:03:28 INFO - 'NO_EM_RESTART': '1', 23:03:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:03:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:03:28 INFO - 'PWD': '/builds/slave/test', 23:03:28 INFO - 'SHELL': '/bin/bash', 23:03:28 INFO - 'SHLVL': '1', 23:03:28 INFO - 'TERM': 'linux', 23:03:28 INFO - 'TMOUT': '86400', 23:03:28 INFO - 'USER': 'cltbld', 23:03:28 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1479020533.603996-940162487', 23:03:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:03:28 INFO - '_': '/tools/buildbot/bin/python'} 23:03:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:03:28 INFO - Downloading/unpacking psutil>=3.1.1 23:03:28 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 23:03:28 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 23:03:28 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 23:03:28 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 23:03:28 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 23:03:28 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 23:03:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:03:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:03:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:03:30 INFO - Installing collected packages: psutil 23:03:30 INFO - Running setup.py install for psutil 23:03:30 INFO - building 'psutil._psutil_linux' extension 23:03:30 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 23:03:31 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 23:03:31 INFO - building 'psutil._psutil_posix' extension 23:03:31 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 23:03:31 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 23:03:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:03:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:03:31 INFO - Successfully installed psutil 23:03:31 INFO - Cleaning up... 23:03:31 INFO - Return code: 0 23:03:31 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:03:31 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:03:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:03:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:03:31 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:03:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:03:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:03:31 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 0x7f6007e71f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007f60>, '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 0x1008d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10091d0>, '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': '0e2a256762fed810e2f0f810000001b3-1479020533.603996-940162487', '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 23:03:31 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 23:03:31 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 23:03:31 INFO - Using env: {'DISPLAY': ':0', 23:03:31 INFO - 'HOME': '/home/cltbld', 23:03:31 INFO - 'LANG': 'en_US.UTF-8', 23:03:31 INFO - 'LANGUAGE': 'en_US:en', 23:03:31 INFO - 'LOGNAME': 'cltbld', 23:03:31 INFO - 'MAIL': '/var/mail/cltbld', 23:03:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:03:31 INFO - 'MOZ_NO_REMOTE': '1', 23:03:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:03:31 INFO - 'NO_EM_RESTART': '1', 23:03:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:03:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:03:31 INFO - 'PWD': '/builds/slave/test', 23:03:31 INFO - 'SHELL': '/bin/bash', 23:03:31 INFO - 'SHLVL': '1', 23:03:31 INFO - 'TERM': 'linux', 23:03:31 INFO - 'TMOUT': '86400', 23:03:31 INFO - 'USER': 'cltbld', 23:03:31 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1479020533.603996-940162487', 23:03:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:03:31 INFO - '_': '/tools/buildbot/bin/python'} 23:03:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:03:31 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:03:31 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 23:03:31 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 23:03:31 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 23:03:31 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 23:03:31 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 23:03:31 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 23:03:33 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:03:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:03:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:03:33 INFO - Installing collected packages: mozsystemmonitor 23:03:33 INFO - Running setup.py install for mozsystemmonitor 23:03:34 INFO - Successfully installed mozsystemmonitor 23:03:34 INFO - Cleaning up... 23:03:34 INFO - Return code: 0 23:03:34 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:03: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:03:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:03:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:03: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:03:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:03:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:03:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f6007e71f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007f60>, '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 0x1008d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10091d0>, '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': '0e2a256762fed810e2f0f810000001b3-1479020533.603996-940162487', '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 23:03:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 23:03:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 23:03:34 INFO - Using env: {'DISPLAY': ':0', 23:03:34 INFO - 'HOME': '/home/cltbld', 23:03:34 INFO - 'LANG': 'en_US.UTF-8', 23:03:34 INFO - 'LANGUAGE': 'en_US:en', 23:03:34 INFO - 'LOGNAME': 'cltbld', 23:03:34 INFO - 'MAIL': '/var/mail/cltbld', 23:03:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:03:34 INFO - 'MOZ_NO_REMOTE': '1', 23:03:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:03:34 INFO - 'NO_EM_RESTART': '1', 23:03:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:03:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:03:34 INFO - 'PWD': '/builds/slave/test', 23:03:34 INFO - 'SHELL': '/bin/bash', 23:03:34 INFO - 'SHLVL': '1', 23:03:34 INFO - 'TERM': 'linux', 23:03:34 INFO - 'TMOUT': '86400', 23:03:34 INFO - 'USER': 'cltbld', 23:03:34 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1479020533.603996-940162487', 23:03:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:03:34 INFO - '_': '/tools/buildbot/bin/python'} 23:03:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:03:34 INFO - Downloading/unpacking jsonschema==2.5.1 23:03:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:03:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:03:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:03:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:03:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:03:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:03:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:03:36 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:03:36 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 23:03:36 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 23:03:36 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 23:03:36 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 23:03:36 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 23:03:36 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 23:03:36 INFO - Downloading functools32-3.2.3-2.tar.gz 23:03:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:03:36 INFO - warning: no files found matching '*.txt' 23:03:36 INFO - no previously-included directories found matching 'build' 23:03:36 INFO - no previously-included directories found matching 'dist' 23:03:36 INFO - no previously-included directories found matching '.git*' 23:03:36 INFO - Installing collected packages: jsonschema, functools32 23:03:36 INFO - Running setup.py install for functools32 23:03:36 INFO - warning: no files found matching '*.txt' 23:03:36 INFO - no previously-included directories found matching 'build' 23:03:36 INFO - no previously-included directories found matching 'dist' 23:03:36 INFO - no previously-included directories found matching '.git*' 23:03:36 INFO - Successfully installed jsonschema functools32 23:03:36 INFO - Cleaning up... 23:03:36 INFO - Return code: 0 23:03:36 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 23:03:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:03:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:03:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:03:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:03:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:03:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:03:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f6007e71f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007f60>, '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 0x1008d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10091d0>, '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': '0e2a256762fed810e2f0f810000001b3-1479020533.603996-940162487', '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 23:03:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 23:03:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 23:03:36 INFO - Using env: {'DISPLAY': ':0', 23:03:36 INFO - 'HOME': '/home/cltbld', 23:03:36 INFO - 'LANG': 'en_US.UTF-8', 23:03:36 INFO - 'LANGUAGE': 'en_US:en', 23:03:36 INFO - 'LOGNAME': 'cltbld', 23:03:36 INFO - 'MAIL': '/var/mail/cltbld', 23:03:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:03:36 INFO - 'MOZ_NO_REMOTE': '1', 23:03:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:03:36 INFO - 'NO_EM_RESTART': '1', 23:03:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:03:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:03:36 INFO - 'PWD': '/builds/slave/test', 23:03:36 INFO - 'SHELL': '/bin/bash', 23:03:36 INFO - 'SHLVL': '1', 23:03:36 INFO - 'TERM': 'linux', 23:03:36 INFO - 'TMOUT': '86400', 23:03:36 INFO - 'USER': 'cltbld', 23:03:36 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1479020533.603996-940162487', 23:03:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:03:36 INFO - '_': '/tools/buildbot/bin/python'} 23:03:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:03:36 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 23:03:36 INFO - Cleaning up... 23:03:36 INFO - Return code: 0 23:03:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:03:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:03:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:03:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:03:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:03:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:03:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:03:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f6007e71f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007f60>, '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 0x1008d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10091d0>, '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': '0e2a256762fed810e2f0f810000001b3-1479020533.603996-940162487', '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 23:03:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 23:03:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 23:03:36 INFO - Using env: {'DISPLAY': ':0', 23:03:36 INFO - 'HOME': '/home/cltbld', 23:03:36 INFO - 'LANG': 'en_US.UTF-8', 23:03:36 INFO - 'LANGUAGE': 'en_US:en', 23:03:36 INFO - 'LOGNAME': 'cltbld', 23:03:36 INFO - 'MAIL': '/var/mail/cltbld', 23:03:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:03:36 INFO - 'MOZ_NO_REMOTE': '1', 23:03:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:03:36 INFO - 'NO_EM_RESTART': '1', 23:03:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:03:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:03:36 INFO - 'PWD': '/builds/slave/test', 23:03:36 INFO - 'SHELL': '/bin/bash', 23:03:36 INFO - 'SHLVL': '1', 23:03:36 INFO - 'TERM': 'linux', 23:03:36 INFO - 'TMOUT': '86400', 23:03:36 INFO - 'USER': 'cltbld', 23:03:36 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1479020533.603996-940162487', 23:03:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:03:36 INFO - '_': '/tools/buildbot/bin/python'} 23:03:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:03:37 INFO - Downloading/unpacking blobuploader==1.2.4 23:03:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:03:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:03:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:03:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:03:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:03:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:03:39 INFO - Downloading blobuploader-1.2.4.tar.gz 23:03:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:03:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:03: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 23:03: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 23:03: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 23:03: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 23:03: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 23:03: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 23:03:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:03:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:03: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 23:03: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 23:03: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 23:03: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 23:03: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 23:03: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 23:03:39 INFO - Downloading docopt-0.6.1.tar.gz 23:03:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:03:39 INFO - Installing collected packages: blobuploader, requests, docopt 23:03:39 INFO - Running setup.py install for blobuploader 23:03:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:03:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:03:39 INFO - Running setup.py install for requests 23:03:40 INFO - Running setup.py install for docopt 23:03:40 INFO - Successfully installed blobuploader requests docopt 23:03:40 INFO - Cleaning up... 23:03:40 INFO - Return code: 0 23:03:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:03:40 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:03:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:03:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:03:40 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:03:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:03:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:03:40 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 0x7f6007e71f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007f60>, '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 0x1008d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10091d0>, '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': '0e2a256762fed810e2f0f810000001b3-1479020533.603996-940162487', '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 23:03:40 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 23:03:40 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 23:03:40 INFO - Using env: {'DISPLAY': ':0', 23:03:40 INFO - 'HOME': '/home/cltbld', 23:03:40 INFO - 'LANG': 'en_US.UTF-8', 23:03:40 INFO - 'LANGUAGE': 'en_US:en', 23:03:40 INFO - 'LOGNAME': 'cltbld', 23:03:40 INFO - 'MAIL': '/var/mail/cltbld', 23:03:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:03:40 INFO - 'MOZ_NO_REMOTE': '1', 23:03:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:03:40 INFO - 'NO_EM_RESTART': '1', 23:03:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:03:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:03:40 INFO - 'PWD': '/builds/slave/test', 23:03:40 INFO - 'SHELL': '/bin/bash', 23:03:40 INFO - 'SHLVL': '1', 23:03:40 INFO - 'TERM': 'linux', 23:03:40 INFO - 'TMOUT': '86400', 23:03:40 INFO - 'USER': 'cltbld', 23:03:40 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1479020533.603996-940162487', 23:03:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:03:40 INFO - '_': '/tools/buildbot/bin/python'} 23:03:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:03:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:03:40 INFO - Running setup.py (path:/tmp/pip-UfgYJH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:03:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:03:40 INFO - Running setup.py (path:/tmp/pip-SMJRUQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:03:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:03:40 INFO - Running setup.py (path:/tmp/pip-SinjQJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:03:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:03:40 INFO - Running setup.py (path:/tmp/pip-KpjZg9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:03:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:03:40 INFO - Running setup.py (path:/tmp/pip-7mCcEL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:03:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:03:41 INFO - Running setup.py (path:/tmp/pip-qiQVLJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:03:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:03:41 INFO - Running setup.py (path:/tmp/pip-EAvrTj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:03:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:03:41 INFO - Running setup.py (path:/tmp/pip-fcUlKb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:03:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:03:41 INFO - Running setup.py (path:/tmp/pip-gJMmRw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:03:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:03:41 INFO - Running setup.py (path:/tmp/pip-VEVMGX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:03:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:03:41 INFO - Running setup.py (path:/tmp/pip-pnUqrS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:03:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:03:41 INFO - Running setup.py (path:/tmp/pip-dF8gOk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:03:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:03:41 INFO - Running setup.py (path:/tmp/pip-MFp23E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:03:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:03:41 INFO - Running setup.py (path:/tmp/pip-Ftjdrn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:03:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:03:41 INFO - Running setup.py (path:/tmp/pip-Xfxsw2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:03:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:03:41 INFO - Running setup.py (path:/tmp/pip-8_4mDZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:03:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:03:41 INFO - Running setup.py (path:/tmp/pip-Na0sdn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:03:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:03:41 INFO - Running setup.py install for manifestparser 23:03:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:03:42 INFO - Running setup.py install for mozcrash 23:03:42 INFO - Running setup.py install for mozdebug 23:03:42 INFO - Running setup.py install for mozdevice 23:03:42 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:03:42 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:03:42 INFO - Running setup.py install for mozfile 23:03:42 INFO - Running setup.py install for mozhttpd 23:03:42 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:03:42 INFO - Running setup.py install for mozinfo 23:03:42 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:03:42 INFO - Running setup.py install for mozInstall 23:03:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:03:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:03:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:03:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:03:42 INFO - Running setup.py install for mozleak 23:03:42 INFO - Running setup.py install for mozlog 23:03:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:03:42 INFO - Running setup.py install for moznetwork 23:03:43 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:03:43 INFO - Running setup.py install for mozprocess 23:03:43 INFO - Running setup.py install for mozprofile 23:03:43 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:03:43 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:03:43 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:03:43 INFO - Running setup.py install for mozrunner 23:03:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:03:43 INFO - Running setup.py install for mozscreenshot 23:03:43 INFO - Running setup.py install for moztest 23:03:43 INFO - Running setup.py install for mozversion 23:03:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:03:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:03:43 INFO - Cleaning up... 23:03:43 INFO - Return code: 0 23:03:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:03:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:03:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:03:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:03:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:03:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:03:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:03:43 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 0x7f6007e71f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007f60>, '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 0x1008d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10091d0>, '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': '0e2a256762fed810e2f0f810000001b3-1479020533.603996-940162487', '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 23:03:43 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 23:03:43 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 23:03:43 INFO - Using env: {'DISPLAY': ':0', 23:03:43 INFO - 'HOME': '/home/cltbld', 23:03:43 INFO - 'LANG': 'en_US.UTF-8', 23:03:43 INFO - 'LANGUAGE': 'en_US:en', 23:03:43 INFO - 'LOGNAME': 'cltbld', 23:03:43 INFO - 'MAIL': '/var/mail/cltbld', 23:03:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:03:43 INFO - 'MOZ_NO_REMOTE': '1', 23:03:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:03:43 INFO - 'NO_EM_RESTART': '1', 23:03:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:03:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:03:43 INFO - 'PWD': '/builds/slave/test', 23:03:43 INFO - 'SHELL': '/bin/bash', 23:03:43 INFO - 'SHLVL': '1', 23:03:43 INFO - 'TERM': 'linux', 23:03:43 INFO - 'TMOUT': '86400', 23:03:43 INFO - 'USER': 'cltbld', 23:03:43 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1479020533.603996-940162487', 23:03:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:03:43 INFO - '_': '/tools/buildbot/bin/python'} 23:03:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:03:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:03:43 INFO - Running setup.py (path:/tmp/pip-NefCEk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:03:44 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)) 23:03:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:03:44 INFO - Running setup.py (path:/tmp/pip-kFnuNi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:03:44 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)) 23:03:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:03:44 INFO - Running setup.py (path:/tmp/pip-ZRvSSL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:03:44 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)) 23:03:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:03:44 INFO - Running setup.py (path:/tmp/pip-mURKGl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:03:44 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)) 23:03:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:03:44 INFO - Running setup.py (path:/tmp/pip-zsP3bQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:03:44 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)) 23:03:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:03:44 INFO - Running setup.py (path:/tmp/pip-BErxr_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:03:44 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)) 23:03:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:03:44 INFO - Running setup.py (path:/tmp/pip-U3N9Kh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:03:44 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)) 23:03:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:03:44 INFO - Running setup.py (path:/tmp/pip-IRYc94-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:03:44 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)) 23:03:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:03:44 INFO - Running setup.py (path:/tmp/pip-B_t_4v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:03:44 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)) 23:03:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:03:44 INFO - Running setup.py (path:/tmp/pip-Xllpqc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:03:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 23:03:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:03:44 INFO - Running setup.py (path:/tmp/pip-h7snD7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:03:44 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)) 23:03:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:03:44 INFO - Running setup.py (path:/tmp/pip-fgMo0e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:03:44 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)) 23:03:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:03:44 INFO - Running setup.py (path:/tmp/pip-Of8Da7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:03:44 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)) 23:03:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:03:44 INFO - Running setup.py (path:/tmp/pip-HdzeBy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:03:44 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)) 23:03:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:03:44 INFO - Running setup.py (path:/tmp/pip-3nsIvX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:03:45 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)) 23:03:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:03:45 INFO - Running setup.py (path:/tmp/pip-Oyso4L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:03:45 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)) 23:03:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:03:45 INFO - Running setup.py (path:/tmp/pip-oFK8Bf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:03:45 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)) 23:03:45 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)) 23:03:45 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)) 23:03:45 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)) 23:03:45 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)) 23:03:45 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)) 23:03:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:03:45 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 23:03:45 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 23:03:45 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 23:03:45 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 23:03:45 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 23:03:45 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 23:03:47 INFO - Downloading blessings-1.6.tar.gz 23:03:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:03:47 INFO - Installing collected packages: blessings 23:03:47 INFO - Running setup.py install for blessings 23:03:47 INFO - Successfully installed blessings 23:03:47 INFO - Cleaning up... 23:03:47 INFO - Return code: 0 23:03:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:03:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:03:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:03:47 INFO - Reading from file tmpfile_stdout 23:03:47 INFO - Current package versions: 23:03:47 INFO - argparse == 1.2.1 23:03:47 INFO - blessings == 1.6 23:03:47 INFO - blobuploader == 1.2.4 23:03:47 INFO - docopt == 0.6.1 23:03:47 INFO - functools32 == 3.2.3-2 23:03:47 INFO - jsonschema == 2.5.1 23:03:47 INFO - manifestparser == 1.1 23:03:47 INFO - mozInstall == 1.12 23:03:47 INFO - mozcrash == 0.17 23:03:47 INFO - mozdebug == 0.1 23:03:47 INFO - mozdevice == 0.48 23:03:47 INFO - mozfile == 1.2 23:03:47 INFO - mozhttpd == 0.7 23:03:47 INFO - mozinfo == 0.9 23:03:47 INFO - mozleak == 0.1 23:03:47 INFO - mozlog == 3.4 23:03:47 INFO - moznetwork == 0.27 23:03:47 INFO - mozprocess == 0.23 23:03:47 INFO - mozprofile == 0.28 23:03:47 INFO - mozrunner == 6.12 23:03:47 INFO - mozscreenshot == 0.1 23:03:47 INFO - mozsystemmonitor == 0.3 23:03:47 INFO - moztest == 0.7 23:03:47 INFO - mozversion == 1.4 23:03:47 INFO - psutil == 3.1.1 23:03:47 INFO - requests == 1.2.3 23:03:47 INFO - wsgiref == 0.1.2 23:03:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:03:47 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:03:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:03:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:03:47 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:03:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:03:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:03:47 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 0x7f6007e71f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007f60>, '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 0x1008d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10091d0>, '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': '0e2a256762fed810e2f0f810000001b3-1479020533.603996-940162487', '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 23:03:47 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 23:03:47 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 23:03:47 INFO - Using env: {'DISPLAY': ':0', 23:03:47 INFO - 'HOME': '/home/cltbld', 23:03:47 INFO - 'LANG': 'en_US.UTF-8', 23:03:47 INFO - 'LANGUAGE': 'en_US:en', 23:03:47 INFO - 'LOGNAME': 'cltbld', 23:03:47 INFO - 'MAIL': '/var/mail/cltbld', 23:03:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:03:47 INFO - 'MOZ_NO_REMOTE': '1', 23:03:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:03:47 INFO - 'NO_EM_RESTART': '1', 23:03:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:03:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:03:47 INFO - 'PWD': '/builds/slave/test', 23:03:47 INFO - 'SHELL': '/bin/bash', 23:03:47 INFO - 'SHLVL': '1', 23:03:47 INFO - 'TERM': 'linux', 23:03:47 INFO - 'TMOUT': '86400', 23:03:47 INFO - 'USER': 'cltbld', 23:03:47 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1479020533.603996-940162487', 23:03:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:03:47 INFO - '_': '/tools/buildbot/bin/python'} 23:03:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:03:47 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)) 23:03:47 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)) 23:03:47 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)) 23:03:47 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)) 23:03:47 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)) 23:03:47 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)) 23:03:47 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)) 23:03:47 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)) 23:03:47 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)) 23:03:47 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)) 23:03:47 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)) 23:03:47 INFO - Cleaning up... 23:03:47 INFO - Return code: 0 23:03:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:03:47 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:03:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:03:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:03:47 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:03:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:03:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:03:47 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 0x7f6007e71f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007f60>, '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 0x1008d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10091d0>, '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': '0e2a256762fed810e2f0f810000001b3-1479020533.603996-940162487', '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 23:03:47 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 23:03:47 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 23:03:47 INFO - Using env: {'DISPLAY': ':0', 23:03:47 INFO - 'HOME': '/home/cltbld', 23:03:47 INFO - 'LANG': 'en_US.UTF-8', 23:03:47 INFO - 'LANGUAGE': 'en_US:en', 23:03:47 INFO - 'LOGNAME': 'cltbld', 23:03:47 INFO - 'MAIL': '/var/mail/cltbld', 23:03:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:03:47 INFO - 'MOZ_NO_REMOTE': '1', 23:03:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:03:47 INFO - 'NO_EM_RESTART': '1', 23:03:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:03:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:03:47 INFO - 'PWD': '/builds/slave/test', 23:03:47 INFO - 'SHELL': '/bin/bash', 23:03:47 INFO - 'SHLVL': '1', 23:03:47 INFO - 'TERM': 'linux', 23:03:47 INFO - 'TMOUT': '86400', 23:03:47 INFO - 'USER': 'cltbld', 23:03:47 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1479020533.603996-940162487', 23:03:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:03:47 INFO - '_': '/tools/buildbot/bin/python'} 23:03:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:03:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:03:48 INFO - Cleaning up... 23:03:48 INFO - Return code: 0 23:03:48 INFO - Running post-action listener: _resource_record_post_action 23:03:48 INFO - Running post-action listener: _start_resource_monitoring 23:03:48 INFO - Starting resource monitoring. 23:03:48 INFO - [mozharness: 2016-11-13 07:03:48.098757Z] Finished create-virtualenv step (success) 23:03:48 INFO - [mozharness: 2016-11-13 07:03:48.099680Z] Running install step. 23:03:48 INFO - Running pre-action listener: _resource_record_pre_action 23:03:48 INFO - Running main action method: install 23:03:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:03:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:03:48 INFO - Reading from file tmpfile_stdout 23:03:48 INFO - Detecting whether we're running mozinstall >=1.0... 23:03:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:03:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:03:48 INFO - Reading from file tmpfile_stdout 23:03:48 INFO - Output received: 23:03:48 INFO - Usage: mozinstall [options] installer 23:03:48 INFO - Options: 23:03:48 INFO - -h, --help show this help message and exit 23:03:48 INFO - -d DEST, --destination=DEST 23:03:48 INFO - Directory to install application into. [default: 23:03:48 INFO - "/builds/slave/test"] 23:03:48 INFO - --app=APP Application being installed. [default: firefox] 23:03:48 INFO - mkdir: /builds/slave/test/build/application 23:03:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 23:03:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 23:04:04 INFO - Reading from file tmpfile_stdout 23:04:04 INFO - Output received: 23:04:04 INFO - /builds/slave/test/build/application/firefox/firefox 23:04:04 INFO - Running post-action listener: _resource_record_post_action 23:04:04 INFO - [mozharness: 2016-11-13 07:04:04.521116Z] Finished install step (success) 23:04:04 INFO - [mozharness: 2016-11-13 07:04:04.521364Z] Running run-tests step. 23:04:04 INFO - Running pre-action listener: _resource_record_pre_action 23:04:04 INFO - Running main action method: run_tests 23:04:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:04:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:04:04 INFO - Python 2.7.3 23:04:04 INFO - Return code: 0 23:04:04 INFO - grabbing minidump binary from tooltool 23:04:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:04:04 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 0x1007f60>, '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 0x1008d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10091d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:04:04 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 23:04:04 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 23:04:04 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 23:04:04 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:04:04 INFO - Return code: 0 23:04:04 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 23:04:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:04:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:04:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 23:04:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:04:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/E_1Ylpp7Scy-axU0GHGprw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-058', '--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 23:04:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/E_1Ylpp7Scy-axU0GHGprw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-058 --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 23:04:04 INFO - Using env: {'DISPLAY': ':0', 23:04:04 INFO - 'HOME': '/home/cltbld', 23:04:04 INFO - 'LANG': 'en_US.UTF-8', 23:04:04 INFO - 'LANGUAGE': 'en_US:en', 23:04:04 INFO - 'LOGNAME': 'cltbld', 23:04:04 INFO - 'MAIL': '/var/mail/cltbld', 23:04:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:04:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 23:04:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:04:04 INFO - 'MOZ_NO_REMOTE': '1', 23:04:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:04:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:04:04 INFO - 'NO_EM_RESTART': '1', 23:04:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:04:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:04:04 INFO - 'PWD': '/builds/slave/test', 23:04:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:04:04 INFO - 'SHELL': '/bin/bash', 23:04:04 INFO - 'SHLVL': '1', 23:04:04 INFO - 'TERM': 'linux', 23:04:04 INFO - 'TMOUT': '86400', 23:04:04 INFO - 'USER': 'cltbld', 23:04:04 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1479020533.603996-940162487', 23:04:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:04:04 INFO - '_': '/tools/buildbot/bin/python'} 23:04:04 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/E_1Ylpp7Scy-axU0GHGprw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-058', '--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 23:04:05 INFO - mozversion application_buildid: 20161112201920 23:04:05 INFO - mozversion application_changeset: 950df3cfb7798d1c6b1f422457a0e398a2b74587 23:04:05 INFO - mozversion application_display_name: FirefoxDeveloperEdition 23:04:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:04:05 INFO - mozversion application_name: Firefox 23:04:05 INFO - mozversion application_remotingname: firefox-dev 23:04:05 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 23:04:05 INFO - mozversion application_vendor: Mozilla 23:04:05 INFO - mozversion application_version: 51.0a2 23:04:05 INFO - mozversion platform_buildid: 20161112201920 23:04:05 INFO - mozversion platform_changeset: 950df3cfb7798d1c6b1f422457a0e398a2b74587 23:04:05 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 23:04:05 INFO - mozversion platform_version: 51.0a2 23:04:05 INFO - using testdate: 1479020645 23:04:05 INFO - actual date: 1479020645 23:04:05 INFO - starting webserver on 'localhost:39414' 23:04:05 INFO - SUITE-START | Running 2 tests 23:04:05 INFO - TEST-START | dromaeo_css 23:04:05 INFO - operating with platform_type : linux_ 23:04:05 INFO - Initialising browser for dromaeo_css test... 23:04:05 INFO - TEST-INFO | started process 3298 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRDPCuN/profile http://localhost:39414/getInfo.html) 23:04:10 INFO - PROCESS | 3298 | __metrics Screen width/height:1600/1200 23:04:10 INFO - PROCESS | 3298 | colorDepth:24 23:04:10 INFO - PROCESS | 3298 | Browser inner width/height: 1024/697 23:04:10 INFO - PROCESS | 3298 | __metrics 23:04:10 INFO - PROCESS | 3298 | 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" 23:04:10 INFO - PROCESS | 3298 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:04:11 INFO - PROCESS | 3298 | console.error: PushService: 23:04:11 INFO - PROCESS | 3298 | stateChangeProcessEnqueue: Error transitioning state 23:04:11 INFO - PROCESS | 3298 | UnknownError 23:04:11 INFO - TEST-INFO | 3298: exit 0 23:04:11 INFO - Browser initialized. 23:04:11 INFO - Running cycle 1/1 for dromaeo_css test... 23:04:11 INFO - TEST-INFO | started process 3389 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRDPCuN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:04:23 INFO - PROCESS | 3389 | RSS: Main: 144769024 23:04:23 INFO - PROCESS | 3389 | 23:05:14 INFO - PROCESS | 3389 | 1479020714873 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] 23:07:24 INFO - PROCESS | 3389 | Cycle 1(1): loaded http://localhost:39414/tests/dromaeo/cssquery-dojo.html (next: http://localhost:39414/tests/dromaeo/cssquery-ext.html) 23:07:25 INFO - PROCESS | 3389 | RSS: Main: 242585600 23:07:25 INFO - PROCESS | 3389 | 23:10:27 INFO - PROCESS | 3389 | Cycle 1(1): loaded http://localhost:39414/tests/dromaeo/cssquery-ext.html (next: http://localhost:39414/tests/dromaeo/cssquery-jquery.html) 23:10:28 INFO - PROCESS | 3389 | RSS: Main: 227233792 23:10:28 INFO - PROCESS | 3389 | 23:13:30 INFO - PROCESS | 3389 | Cycle 1(1): loaded http://localhost:39414/tests/dromaeo/cssquery-jquery.html (next: http://localhost:39414/tests/dromaeo/cssquery-mootools.html) 23:13:30 INFO - PROCESS | 3389 | RSS: Main: 188203008 23:13:30 INFO - PROCESS | 3389 | 23:16:32 INFO - PROCESS | 3389 | Cycle 1(1): loaded http://localhost:39414/tests/dromaeo/cssquery-mootools.html (next: http://localhost:39414/tests/dromaeo/cssquery-prototype.html) 23:16:33 INFO - PROCESS | 3389 | RSS: Main: 221990912 23:16:33 INFO - PROCESS | 3389 | 23:19:36 INFO - PROCESS | 3389 | Cycle 1(1): loaded http://localhost:39414/tests/dromaeo/cssquery-prototype.html (next: http://localhost:39414/tests/dromaeo/cssquery-yui.html) 23:19:36 INFO - PROCESS | 3389 | RSS: Main: 176246784 23:19:36 INFO - PROCESS | 3389 | 23:22:39 INFO - PROCESS | 3389 | Cycle 1(1): loaded http://localhost:39414/tests/dromaeo/cssquery-yui.html (next: http://localhost:39414/tests/dromaeo/cssquery-dojo.html) 23:22:39 INFO - PROCESS | 3389 | RSS: Main: 265891840 23:22:39 INFO - PROCESS | 3389 | 23:22:39 INFO - PROCESS | 3389 | __start_tp_report 23:22:39 INFO - PROCESS | 3389 | _x_x_mozilla_page_load 23:22:39 INFO - PROCESS | 3389 | _x_x_mozilla_page_load_details 23:22:39 INFO - PROCESS | 3389 | |i|pagename|runs| 23:22:39 INFO - PROCESS | 3389 | |0;dojo.html;3129.3584815112904;3182.17263511487;3198.4082944655224;3223.226425657843;3237.0612522368515;5514.338279406472;5546.805861794838;5581.134924086763;5586.273784408027;5600.0759874620635;5556.361018482876;5617.494425501704;5618.550515958724;5623.775048998035;5649.6327738697055;5057.69653820772;5072.061668591304;5089.516495932899;5096.413912400066;5106.4638212987475;4936.876578085558;5038.2190760432195;5091.0581542414775;5101.132807422747;5126.5898728101665;4960.25596160577;5027.095122877899;5034.748262586856;5061.873453163682;5087.79648814047;5060.873478163038;5080.568151707091;5083;5177.715225662587;5184.351956005499;5008.924866127011;5075.98480303937;5122.359705036871;5122.462141475129;5136.203888397267;5330.413654498002;5335.866603334909;5383.300170977748;5398.190271459273;5454.91817622736;5743.45437183471;5757.510611598019;5761.827145185651;5934.228550288435;5963.0459126539545;5995.310539287951;6000.879982400327;6001.12983617373;6004.6997650117755;6007.128966299906;5317.654352467116;5357.142857142878;5391.137418013099;5505.587080968943;5520.861978450571;6050.031994880798;6100.23747031621;6104.481119104926;6105.786297479567;6108.633481991006;5867.677277749679;5875.089361149029;5885.676287804126;5890.617109887844;5893.587448878623;5707.486326230659;5739.655620662774;5744.51171650406;5784.21913041734;5801.622894511843;5805.071188609802;5822.825315240465;5822.82531524055;5825.359210486843;5860.736266868002;5778.855528611818;5819.185314056035;5826.650400975955;5829.15477255804;5849.532037436995;4378.715383500126;4428.357888106207;4445.599896009375;4517.683762136685;4519.751413672214;4037.1118353962083;4060.2285565742413;4087.019115412339;4089.284375234322;4108.301588729923;5504.697241651747;5505.311836020497;5516.282883225155;5516.641418307797;5518.834434966958;5730.541556675456;5784.1902133701315;5797.043487824461;5941.435563621449;5944.6730429826775;5414.133738601805;5524.4199359067525;5527.336719593674;5562.582806289463;5568.415316391721;5122.641415100908;5133.12736834739;5134.255532947702;5134.589232861362;5156.664816786897;5872.853178670482;5885.852853678693;6084.817455476343;6086.513078953762;6090.604110732788;5812.593118481665;5834.883302334015;5850;5867.031939729897;5880.26496687914;6009.489193418433;6010.368911264254;6013.098035294741;6017;6037.154798328325;5635.633683810539;5763.538916886555;5765.971170144123;5767.134929760569;5796.681182535001;5739.741711623071;5809.82570522885;5862.853428664317;5877.089051197071;5980.910286345621;5796.21751063601;5803;5805.883882322245;5807.622504537191;5808.593398462236;5751.4536119068625;5754.338251101177;5817.0111081115465;5828;5829.038208695518;5553.194786755978;5565.1095824667855;5689.146628005832;5722.198892155018;5743.1385292206505;5872.5301975842685;5873.676947767743;5877.970610147093;5880.382559831156;5899.646021238739;5440.156775699773;5743;5758.798442054508;5759.193712880284;5763.7118144093465 23:22:39 INFO - PROCESS | 3389 | |1;ext.html;593.6972144206457;626.8432891777056;640.8397900524869;651.5504302031595;670.7719375412358;36916;37619.43570846439;37629.05927351818;37632.8710138696;37674.811625941904;38055.23889522215;47038;48323;48381.27428088573;52500.73749631247;9391.859122113157;9543.47510886901;9550.665726699566;9554.18789402898;9579.281553883451;8382.119877412875;9357;9461.952690236574;9463.290253231035;9464.19554337882;8700.477971321709;11112.44437778112;11390.316581005125;11403.486843092081;11791.233569817978;11338.206325557214;11389;11409.885901141048;11412.14408919326;11417.257878237888;8365.581720913991;8694.956525217396;11176.105911527058;11241.381724005176;11267.492962816694;44833.55166448359;45482;45569.54430455686;48949.75525122387;48965.51034489645;3699.5190625218547;3738.7722693662736;3790.2419516096893;3833.6933045356304;4349.26062569364;33782.81760138408;34782.47826282608;34908.30183396343;34944.30111397783;34957.82521087378;4703.390041225528;4811.182099043136;4885.096257192431;4902.828401005948;4946.3817022872145;366665;367086;370178;374138;374913;57739.13391299134;58218;58312.70843645755;58691.11963320553;66271.66864165745;67928;68210;68318.65840670765;68418;68452;17193.82806171922;17591.38430154964;17835.643287134186;17846.643067138586;17850.5537361567;14053.297335133202;14062.507812226522;14068.788968165485;14084.929575352058;14123.223222722849;17877.642447150985;18263.54341141482;19288.710669339973;19890.204391824453;19898.60202795936;6589.209294884645;6601.339866013456;6627.668616569153;6665.366790154928;6700.93299067003;979.7844474215661;1014.5941623350719;1081.0540776820283;1110.9111271098293;1134.4951496584106;34384.484232736526;34653.48019779706;34800.30399391998;34842.65157348394;35271.82364088163;1189.2745425290566;1214.9757004859853;1217.7807994561065;1219.3781171602548;1258.5532136091738;18568.25726970906;18617.441476755717;18731.625367492576;18810.435686929413;18847;520137;523954;524685;524836;524969;63702.36297637057;64106.67946660237;64174.35825641777;64296.67851660712;64338;1313.487739781486;1360.5782207515701;1385.5704731533056;1437.6908964572458;1466.9193194374411;30871.84564077165;35617.82191089132;35664.82167589146;35977.926180669456;36087.45868811917;61955;64042;66683;66717;66720.6663966677;483798;493612;494887;495058;495571;48334.758326208146;49703.50296497133;49929.50070499248;50408.747956259984;50510.74744626253;52548.211776824144;52693.47306526886;53006.447743289544;53366.46633533615;54189.45810541844;44587.331190031524;45676.771616141705;46104;46612.76693616646;47549;46062.769686151354;46438.30342544932;46708.53291467042;46971.077486843875;47343.526564735286;46863;47341.28988065113;47857.5214247867;48050.27924581064;49443.75278123586;39482.80258598689;41608;41931;42117.57882421136;42320.365194522725;30977.225569360948;31291.374172515967;31429;31695.841520792248;31728.841355793073 23:22:39 INFO - PROCESS | 3389 | |2;jquery.html;389.9142188718481;426.9369270516413;434.96085352318306;437.5952244174139;440.37789183961917;16866.494005179848;20320;21199.258025969095;21581.46046348842;21848.12607495702;19196.61606767864;19765;19839.801601983974;19904.50238744032;19914.302999395026;5539.362973258067;5635.3094071778505;5752.108423194411;5756.856078598027;5766.654000759968;6009.1286763419275;6111.572189946705;6115.969420152893;6121.449069583737;6121.571489995702;5884.852878678046;6052.455279024887;6088.604240724361;6093.573449858512;6094.847628809271;6093.664848433356;6094.664793436359;6121.326654068049;6122.571420000601;6127.877442451148;5360.517553420172;5513.062779327483;5567.192757050205;5576.693281869496;5607.186957891083;23635.645465318034;24060.278191654103;24145.396365091012;24409;24664.630030549557;2391.449966507713;2399.292208798391;2441.084593277521;2452.080469823816;2584.5606246938064;10701.09040731531;10780.5687772489;11124.38815865127;11410.315381077162;11596.072314214927;1741.6429631925425;1754.2632094520332;1897.7722673279156;1979.8218160365636;2001.159513004527;82494.58752706258;84559;84979;88796;92935.07064929332;10972.835407468894;11696.298222106701;11858.229215100991;12299.200551964093;12321.568745094059;15574.532764017098;16303.673926521404;16307.91846040786;16429.424970125987;17066.402675906284;7693.6922523099565;8926.241269492037;9101.499417531966;9172.954135229282;9235.538223088819;8765.38642295046;8794.428362156566;8864.024957254696;8988.55057247135;9051.321150913707;5148.433672296044;5179.715115668599;5253.947460525423;5282.392524859613;5287.68273903567;1893.1386219270198;2000.2699014859763;2037.5924815037051;2101.443117573844;2176.738791345049;944.5985456181096;1043.89561043895;1102.3165637304762;1103.707517507861;1115.8549388579595;20017.699734503993;20637.896810515853;20739.377818665464;20797.89601051985;21292.893535532534;1055.7413433708637;1065.9979619157966;1067.3755852826184;1086.8532748079067;1109.9223054386116;11024.173187010845;11705.180637355474;11750.647480575597;11769.764174761698;11842.171048026728;433238;450040;503480;503967;504794;84631.57684211663;102213;104087.47956260321;105584.47207763912;108509.4574527138;1053.167997282154;1065.0893486069454;1083.3987137138963;1087.5595383869545;1094.1684319916922;18147.818521814614;18180.181891814635;18521.81478185201;18650.42407701658;18857.811421885606;45035.32447013232;45052;45639.31541026953;45673.31490027586;45771;62161.298057708926;64513;64553.67723161354;65250;66314.66842665756;9050.592723327334;9339.159475647224;9382.953085234803;9475.857862132212;9703.563339649874;13592.524261650795;14204.076735012191;14249.950129662793;14517.346719397863;14780.852191477947;11908.166428349932;12048.51805927753;12119.878801211875;12293.016558675463;12575.559855405018;11349.716257093638;11656.18406711556;12112.21270617407;12247.142700010914;12309.199902006341;12483.31341776211;12556.686082848002;12712.427940742873;12779;12899.742005160031;9240.584173712334;9414.105659962293;9464.858027129461;9669.081437263449;9696.660616878375;10705.45294985829;11856.347900865536;12428.875711242772;12765.74468510606;12849.550265740654 23:22:39 INFO - PROCESS | 3389 | |3;mootools.html;1393.5053056165068;1507.449780829997;1549.1324858079477;1694.3815507339816;1822.6810308196063;7739.806504837382;8285;8606.91393086069;8942.105789421055;8946.821063578724;8866.335024873128;8920.821583568324;8978.416402933804;8983.056779038205;9014.729558113251;2478.8884500197446;2501.024600405843;2504.2362079565814;2507.109975958536;2508.673872396595;2310.8151347892212;2463.802895768343;2471.3203868936007;2478.2069737683946;2481.0323973650293;2458.4714286428416;2464.7042354917344;2469.777720005199;2472.530219258344;2485.875706214691;2398.7001624796812;2406.4344878953434;2410.6143017117265;2466.4450498637843;2466.691663542057;2368.171140100968;2407.687000689899;2412.8311018228733;2416.7583241675866;2455.9386015349583;8735.126487351214;8881.24509416694;8892.377533572653;8966.103389660982;9012.909870901272;5373.946260537423;5587.3015873015465;5597.3003374578175;5599.8880022399735;5600.803971861006;3664.120611053328;3682.963170368289;3704.0369503928905;3726.571444283888;3732.5520937487677;1215.5745489078752;1240;1255.3033066648006;1274.3691872523161;1275;12029.819552706716;12123.33321667308;12164.756704865942;12194.329311887843;12202.938985305105;4706.082313948816;4719.00802558936;4733.840209148781;4834.516548345207;4873.098476781806;9841.901580984242;9851.45816980073;9854.950725246328;9856.063673950963;9887.209023278121;5403.918941215885;5431.402545720047;5434.8913021739345;5449.182622606561;5460.34475862891;4387.692861499728;4506.3465797459185;4925.21196608541;5004.549590536869;5066.03745288394;3962.2867883781196;4047.3726572380738;4090.1615168890307;4109.691773117028;4167.937480937789;1167.1538134852165;1167.550493060178;1187.5190547828142;1190.8451901252781;1198.1972078707113;958.6213445689053;986.7335819328885;1011.8886922438526;1029.629333439961;1053.6048981631889;5975;6011.308699499614;6032.969835150796;6124;6278.080158337457;496.48117716985837;501.0430078549952;504.3821318884337;524.609166171205;524.9527542521191;4078;4111.753294802322;4190.392393102983;4229.809658565372;4235.856318793908;10751.247412681194;11011.504482298316;11072.612458563912;11073.667789966314;11077.778444431227;10930.890691093146;10965.396903170402;10998.725031874266;11005.229633925708;11017.944910275397;8009.519428834288;8288.58557072144;8350.331973442231;8372.497650141011;8394.45436046655;6216;6253.593516421418;6320.747170113144;6380.521460890544;6457.806265812033;10055.245856560934;10223.1821454282;10378.117859981987;10395.376277423378;10399.1160751337;10479.842802358124;10544.578216871547;10547.736306592396;10563.366198028143;10570;7235.312645298688;7457.2542745724995;7839.804004899923;8038.236367545074;8131.390145738975;8542.145785421657;8995.23540499065;9003.819923601623;9004.549772511216;9007.90992090097;7730.884036739566;7904.604769761604;7962.402819788655;8029.156938521372;8227.794305142419;7738.419618528746;7756.806079848049;7843.529388236724;7862.4103192261955;7932.36541076724;7097.041899343522;7188.28117188277;7212.459065570209;7217.7473788417155;7223.747168849065;5247.55395791362;5267.394249661338;5294.470552944675;5415.350157981067;5443.101888188405;5241.475852414728;5275.340582427197;5326.573874090141;5357.491038351351;5372.5433338166695 23:22:39 INFO - PROCESS | 3389 | |4;prototype.html;333.3050589520849;340.31256861140497;346.8820600995661;347.6176206173209;348.5277449056528;5444.727763611819;5492.972535137324;5507.228987941681;5533.529649979757;5572.136318870576;5113.565346945505;5234.92147617786;5282.683039017662;5412.377576578695;5458.890822183554;4271.466066741657;4288.828446862121;4298.935515967248;4308.719933204347;4317.309230523117;4303.203907277164;4305.74165550068;4308.849190278341;4310.4611923509565;4312.266914624522;4101.5488296287385;4210.452641156646;4221.451211342521;4239.47006624172;4290.892727681801;4208.600182982612;4222.9577704222875;4227.689416280963;4227.936580951273;4239.7668128252935;3734.60786617404;3736.159364143073;3737.215184811193;3738.476613274144;3739.139997800494;5185.585153187736;5270.209468579706;5296.735163241822;5325.307709997675;5327.200919862013;4797.736124513151;4829.589484893753;4840.927386089212;4853.4418541867435;4855.814883302528;5607.299087614048;5612.979327442117;5631.352394474647;5665.745041473144;5699.515541179005;3955.5055618047745;4073.9388909166387;4123.690723195772;4125.504070037644;4128.483939507561;5842.532597392241;5846.678432686201;5878.353381128072;5908.231929849135;5912.881742365172;4138.5654506276405;4148.149629325981;4150.626443620089;4173.332266837291;4190.20386126635;4006.1587066715733;4221.387898754663;4222.282212023963;4230;4241.851535196253;4794.688345257547;4833.613310935117;4848.418189817174;4848.781804818792;4849.636277279218;5696.458836410535;5723.484886360165;5726.3700992890745;5738.397468265855;5749.885002300014;1429.9428022879176;1449.681070164562;1454.5854431486975;1457.8031965684497;1472.5655931500232;903.8327909336663;917.40368760305;920.6742291100793;925.7824411263348;934.1965909317981;4838.467768545457;4842.854714358575;4867.756612169448;4886.242632391985;4896.755162241873;5117.309163262912;5127;5132.230165475134;5137.434882162959;5195.896082078338;4515.119551687455;4843.685160464559;4866.7079975201605;4899.354348651688;4899.436564795023;2720.7551320380867;2736.233854520737;2737.9726202737716;2744.67063952327;2767.7370649788236;5258.474152584788;5266.4470230626;5286.629935904527;5316.043112239757;5316.255724198615;5227;5227;5274.367075950834;5544.500994910478;5987.970060149672;6020.217371741734;6089.985457198715;6096.817095487054;6105.45050945417;6115.969420152871;5898.233229680114;5920.733566989582;5921.63134746105;5935.792247271324;5944.910826337695;5517.586181036519;5776;5830.443429508082;5861.677607731445;5888.205092312483;6095.481884039907;6096.695165241631;6099.176611157614;6103.481204097701;6112.82994536933;3962.8216730247786;4173.686973477062;4203.327467605169;4214.059854036485;4229.323308270662;5605.598600349913;5702.6293290935955;5773;5862.94137058641;5872.911906321323;5696.949098324682;5744;5748.281464816898;5755.5971082025535;5767.221425107725;5597.608167428241;5725.6850873202375;5727.627704199213;5743.4256574342235;5751.166080918159;5703.258576385043;5710.17346413528;5714.285714285715;5726.370099289158;5727.59208271078;5763.7118144093465;5771.249737534094;5780.364159942319;5784.161296612052;5807.506361959281;5447.945520544902;5625.690587017753;5641.238432811518;5643.35101463337;5655.293088363955 23:22:39 INFO - PROCESS | 3389 | |5;yui.html;765.8430021845522;777.6111944027986;796.3469954637197;815.5595978171788;878.5607196401797;14973.251337433125;16251.593710157222;16453.17734113294;16588.170591470407;16727.163641817908;14797.704045919076;14966.476173333935;14967.850321496782;14979.400823967055;15000.549983500512;728.3699599846121;744.240874308208;846.5894041389921;857.536930057768;861.1603686405767;851.6423102297019;862.1163307609719;864.8097418567905;871.8997315308726;875.7329014650529;914.2685851318952;918.0131358789329;918.9540522973858;919.6827094652334;920.9493477858715;747.5850902748972;765.4794739577086;911.2300106410073;912.4525284829116;913.1781396742952;653.7483069018415;667.552739664426;672.9798106056777;673.2224280955504;674.0596867369991;15517.5344739658;15541.222938853125;15553.688926221412;15569.532914012598;15569.92215038929;13897.305134743223;14071.788923166161;14560.199189044599;14611.123332600078;14994;13461.326933653278;13697.383617737127;13714.725705485935;14035.50875719345;14226.644333891632;4582.541745825424;4632.143053535106;4710.034442939189;4730.526947305242;4752.59602933751;88005.55997219973;88007.55996220042;90081.54959225227;90629;90678.54660726654;11713.648590542298;11752.177347585757;11837.230580012272;11949.522019119311;12096.213746106476;22671.319860404215;22677.546449070927;22794.65808012881;22798.43003924882;23017.42456438569;8601.827963440695;8678.088800675965;8700.825983480421;9248.907510924804;9320.580573874191;4916.483769204254;5030.2957585938;5036.345275114211;5140.357455318085;5179.948200518022;3364.5457863188644;3478.32172726321;3482.407990641597;3489.7906125632544;3497.8076205808416;605.7849463440504;607.7690477618477;611.2084850119106;611.6483022262217;613.3712944232198;749.4460939087713;751.620431681997;762.1730422491659;775.4571799740204;794.5907857453416;9772.560234789451;10540.9986051325;10605.522751476203;10869.263003197451;10917.126629869592;722.8626560953402;751.6842925971105;766.4941138848334;773.8645736996003;794.3962588432832;5024.346834911438;5129.102407078746;5155.9742201288755;5193.714345711022;5209.218617207449;78178;91501.0849891506;119241;131257;131523.3423832894;109896.4505177469;112665.62003139919;114735;115097;118889;92907;94320;98748;100152;101035.49482252689;9358.251339892657;9891.554880030271;9896.505174741378;9938.45338506389;9957.800843983225;43932.78033609811;49565;58288.70855645695;60125.699371502866;60285;51915;64268;67856;75789.31580131623;77430;11143.665690029313;11364.374959377314;11510.482028308914;11573.594924177612;14091.577252682437;14053.227072510701;14457.843367533138;15355.548943766471;15490.380384784483;15519.37922483088;14908.403663853325;15427.61430964235;15659;15925.362985480915;16172.595685107966;14586.562403127924;14633.853661463248;14743.11541307525;15188.772168417703;15370.154641494824;13966;14034.508792192224;14448.205348705502;14517.637059073608;14699.265036748333;12496.562895267161;12850.42173102194;12915.612531624067;13076.491972880644;13248.151975200562;12574;13253.403596837972;13414.865851341752;13483.19100853952;13767.105138165987 23:22:39 INFO - PROCESS | 3389 | __end_tp_report 23:22:39 INFO - PROCESS | 3389 | __start_cc_report 23:22:39 INFO - PROCESS | 3389 | _x_x_mozilla_cycle_collect,889 23:22:39 INFO - PROCESS | 3389 | __end_cc_report 23:22:39 INFO - PROCESS | 3389 | __startTimestamp1479021759277__endTimestamp 23:22:39 INFO - PROCESS | 3389 | 23:22:39 INFO - PROCESS | 3389 | ------- Summary: start ------- 23:22:39 INFO - PROCESS | 3389 | Number of tests: 6 23:22:39 INFO - PROCESS | 3389 | 23:22:39 INFO - PROCESS | 3389 | [#0] dojo.html Cycles:165 Average:5473.17 Median:5730.54 stddev:605.73 (10.6%) stddev-sans-first:579.01 23:22:39 INFO - PROCESS | 3389 | Values: 3129.4 3182.2 3198.4 3223.2 3237.1 5514.3 5546.8 5581.1 5586.3 5600.1 5556.4 5617.5 5618.6 5623.8 5649.6 5057.7 5072.1 5089.5 5096.4 5106.5 4936.9 5038.2 5091.1 5101.1 5126.6 4960.3 5027.1 5034.7 5061.9 5087.8 5060.9 5080.6 5083.0 5177.7 5184.4 5008.9 5076.0 5122.4 5122.5 5136.2 5330.4 5335.9 5383.3 5398.2 5454.9 5743.5 5757.5 5761.8 5934.2 5963.0 5995.3 6000.9 6001.1 6004.7 6007.1 5317.7 5357.1 5391.1 5505.6 5520.9 6050.0 6100.2 6104.5 6105.8 6108.6 5867.7 5875.1 5885.7 5890.6 5893.6 5707.5 5739.7 5744.5 5784.2 5801.6 5805.1 5822.8 5822.8 5825.4 5860.7 5778.9 5819.2 5826.7 5829.2 5849.5 4378.7 4428.4 4445.6 4517.7 4519.8 4037.1 4060.2 4087.0 4089.3 4108.3 5504.7 5505.3 5516.3 5516.6 5518.8 5730.5 5784.2 5797.0 5941.4 5944.7 5414.1 5524.4 5527.3 5562.6 5568.4 5122.6 5133.1 5134.3 5134.6 5156.7 5872.9 5885.9 6084.8 6086.5 6090.6 5812.6 5834.9 5850.0 5867.0 5880.3 6009.5 6010.4 6013.1 6017.0 6037.2 5635.6 5763.5 5766.0 5767.1 5796.7 5739.7 5809.8 5862.9 5877.1 5980.9 5796.2 5803.0 5805.9 5807.6 5808.6 5751.5 5754.3 5817.0 5828.0 5829.0 5553.2 5565.1 5689.1 5722.2 5743.1 5872.5 5873.7 5878.0 5880.4 5899.6 5440.2 5743.0 5758.8 5759.2 5763.7 23:22:39 INFO - PROCESS | 3389 | 23:22:39 INFO - PROCESS | 3389 | [#1] ext.html Cycles:180 Average:64908.67 Median:45623.16 stddev:123400.48 (270.5%) stddev-sans-first:123652.17 23:22:39 INFO - PROCESS | 3389 | Values: 593.7 626.8 640.8 651.6 670.8 36916.0 37619.4 37629.1 37632.9 37674.8 38055.2 47038.0 48323.0 48381.3 52500.7 9391.9 9543.5 9550.7 9554.2 9579.3 8382.1 9357.0 9462.0 9463.3 9464.2 8700.5 11112.4 11390.3 11403.5 11791.2 11338.2 11389.0 11409.9 11412.1 11417.3 8365.6 8695.0 11176.1 11241.4 11267.5 44833.6 45482.0 45569.5 48949.8 48965.5 3699.5 3738.8 3790.2 3833.7 4349.3 33782.8 34782.5 34908.3 34944.3 34957.8 4703.4 4811.2 4885.1 4902.8 4946.4 366665.0 367086.0 370178.0 374138.0 374913.0 57739.1 58218.0 58312.7 58691.1 66271.7 67928.0 68210.0 68318.7 68418.0 68452.0 17193.8 17591.4 17835.6 17846.6 17850.6 14053.3 14062.5 14068.8 14084.9 14123.2 17877.6 18263.5 19288.7 19890.2 19898.6 6589.2 6601.3 6627.7 6665.4 6700.9 979.8 1014.6 1081.1 1110.9 1134.5 34384.5 34653.5 34800.3 34842.7 35271.8 1189.3 1215.0 1217.8 1219.4 1258.6 18568.3 18617.4 18731.6 18810.4 18847.0 520137.0 523954.0 524685.0 524836.0 524969.0 63702.4 64106.7 64174.4 64296.7 64338.0 1313.5 1360.6 1385.6 1437.7 1466.9 30871.8 35617.8 35664.8 35977.9 36087.5 61955.0 64042.0 66683.0 66717.0 66720.7 483798.0 493612.0 494887.0 495058.0 495571.0 48334.8 49703.5 49929.5 50408.7 50510.7 52548.2 52693.5 53006.4 53366.5 54189.5 44587.3 45676.8 46104.0 46612.8 47549.0 46062.8 46438.3 46708.5 46971.1 47343.5 46863.0 47341.3 47857.5 48050.3 49443.8 39482.8 41608.0 41931.0 42117.6 42320.4 30977.2 31291.4 31429.0 31695.8 31728.8 23:22:39 INFO - PROCESS | 3389 | 23:22:39 INFO - PROCESS | 3389 | [#2] jquery.html Cycles:180 Average:29880.98 Median:11879.10 stddev:79557.82 (669.7%) stddev-sans-first:79750.19 23:22:39 INFO - PROCESS | 3389 | Values: 389.9 426.9 435.0 437.6 440.4 16866.5 20320.0 21199.3 21581.5 21848.1 19196.6 19765.0 19839.8 19904.5 19914.3 5539.4 5635.3 5752.1 5756.9 5766.7 6009.1 6111.6 6116.0 6121.4 6121.6 5884.9 6052.5 6088.6 6093.6 6094.8 6093.7 6094.7 6121.3 6122.6 6127.9 5360.5 5513.1 5567.2 5576.7 5607.2 23635.6 24060.3 24145.4 24409.0 24664.6 2391.4 2399.3 2441.1 2452.1 2584.6 10701.1 10780.6 11124.4 11410.3 11596.1 1741.6 1754.3 1897.8 1979.8 2001.2 82494.6 84559.0 84979.0 88796.0 92935.1 10972.8 11696.3 11858.2 12299.2 12321.6 15574.5 16303.7 16307.9 16429.4 17066.4 7693.7 8926.2 9101.5 9173.0 9235.5 8765.4 8794.4 8864.0 8988.6 9051.3 5148.4 5179.7 5253.9 5282.4 5287.7 1893.1 2000.3 2037.6 2101.4 2176.7 944.6 1043.9 1102.3 1103.7 1115.9 20017.7 20637.9 20739.4 20797.9 21292.9 1055.7 1066.0 1067.4 1086.9 1109.9 11024.2 11705.2 11750.6 11769.8 11842.2 433238.0 450040.0 503480.0 503967.0 504794.0 84631.6 102213.0 104087.5 105584.5 108509.5 1053.2 1065.1 1083.4 1087.6 1094.2 18147.8 18180.2 18521.8 18650.4 18857.8 45035.3 45052.0 45639.3 45673.3 45771.0 62161.3 64513.0 64553.7 65250.0 66314.7 9050.6 9339.2 9383.0 9475.9 9703.6 13592.5 14204.1 14250.0 14517.3 14780.9 11908.2 12048.5 12119.9 12293.0 12575.6 11349.7 11656.2 12112.2 12247.1 12309.2 12483.3 12556.7 12712.4 12779.0 12899.7 9240.6 9414.1 9464.9 9669.1 9696.7 10705.5 11856.3 12428.9 12765.7 12849.6 23:22:39 INFO - PROCESS | 3389 | 23:22:39 INFO - PROCESS | 3389 | [#3] mootools.html Cycles:180 Average:5900.87 Median:2710.85 stddev:3302.95 (121.8%) stddev-sans-first:3294.85 23:22:39 INFO - PROCESS | 3389 | Values: 1393.5 1507.4 1549.1 1694.4 1822.7 7739.8 8285.0 8606.9 8942.1 8946.8 8866.3 8920.8 8978.4 8983.1 9014.7 2478.9 2501.0 2504.2 2507.1 2508.7 2310.8 2463.8 2471.3 2478.2 2481.0 2458.5 2464.7 2469.8 2472.5 2485.9 2398.7 2406.4 2410.6 2466.4 2466.7 2368.2 2407.7 2412.8 2416.8 2455.9 8735.1 8881.2 8892.4 8966.1 9012.9 5373.9 5587.3 5597.3 5599.9 5600.8 3664.1 3683.0 3704.0 3726.6 3732.6 1215.6 1240.0 1255.3 1274.4 1275.0 12029.8 12123.3 12164.8 12194.3 12202.9 4706.1 4719.0 4733.8 4834.5 4873.1 9841.9 9851.5 9855.0 9856.1 9887.2 5403.9 5431.4 5434.9 5449.2 5460.3 4387.7 4506.3 4925.2 5004.5 5066.0 3962.3 4047.4 4090.2 4109.7 4167.9 1167.2 1167.6 1187.5 1190.8 1198.2 958.6 986.7 1011.9 1029.6 1053.6 5975.0 6011.3 6033.0 6124.0 6278.1 496.5 501.0 504.4 524.6 525.0 4078.0 4111.8 4190.4 4229.8 4235.9 10751.2 11011.5 11072.6 11073.7 11077.8 10930.9 10965.4 10998.7 11005.2 11017.9 8009.5 8288.6 8350.3 8372.5 8394.5 6216.0 6253.6 6320.7 6380.5 6457.8 10055.2 10223.2 10378.1 10395.4 10399.1 10479.8 10544.6 10547.7 10563.4 10570.0 7235.3 7457.3 7839.8 8038.2 8131.4 8542.1 8995.2 9003.8 9004.5 9007.9 7730.9 7904.6 7962.4 8029.2 8227.8 7738.4 7756.8 7843.5 7862.4 7932.4 7097.0 7188.3 7212.5 7217.7 7223.7 5247.6 5267.4 5294.5 5415.4 5443.1 5241.5 5275.3 5326.6 5357.5 5372.5 23:22:39 INFO - PROCESS | 3389 | 23:22:39 INFO - PROCESS | 3389 | [#4] prototype.html Cycles:180 Average:4710.75 Median:5262.46 stddev:1392.74 (26.5%) stddev-sans-first:1357.34 23:22:39 INFO - PROCESS | 3389 | Values: 333.3 340.3 346.9 347.6 348.5 5444.7 5493.0 5507.2 5533.5 5572.1 5113.6 5234.9 5282.7 5412.4 5458.9 4271.5 4288.8 4298.9 4308.7 4317.3 4303.2 4305.7 4308.8 4310.5 4312.3 4101.5 4210.5 4221.5 4239.5 4290.9 4208.6 4223.0 4227.7 4227.9 4239.8 3734.6 3736.2 3737.2 3738.5 3739.1 5185.6 5270.2 5296.7 5325.3 5327.2 4797.7 4829.6 4840.9 4853.4 4855.8 5607.3 5613.0 5631.4 5665.7 5699.5 3955.5 4073.9 4123.7 4125.5 4128.5 5842.5 5846.7 5878.4 5908.2 5912.9 4138.6 4148.1 4150.6 4173.3 4190.2 4006.2 4221.4 4222.3 4230.0 4241.9 4794.7 4833.6 4848.4 4848.8 4849.6 5696.5 5723.5 5726.4 5738.4 5749.9 1429.9 1449.7 1454.6 1457.8 1472.6 903.8 917.4 920.7 925.8 934.2 4838.5 4842.9 4867.8 4886.2 4896.8 5117.3 5127.0 5132.2 5137.4 5195.9 4515.1 4843.7 4866.7 4899.4 4899.4 2720.8 2736.2 2738.0 2744.7 2767.7 5258.5 5266.4 5286.6 5316.0 5316.3 5227.0 5227.0 5274.4 5544.5 5988.0 6020.2 6090.0 6096.8 6105.5 6116.0 5898.2 5920.7 5921.6 5935.8 5944.9 5517.6 5776.0 5830.4 5861.7 5888.2 6095.5 6096.7 6099.2 6103.5 6112.8 3962.8 4173.7 4203.3 4214.1 4229.3 5605.6 5702.6 5773.0 5862.9 5872.9 5696.9 5744.0 5748.3 5755.6 5767.2 5597.6 5725.7 5727.6 5743.4 5751.2 5703.3 5710.2 5714.3 5726.4 5727.6 5763.7 5771.2 5780.4 5784.2 5807.5 5447.9 5625.7 5641.2 5643.4 5655.3 23:22:39 INFO - PROCESS | 3389 | 23:22:39 INFO - PROCESS | 3389 | [#5] yui.html Cycles:180 Average:22101.49 Median:4607.34 stddev:32073.20 (696.1%) stddev-sans-first:32123.17 23:22:39 INFO - PROCESS | 3389 | Values: 765.8 777.6 796.3 815.6 878.6 14973.3 16251.6 16453.2 16588.2 16727.2 14797.7 14966.5 14967.9 14979.4 15000.5 728.4 744.2 846.6 857.5 861.2 851.6 862.1 864.8 871.9 875.7 914.3 918.0 919.0 919.7 920.9 747.6 765.5 911.2 912.5 913.2 653.7 667.6 673.0 673.2 674.1 15517.5 15541.2 15553.7 15569.5 15569.9 13897.3 14071.8 14560.2 14611.1 14994.0 13461.3 13697.4 13714.7 14035.5 14226.6 4582.5 4632.1 4710.0 4730.5 4752.6 88005.6 88007.6 90081.5 90629.0 90678.5 11713.6 11752.2 11837.2 11949.5 12096.2 22671.3 22677.5 22794.7 22798.4 23017.4 8601.8 8678.1 8700.8 9248.9 9320.6 4916.5 5030.3 5036.3 5140.4 5179.9 3364.5 3478.3 3482.4 3489.8 3497.8 605.8 607.8 611.2 611.6 613.4 749.4 751.6 762.2 775.5 794.6 9772.6 10541.0 10605.5 10869.3 10917.1 722.9 751.7 766.5 773.9 794.4 5024.3 5129.1 5156.0 5193.7 5209.2 78178.0 91501.1 119241.0 131257.0 131523.3 109896.5 112665.6 114735.0 115097.0 118889.0 92907.0 94320.0 98748.0 100152.0 101035.5 9358.3 9891.6 9896.5 9938.5 9957.8 43932.8 49565.0 58288.7 60125.7 60285.0 51915.0 64268.0 67856.0 75789.3 77430.0 11143.7 11364.4 11510.5 11573.6 14091.6 14053.2 14457.8 15355.5 15490.4 15519.4 14908.4 15427.6 15659.0 15925.4 16172.6 14586.6 14633.9 14743.1 15188.8 15370.2 13966.0 14034.5 14448.2 14517.6 14699.3 12496.6 12850.4 12915.6 13076.5 13248.2 12574.0 13253.4 13414.9 13483.2 13767.1 23:22:39 INFO - PROCESS | 3389 | -------- Summary: end -------- 23:22:39 INFO - PROCESS | 3389 | 23:22:40 INFO - TEST-INFO | 3389: exit 0 23:22:40 INFO - TEST-OK | dromaeo_css | took 1114735ms 23:22:40 INFO - TEST-START | kraken 23:22:40 INFO - operating with platform_type : linux_ 23:22:40 INFO - Initialising browser for kraken test... 23:22:40 INFO - TEST-INFO | started process 3710 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmRoXT4/profile http://localhost:39414/getInfo.html) 23:22:43 INFO - PROCESS | 3710 | __metrics Screen width/height:1600/1200 23:22:43 INFO - PROCESS | 3710 | colorDepth:24 23:22:43 INFO - PROCESS | 3710 | Browser inner width/height: 1024/697 23:22:43 INFO - PROCESS | 3710 | __metrics 23:22:44 INFO - PROCESS | 3710 | 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" 23:22:44 INFO - PROCESS | 3710 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:22:44 INFO - PROCESS | 3710 | console.error: PushService: 23:22:44 INFO - PROCESS | 3710 | stateChangeProcessEnqueue: Error transitioning state 23:22:44 INFO - PROCESS | 3710 | UnknownError 23:22:45 INFO - TEST-INFO | 3710: exit 0 23:22:45 INFO - Browser initialized. 23:22:45 INFO - Running cycle 1/1 for kraken test... 23:22:45 INFO - TEST-INFO | started process 3800 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmRoXT4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:22:57 INFO - PROCESS | 3800 | RSS: Main: 145047552 23:22:57 INFO - PROCESS | 3800 | 23:22:59 INFO - PROCESS | 3800 | Cycle 1(1): loaded http://localhost:39414/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:39414/tests/kraken/driver.html?testName=audio-beat-detection) 23:23:00 INFO - PROCESS | 3800 | RSS: Main: 186568704 23:23:00 INFO - PROCESS | 3800 | 23:23:03 INFO - PROCESS | 3800 | Cycle 1(1): loaded http://localhost:39414/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:39414/tests/kraken/driver.html?testName=audio-dft) 23:23:03 INFO - PROCESS | 3800 | RSS: Main: 176697344 23:23:03 INFO - PROCESS | 3800 | 23:23:06 INFO - PROCESS | 3800 | Cycle 1(1): loaded http://localhost:39414/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:39414/tests/kraken/driver.html?testName=audio-fft) 23:23:07 INFO - PROCESS | 3800 | RSS: Main: 268120064 23:23:07 INFO - PROCESS | 3800 | 23:23:09 INFO - PROCESS | 3800 | Cycle 1(1): loaded http://localhost:39414/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:39414/tests/kraken/driver.html?testName=audio-oscillator) 23:23:09 INFO - PROCESS | 3800 | RSS: Main: 174403584 23:23:09 INFO - PROCESS | 3800 | 23:23:11 INFO - PROCESS | 3800 | Cycle 1(1): loaded http://localhost:39414/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:39414/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:23:12 INFO - PROCESS | 3800 | RSS: Main: 176037888 23:23:12 INFO - PROCESS | 3800 | 23:23:15 INFO - PROCESS | 3800 | Cycle 1(1): loaded http://localhost:39414/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:39414/tests/kraken/driver.html?testName=imaging-darkroom) 23:23:16 INFO - PROCESS | 3800 | RSS: Main: 210919424 23:23:16 INFO - PROCESS | 3800 | 23:23:19 INFO - PROCESS | 3800 | Cycle 1(1): loaded http://localhost:39414/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:39414/tests/kraken/driver.html?testName=imaging-desaturate) 23:23:19 INFO - PROCESS | 3800 | RSS: Main: 210337792 23:23:19 INFO - PROCESS | 3800 | 23:23:22 INFO - PROCESS | 3800 | Cycle 1(1): loaded http://localhost:39414/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:39414/tests/kraken/driver.html?testName=json-parse-financial) 23:23:23 INFO - PROCESS | 3800 | RSS: Main: 210644992 23:23:23 INFO - PROCESS | 3800 | 23:23:24 INFO - PROCESS | 3800 | Cycle 1(1): loaded http://localhost:39414/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:39414/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:23:25 INFO - PROCESS | 3800 | RSS: Main: 172187648 23:23:25 INFO - PROCESS | 3800 | 23:23:27 INFO - PROCESS | 3800 | Cycle 1(1): loaded http://localhost:39414/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:39414/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:23:27 INFO - PROCESS | 3800 | RSS: Main: 179945472 23:23:27 INFO - PROCESS | 3800 | 23:23:29 INFO - PROCESS | 3800 | Cycle 1(1): loaded http://localhost:39414/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:39414/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:23:30 INFO - PROCESS | 3800 | RSS: Main: 189296640 23:23:30 INFO - PROCESS | 3800 | 23:23:32 INFO - PROCESS | 3800 | Cycle 1(1): loaded http://localhost:39414/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:39414/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:23:33 INFO - PROCESS | 3800 | RSS: Main: 193388544 23:23:33 INFO - PROCESS | 3800 | 23:23:36 INFO - PROCESS | 3800 | Cycle 1(1): loaded http://localhost:39414/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:39414/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:23:36 INFO - PROCESS | 3800 | RSS: Main: 193511424 23:23:36 INFO - PROCESS | 3800 | 23:23:38 INFO - PROCESS | 3800 | Cycle 1(1): loaded http://localhost:39414/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:39414/tests/kraken/driver.html?testName=ai-astar) 23:23:38 INFO - PROCESS | 3800 | RSS: Main: 246280192 23:23:38 INFO - PROCESS | 3800 | 23:23:38 INFO - PROCESS | 3800 | __start_tp_report 23:23:38 INFO - PROCESS | 3800 | _x_x_mozilla_page_load 23:23:38 INFO - PROCESS | 3800 | _x_x_mozilla_page_load_details 23:23:38 INFO - PROCESS | 3800 | |i|pagename|runs| 23:23:38 INFO - PROCESS | 3800 | |0;ai-astar;110;111;112;113;111;112;113;110;110;111 23:23:38 INFO - PROCESS | 3800 | |1;audio-beat-detection;147;117;116;156;122;120;122;121;149;117 23:23:38 INFO - PROCESS | 3800 | |2;audio-dft;160;160;159;162;162;159;159;161;162;161 23:23:38 INFO - PROCESS | 3800 | |3;audio-fft;76;75;75;122;75;76;76;75;103;75 23:23:38 INFO - PROCESS | 3800 | |4;audio-oscillator;81;81;118;81;81;81;106;81;81;119 23:23:38 INFO - PROCESS | 3800 | |5;imaging-gaussian-blur;116;116;117;117;117;117;116;117;117;116 23:23:38 INFO - PROCESS | 3800 | |6;imaging-darkroom;146;147;145;146;146;146;146;146;146;146 23:23:38 INFO - PROCESS | 3800 | |7;imaging-desaturate;95;95;96;96;95;95;96;97;95;95 23:23:38 INFO - PROCESS | 3800 | |8;json-parse-financial;51;52;49;48;49;49;49;49;49;49 23:23:38 INFO - PROCESS | 3800 | |9;json-stringify-tinderbox;59;60;60;59;60;60;58;59;59;60 23:23:38 INFO - PROCESS | 3800 | |10;stanford-crypto-aes;82;76;74;75;74;79;75;74;82;73 23:23:38 INFO - PROCESS | 3800 | |11;stanford-crypto-ccm;123;120;117;122;128;120;120;119;171;130 23:23:38 INFO - PROCESS | 3800 | |12;stanford-crypto-pbkdf2;154;156;159;154;156;153;151;153;151;153 23:23:38 INFO - PROCESS | 3800 | |13;stanford-crypto-sha256-iterative;63;63;63;62;62;63;62;62;61;61 23:23:38 INFO - PROCESS | 3800 | __end_tp_report 23:23:38 INFO - PROCESS | 3800 | __start_cc_report 23:23:38 INFO - PROCESS | 3800 | _x_x_mozilla_cycle_collect,1589 23:23:38 INFO - PROCESS | 3800 | __end_cc_report 23:23:38 INFO - PROCESS | 3800 | __startTimestamp1479021818617__endTimestamp 23:23:38 INFO - PROCESS | 3800 | 23:23:38 INFO - PROCESS | 3800 | ------- Summary: start ------- 23:23:38 INFO - PROCESS | 3800 | Number of tests: 14 23:23:38 INFO - PROCESS | 3800 | 23:23:38 INFO - PROCESS | 3800 | [#0] ai-astar Cycles:10 Average:111.30 Median:111.50 stddev:1.16 (1.0%) stddev-sans-first:1.13 23:23:38 INFO - PROCESS | 3800 | Values: 110.0 111.0 112.0 113.0 111.0 112.0 113.0 110.0 110.0 111.0 23:23:38 INFO - PROCESS | 3800 | 23:23:38 INFO - PROCESS | 3800 | [#1] audio-beat-detection Cycles:10 Average:128.70 Median:122.00 stddev:15.46 (12.7%) stddev-sans-first:14.92 23:23:38 INFO - PROCESS | 3800 | Values: 147.0 117.0 116.0 156.0 122.0 120.0 122.0 121.0 149.0 117.0 23:23:38 INFO - PROCESS | 3800 | 23:23:38 INFO - PROCESS | 3800 | [#2] audio-dft Cycles:10 Average:160.50 Median:161.00 stddev:1.27 (0.8%) stddev-sans-first:1.33 23:23:38 INFO - PROCESS | 3800 | Values: 160.0 160.0 159.0 162.0 162.0 159.0 159.0 161.0 162.0 161.0 23:23:38 INFO - PROCESS | 3800 | 23:23:38 INFO - PROCESS | 3800 | [#3] audio-fft Cycles:10 Average:82.80 Median:75.00 stddev:16.29 (21.7%) stddev-sans-first:17.09 23:23:38 INFO - PROCESS | 3800 | Values: 76.0 75.0 75.0 122.0 75.0 76.0 76.0 75.0 103.0 75.0 23:23:38 INFO - PROCESS | 3800 | 23:23:38 INFO - PROCESS | 3800 | [#4] audio-oscillator Cycles:10 Average:91.00 Median:81.00 stddev:16.46 (20.3%) stddev-sans-first:17.05 23:23:38 INFO - PROCESS | 3800 | Values: 81.0 81.0 118.0 81.0 81.0 81.0 106.0 81.0 81.0 119.0 23:23:38 INFO - PROCESS | 3800 | 23:23:38 INFO - PROCESS | 3800 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.50 23:23:38 INFO - PROCESS | 3800 | Values: 116.0 116.0 117.0 117.0 117.0 117.0 116.0 117.0 117.0 116.0 23:23:38 INFO - PROCESS | 3800 | 23:23:38 INFO - PROCESS | 3800 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.47 (0.3%) stddev-sans-first:0.50 23:23:38 INFO - PROCESS | 3800 | Values: 146.0 147.0 145.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 23:23:38 INFO - PROCESS | 3800 | 23:23:38 INFO - PROCESS | 3800 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:95.50 stddev:0.71 (0.7%) stddev-sans-first:0.73 23:23:38 INFO - PROCESS | 3800 | Values: 95.0 95.0 96.0 96.0 95.0 95.0 96.0 97.0 95.0 95.0 23:23:38 INFO - PROCESS | 3800 | 23:23:38 INFO - PROCESS | 3800 | [#8] json-parse-financial Cycles:10 Average:49.40 Median:49.00 stddev:1.17 (2.4%) stddev-sans-first:1.09 23:23:38 INFO - PROCESS | 3800 | Values: 51.0 52.0 49.0 48.0 49.0 49.0 49.0 49.0 49.0 49.0 23:23:38 INFO - PROCESS | 3800 | 23:23:38 INFO - PROCESS | 3800 | [#9] json-stringify-tinderbox Cycles:10 Average:59.40 Median:60.00 stddev:0.70 (1.2%) stddev-sans-first:0.73 23:23:38 INFO - PROCESS | 3800 | Values: 59.0 60.0 60.0 59.0 60.0 60.0 58.0 59.0 59.0 60.0 23:23:38 INFO - PROCESS | 3800 | 23:23:38 INFO - PROCESS | 3800 | [#10] stanford-crypto-aes Cycles:10 Average:76.40 Median:75.50 stddev:3.37 (4.5%) stddev-sans-first:2.91 23:23:38 INFO - PROCESS | 3800 | Values: 82.0 76.0 74.0 75.0 74.0 79.0 75.0 74.0 82.0 73.0 23:23:38 INFO - PROCESS | 3800 | 23:23:38 INFO - PROCESS | 3800 | [#11] stanford-crypto-ccm Cycles:10 Average:127.00 Median:122.50 stddev:15.98 (13.0%) stddev-sans-first:16.88 23:23:38 INFO - PROCESS | 3800 | Values: 123.0 120.0 117.0 122.0 128.0 120.0 120.0 119.0 171.0 130.0 23:23:38 INFO - PROCESS | 3800 | 23:23:38 INFO - PROCESS | 3800 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.00 Median:154.00 stddev:2.45 (1.6%) stddev-sans-first:2.60 23:23:38 INFO - PROCESS | 3800 | Values: 154.0 156.0 159.0 154.0 156.0 153.0 151.0 153.0 151.0 153.0 23:23:38 INFO - PROCESS | 3800 | 23:23:38 INFO - PROCESS | 3800 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.20 Median:62.50 stddev:0.79 (1.3%) stddev-sans-first:0.78 23:23:38 INFO - PROCESS | 3800 | Values: 63.0 63.0 63.0 62.0 62.0 63.0 62.0 62.0 61.0 61.0 23:23:38 INFO - PROCESS | 3800 | -------- Summary: end -------- 23:23:38 INFO - PROCESS | 3800 | 23:23:39 INFO - TEST-INFO | 3800: exit 0 23:23:39 INFO - TEST-OK | kraken | took 59559ms 23:23:39 INFO - SUITE-END | took 1174s 23:23:39 INFO - Completed test suite (00:19:34) 23:23:39 INFO - javascript benchmark 23:23:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3129.3584815112904, 3182.17263511487, 3198.4082944655224, 3223.226425657843, 3237.0612522368515, 5514.338279406472, 5546.805861794838, 5581.134924086763, 5586.273784408027, 5600.0759874620635, 5556.361018482876, 5617.494425501704, 5618.550515958724, 5623.775048998035, 5649.6327738697055, 5057.69653820772, 5072.061668591304, 5089.516495932899, 5096.413912400066, 5106.4638212987475, 4936.876578085558, 5038.2190760432195, 5091.0581542414775, 5101.132807422747, 5126.5898728101665, 4960.25596160577, 5027.095122877899, 5034.748262586856, 5061.873453163682, 5087.79648814047, 5060.873478163038, 5080.568151707091, 5083.0, 5177.715225662587, 5184.351956005499, 5008.924866127011, 5075.98480303937, 5122.359705036871, 5122.462141475129, 5136.203888397267, 5330.413654498002, 5335.866603334909, 5383.300170977748, 5398.190271459273, 5454.91817622736, 5743.45437183471, 5757.510611598019, 5761.827145185651, 5934.228550288435, 5963.0459126539545, 5995.310539287951, 6000.879982400327, 6001.12983617373, 6004.6997650117755, 6007.128966299906, 5317.654352467116, 5357.142857142878, 5391.137418013099, 5505.587080968943, 5520.861978450571, 6050.031994880798, 6100.23747031621, 6104.481119104926, 6105.786297479567, 6108.633481991006, 5867.677277749679, 5875.089361149029, 5885.676287804126, 5890.617109887844, 5893.587448878623, 5707.486326230659, 5739.655620662774, 5744.51171650406, 5784.21913041734, 5801.622894511843, 5805.071188609802, 5822.825315240465, 5822.82531524055, 5825.359210486843, 5860.736266868002, 5778.855528611818, 5819.185314056035, 5826.650400975955, 5829.15477255804, 5849.532037436995, 4378.715383500126, 4428.357888106207, 4445.599896009375, 4517.683762136685, 4519.751413672214, 4037.1118353962083, 4060.2285565742413, 4087.019115412339, 4089.284375234322, 4108.301588729923, 5504.697241651747, 5505.311836020497, 5516.282883225155, 5516.641418307797, 5518.834434966958, 5730.541556675456, 5784.1902133701315, 5797.043487824461, 5941.435563621449, 5944.6730429826775, 5414.133738601805, 5524.4199359067525, 5527.336719593674, 5562.582806289463, 5568.415316391721, 5122.641415100908, 5133.12736834739, 5134.255532947702, 5134.589232861362, 5156.664816786897, 5872.853178670482, 5885.852853678693, 6084.817455476343, 6086.513078953762, 6090.604110732788, 5812.593118481665, 5834.883302334015, 5850.0, 5867.031939729897, 5880.26496687914, 6009.489193418433, 6010.368911264254, 6013.098035294741, 6017.0, 6037.154798328325, 5635.633683810539, 5763.538916886555, 5765.971170144123, 5767.134929760569, 5796.681182535001, 5739.741711623071, 5809.82570522885, 5862.853428664317, 5877.089051197071, 5980.910286345621, 5796.21751063601, 5803.0, 5805.883882322245, 5807.622504537191, 5808.593398462236, 5751.4536119068625, 5754.338251101177, 5817.0111081115465, 5828.0, 5829.038208695518, 5553.194786755978, 5565.1095824667855, 5689.146628005832, 5722.198892155018, 5743.1385292206505, 5872.5301975842685, 5873.676947767743, 5877.970610147093, 5880.382559831156, 5899.646021238739, 5440.156775699773, 5743.0, 5758.798442054508, 5759.193712880284, 5763.7118144093465], "value": 5433.1158607904235, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [593.6972144206457, 626.8432891777056, 640.8397900524869, 651.5504302031595, 670.7719375412358, 36916.0, 37619.43570846439, 37629.05927351818, 37632.8710138696, 37674.811625941904, 38055.23889522215, 47038.0, 48323.0, 48381.27428088573, 52500.73749631247, 9391.859122113157, 9543.47510886901, 9550.665726699566, 9554.18789402898, 9579.281553883451, 8382.119877412875, 9357.0, 9461.952690236574, 9463.290253231035, 9464.19554337882, 8700.477971321709, 11112.44437778112, 11390.316581005125, 11403.486843092081, 11791.233569817978, 11338.206325557214, 11389.0, 11409.885901141048, 11412.14408919326, 11417.257878237888, 8365.581720913991, 8694.956525217396, 11176.105911527058, 11241.381724005176, 11267.492962816694, 44833.55166448359, 45482.0, 45569.54430455686, 48949.75525122387, 48965.51034489645, 3699.5190625218547, 3738.7722693662736, 3790.2419516096893, 3833.6933045356304, 4349.26062569364, 33782.81760138408, 34782.47826282608, 34908.30183396343, 34944.30111397783, 34957.82521087378, 4703.390041225528, 4811.182099043136, 4885.096257192431, 4902.828401005948, 4946.3817022872145, 366665.0, 367086.0, 370178.0, 374138.0, 374913.0, 57739.13391299134, 58218.0, 58312.70843645755, 58691.11963320553, 66271.66864165745, 67928.0, 68210.0, 68318.65840670765, 68418.0, 68452.0, 17193.82806171922, 17591.38430154964, 17835.643287134186, 17846.643067138586, 17850.5537361567, 14053.297335133202, 14062.507812226522, 14068.788968165485, 14084.929575352058, 14123.223222722849, 17877.642447150985, 18263.54341141482, 19288.710669339973, 19890.204391824453, 19898.60202795936, 6589.209294884645, 6601.339866013456, 6627.668616569153, 6665.366790154928, 6700.93299067003, 979.7844474215661, 1014.5941623350719, 1081.0540776820283, 1110.9111271098293, 1134.4951496584106, 34384.484232736526, 34653.48019779706, 34800.30399391998, 34842.65157348394, 35271.82364088163, 1189.2745425290566, 1214.9757004859853, 1217.7807994561065, 1219.3781171602548, 1258.5532136091738, 18568.25726970906, 18617.441476755717, 18731.625367492576, 18810.435686929413, 18847.0, 520137.0, 523954.0, 524685.0, 524836.0, 524969.0, 63702.36297637057, 64106.67946660237, 64174.35825641777, 64296.67851660712, 64338.0, 1313.487739781486, 1360.5782207515701, 1385.5704731533056, 1437.6908964572458, 1466.9193194374411, 30871.84564077165, 35617.82191089132, 35664.82167589146, 35977.926180669456, 36087.45868811917, 61955.0, 64042.0, 66683.0, 66717.0, 66720.6663966677, 483798.0, 493612.0, 494887.0, 495058.0, 495571.0, 48334.758326208146, 49703.50296497133, 49929.50070499248, 50408.747956259984, 50510.74744626253, 52548.211776824144, 52693.47306526886, 53006.447743289544, 53366.46633533615, 54189.45810541844, 44587.331190031524, 45676.771616141705, 46104.0, 46612.76693616646, 47549.0, 46062.769686151354, 46438.30342544932, 46708.53291467042, 46971.077486843875, 47343.526564735286, 46863.0, 47341.28988065113, 47857.5214247867, 48050.27924581064, 49443.75278123586, 39482.80258598689, 41608.0, 41931.0, 42117.57882421136, 42320.365194522725, 30977.225569360948, 31291.374172515967, 31429.0, 31695.841520792248, 31728.841355793073], "value": 22178.690399225394, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [389.9142188718481, 426.9369270516413, 434.96085352318306, 437.5952244174139, 440.37789183961917, 16866.494005179848, 20320.0, 21199.258025969095, 21581.46046348842, 21848.12607495702, 19196.61606767864, 19765.0, 19839.801601983974, 19904.50238744032, 19914.302999395026, 5539.362973258067, 5635.3094071778505, 5752.108423194411, 5756.856078598027, 5766.654000759968, 6009.1286763419275, 6111.572189946705, 6115.969420152893, 6121.449069583737, 6121.571489995702, 5884.852878678046, 6052.455279024887, 6088.604240724361, 6093.573449858512, 6094.847628809271, 6093.664848433356, 6094.664793436359, 6121.326654068049, 6122.571420000601, 6127.877442451148, 5360.517553420172, 5513.062779327483, 5567.192757050205, 5576.693281869496, 5607.186957891083, 23635.645465318034, 24060.278191654103, 24145.396365091012, 24409.0, 24664.630030549557, 2391.449966507713, 2399.292208798391, 2441.084593277521, 2452.080469823816, 2584.5606246938064, 10701.09040731531, 10780.5687772489, 11124.38815865127, 11410.315381077162, 11596.072314214927, 1741.6429631925425, 1754.2632094520332, 1897.7722673279156, 1979.8218160365636, 2001.159513004527, 82494.58752706258, 84559.0, 84979.0, 88796.0, 92935.07064929332, 10972.835407468894, 11696.298222106701, 11858.229215100991, 12299.200551964093, 12321.568745094059, 15574.532764017098, 16303.673926521404, 16307.91846040786, 16429.424970125987, 17066.402675906284, 7693.6922523099565, 8926.241269492037, 9101.499417531966, 9172.954135229282, 9235.538223088819, 8765.38642295046, 8794.428362156566, 8864.024957254696, 8988.55057247135, 9051.321150913707, 5148.433672296044, 5179.715115668599, 5253.947460525423, 5282.392524859613, 5287.68273903567, 1893.1386219270198, 2000.2699014859763, 2037.5924815037051, 2101.443117573844, 2176.738791345049, 944.5985456181096, 1043.89561043895, 1102.3165637304762, 1103.707517507861, 1115.8549388579595, 20017.699734503993, 20637.896810515853, 20739.377818665464, 20797.89601051985, 21292.893535532534, 1055.7413433708637, 1065.9979619157966, 1067.3755852826184, 1086.8532748079067, 1109.9223054386116, 11024.173187010845, 11705.180637355474, 11750.647480575597, 11769.764174761698, 11842.171048026728, 433238.0, 450040.0, 503480.0, 503967.0, 504794.0, 84631.57684211663, 102213.0, 104087.47956260321, 105584.47207763912, 108509.4574527138, 1053.167997282154, 1065.0893486069454, 1083.3987137138963, 1087.5595383869545, 1094.1684319916922, 18147.818521814614, 18180.181891814635, 18521.81478185201, 18650.42407701658, 18857.811421885606, 45035.32447013232, 45052.0, 45639.31541026953, 45673.31490027586, 45771.0, 62161.298057708926, 64513.0, 64553.67723161354, 65250.0, 66314.66842665756, 9050.592723327334, 9339.159475647224, 9382.953085234803, 9475.857862132212, 9703.563339649874, 13592.524261650795, 14204.076735012191, 14249.950129662793, 14517.346719397863, 14780.852191477947, 11908.166428349932, 12048.51805927753, 12119.878801211875, 12293.016558675463, 12575.559855405018, 11349.716257093638, 11656.18406711556, 12112.21270617407, 12247.142700010914, 12309.199902006341, 12483.31341776211, 12556.686082848002, 12712.427940742873, 12779.0, 12899.742005160031, 9240.584173712334, 9414.105659962293, 9464.858027129461, 9669.081437263449, 9696.660616878375, 10705.45294985829, 11856.347900865536, 12428.875711242772, 12765.74468510606, 12849.550265740654], "value": 9791.315647347501, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1393.5053056165068, 1507.449780829997, 1549.1324858079477, 1694.3815507339816, 1822.6810308196063, 7739.806504837382, 8285.0, 8606.91393086069, 8942.105789421055, 8946.821063578724, 8866.335024873128, 8920.821583568324, 8978.416402933804, 8983.056779038205, 9014.729558113251, 2478.8884500197446, 2501.024600405843, 2504.2362079565814, 2507.109975958536, 2508.673872396595, 2310.8151347892212, 2463.802895768343, 2471.3203868936007, 2478.2069737683946, 2481.0323973650293, 2458.4714286428416, 2464.7042354917344, 2469.777720005199, 2472.530219258344, 2485.875706214691, 2398.7001624796812, 2406.4344878953434, 2410.6143017117265, 2466.4450498637843, 2466.691663542057, 2368.171140100968, 2407.687000689899, 2412.8311018228733, 2416.7583241675866, 2455.9386015349583, 8735.126487351214, 8881.24509416694, 8892.377533572653, 8966.103389660982, 9012.909870901272, 5373.946260537423, 5587.3015873015465, 5597.3003374578175, 5599.8880022399735, 5600.803971861006, 3664.120611053328, 3682.963170368289, 3704.0369503928905, 3726.571444283888, 3732.5520937487677, 1215.5745489078752, 1240.0, 1255.3033066648006, 1274.3691872523161, 1275.0, 12029.819552706716, 12123.33321667308, 12164.756704865942, 12194.329311887843, 12202.938985305105, 4706.082313948816, 4719.00802558936, 4733.840209148781, 4834.516548345207, 4873.098476781806, 9841.901580984242, 9851.45816980073, 9854.950725246328, 9856.063673950963, 9887.209023278121, 5403.918941215885, 5431.402545720047, 5434.8913021739345, 5449.182622606561, 5460.34475862891, 4387.692861499728, 4506.3465797459185, 4925.21196608541, 5004.549590536869, 5066.03745288394, 3962.2867883781196, 4047.3726572380738, 4090.1615168890307, 4109.691773117028, 4167.937480937789, 1167.1538134852165, 1167.550493060178, 1187.5190547828142, 1190.8451901252781, 1198.1972078707113, 958.6213445689053, 986.7335819328885, 1011.8886922438526, 1029.629333439961, 1053.6048981631889, 5975.0, 6011.308699499614, 6032.969835150796, 6124.0, 6278.080158337457, 496.48117716985837, 501.0430078549952, 504.3821318884337, 524.609166171205, 524.9527542521191, 4078.0, 4111.753294802322, 4190.392393102983, 4229.809658565372, 4235.856318793908, 10751.247412681194, 11011.504482298316, 11072.612458563912, 11073.667789966314, 11077.778444431227, 10930.890691093146, 10965.396903170402, 10998.725031874266, 11005.229633925708, 11017.944910275397, 8009.519428834288, 8288.58557072144, 8350.331973442231, 8372.497650141011, 8394.45436046655, 6216.0, 6253.593516421418, 6320.747170113144, 6380.521460890544, 6457.806265812033, 10055.245856560934, 10223.1821454282, 10378.117859981987, 10395.376277423378, 10399.1160751337, 10479.842802358124, 10544.578216871547, 10547.736306592396, 10563.366198028143, 10570.0, 7235.312645298688, 7457.2542745724995, 7839.804004899923, 8038.236367545074, 8131.390145738975, 8542.145785421657, 8995.23540499065, 9003.819923601623, 9004.549772511216, 9007.90992090097, 7730.884036739566, 7904.604769761604, 7962.402819788655, 8029.156938521372, 8227.794305142419, 7738.419618528746, 7756.806079848049, 7843.529388236724, 7862.4103192261955, 7932.36541076724, 7097.041899343522, 7188.28117188277, 7212.459065570209, 7217.7473788417155, 7223.747168849065, 5247.55395791362, 5267.394249661338, 5294.470552944675, 5415.350157981067, 5443.101888188405, 5241.475852414728, 5275.340582427197, 5326.573874090141, 5357.491038351351, 5372.5433338166695], "value": 4683.605091918963, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [333.3050589520849, 340.31256861140497, 346.8820600995661, 347.6176206173209, 348.5277449056528, 5444.727763611819, 5492.972535137324, 5507.228987941681, 5533.529649979757, 5572.136318870576, 5113.565346945505, 5234.92147617786, 5282.683039017662, 5412.377576578695, 5458.890822183554, 4271.466066741657, 4288.828446862121, 4298.935515967248, 4308.719933204347, 4317.309230523117, 4303.203907277164, 4305.74165550068, 4308.849190278341, 4310.4611923509565, 4312.266914624522, 4101.5488296287385, 4210.452641156646, 4221.451211342521, 4239.47006624172, 4290.892727681801, 4208.600182982612, 4222.9577704222875, 4227.689416280963, 4227.936580951273, 4239.7668128252935, 3734.60786617404, 3736.159364143073, 3737.215184811193, 3738.476613274144, 3739.139997800494, 5185.585153187736, 5270.209468579706, 5296.735163241822, 5325.307709997675, 5327.200919862013, 4797.736124513151, 4829.589484893753, 4840.927386089212, 4853.4418541867435, 4855.814883302528, 5607.299087614048, 5612.979327442117, 5631.352394474647, 5665.745041473144, 5699.515541179005, 3955.5055618047745, 4073.9388909166387, 4123.690723195772, 4125.504070037644, 4128.483939507561, 5842.532597392241, 5846.678432686201, 5878.353381128072, 5908.231929849135, 5912.881742365172, 4138.5654506276405, 4148.149629325981, 4150.626443620089, 4173.332266837291, 4190.20386126635, 4006.1587066715733, 4221.387898754663, 4222.282212023963, 4230.0, 4241.851535196253, 4794.688345257547, 4833.613310935117, 4848.418189817174, 4848.781804818792, 4849.636277279218, 5696.458836410535, 5723.484886360165, 5726.3700992890745, 5738.397468265855, 5749.885002300014, 1429.9428022879176, 1449.681070164562, 1454.5854431486975, 1457.8031965684497, 1472.5655931500232, 903.8327909336663, 917.40368760305, 920.6742291100793, 925.7824411263348, 934.1965909317981, 4838.467768545457, 4842.854714358575, 4867.756612169448, 4886.242632391985, 4896.755162241873, 5117.309163262912, 5127.0, 5132.230165475134, 5137.434882162959, 5195.896082078338, 4515.119551687455, 4843.685160464559, 4866.7079975201605, 4899.354348651688, 4899.436564795023, 2720.7551320380867, 2736.233854520737, 2737.9726202737716, 2744.67063952327, 2767.7370649788236, 5258.474152584788, 5266.4470230626, 5286.629935904527, 5316.043112239757, 5316.255724198615, 5227.0, 5227.0, 5274.367075950834, 5544.500994910478, 5987.970060149672, 6020.217371741734, 6089.985457198715, 6096.817095487054, 6105.45050945417, 6115.969420152871, 5898.233229680114, 5920.733566989582, 5921.63134746105, 5935.792247271324, 5944.910826337695, 5517.586181036519, 5776.0, 5830.443429508082, 5861.677607731445, 5888.205092312483, 6095.481884039907, 6096.695165241631, 6099.176611157614, 6103.481204097701, 6112.82994536933, 3962.8216730247786, 4173.686973477062, 4203.327467605169, 4214.059854036485, 4229.323308270662, 5605.598600349913, 5702.6293290935955, 5773.0, 5862.94137058641, 5872.911906321323, 5696.949098324682, 5744.0, 5748.281464816898, 5755.5971082025535, 5767.221425107725, 5597.608167428241, 5725.6850873202375, 5727.627704199213, 5743.4256574342235, 5751.166080918159, 5703.258576385043, 5710.17346413528, 5714.285714285715, 5726.370099289158, 5727.59208271078, 5763.7118144093465, 5771.249737534094, 5780.364159942319, 5784.161296612052, 5807.506361959281, 5447.945520544902, 5625.690587017753, 5641.238432811518, 5643.35101463337, 5655.293088363955], "value": 4267.26108029012, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [765.8430021845522, 777.6111944027986, 796.3469954637197, 815.5595978171788, 878.5607196401797, 14973.251337433125, 16251.593710157222, 16453.17734113294, 16588.170591470407, 16727.163641817908, 14797.704045919076, 14966.476173333935, 14967.850321496782, 14979.400823967055, 15000.549983500512, 728.3699599846121, 744.240874308208, 846.5894041389921, 857.536930057768, 861.1603686405767, 851.6423102297019, 862.1163307609719, 864.8097418567905, 871.8997315308726, 875.7329014650529, 914.2685851318952, 918.0131358789329, 918.9540522973858, 919.6827094652334, 920.9493477858715, 747.5850902748972, 765.4794739577086, 911.2300106410073, 912.4525284829116, 913.1781396742952, 653.7483069018415, 667.552739664426, 672.9798106056777, 673.2224280955504, 674.0596867369991, 15517.5344739658, 15541.222938853125, 15553.688926221412, 15569.532914012598, 15569.92215038929, 13897.305134743223, 14071.788923166161, 14560.199189044599, 14611.123332600078, 14994.0, 13461.326933653278, 13697.383617737127, 13714.725705485935, 14035.50875719345, 14226.644333891632, 4582.541745825424, 4632.143053535106, 4710.034442939189, 4730.526947305242, 4752.59602933751, 88005.55997219973, 88007.55996220042, 90081.54959225227, 90629.0, 90678.54660726654, 11713.648590542298, 11752.177347585757, 11837.230580012272, 11949.522019119311, 12096.213746106476, 22671.319860404215, 22677.546449070927, 22794.65808012881, 22798.43003924882, 23017.42456438569, 8601.827963440695, 8678.088800675965, 8700.825983480421, 9248.907510924804, 9320.580573874191, 4916.483769204254, 5030.2957585938, 5036.345275114211, 5140.357455318085, 5179.948200518022, 3364.5457863188644, 3478.32172726321, 3482.407990641597, 3489.7906125632544, 3497.8076205808416, 605.7849463440504, 607.7690477618477, 611.2084850119106, 611.6483022262217, 613.3712944232198, 749.4460939087713, 751.620431681997, 762.1730422491659, 775.4571799740204, 794.5907857453416, 9772.560234789451, 10540.9986051325, 10605.522751476203, 10869.263003197451, 10917.126629869592, 722.8626560953402, 751.6842925971105, 766.4941138848334, 773.8645736996003, 794.3962588432832, 5024.346834911438, 5129.102407078746, 5155.9742201288755, 5193.714345711022, 5209.218617207449, 78178.0, 91501.0849891506, 119241.0, 131257.0, 131523.3423832894, 109896.4505177469, 112665.62003139919, 114735.0, 115097.0, 118889.0, 92907.0, 94320.0, 98748.0, 100152.0, 101035.49482252689, 9358.251339892657, 9891.554880030271, 9896.505174741378, 9938.45338506389, 9957.800843983225, 43932.78033609811, 49565.0, 58288.70855645695, 60125.699371502866, 60285.0, 51915.0, 64268.0, 67856.0, 75789.31580131623, 77430.0, 11143.665690029313, 11364.374959377314, 11510.482028308914, 11573.594924177612, 14091.577252682437, 14053.227072510701, 14457.843367533138, 15355.548943766471, 15490.380384784483, 15519.37922483088, 14908.403663853325, 15427.61430964235, 15659.0, 15925.362985480915, 16172.595685107966, 14586.562403127924, 14633.853661463248, 14743.11541307525, 15188.772168417703, 15370.154641494824, 13966.0, 14034.508792192224, 14448.205348705502, 14517.637059073608, 14699.265036748333, 12496.562895267161, 12850.42173102194, 12915.612531624067, 13076.491972880644, 13248.151975200562, 12574.0, 13253.403596837972, 13414.865851341752, 13483.19100853952, 13767.105138165987], "value": 8013.779495608474, "unit": "score"}], "name": "dromaeo_css", "value": 7575.4231038436155, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 111.0, 112.0, 113.0, 111.0, 112.0, 113.0, 110.0, 110.0, 111.0], "value": 111.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 117.0, 116.0, 156.0, 122.0, 120.0, 122.0, 121.0, 149.0, 117.0], "value": 128.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 159.0, 162.0, 162.0, 159.0, 159.0, 161.0, 162.0, 161.0], "value": 160.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 75.0, 122.0, 75.0, 76.0, 76.0, 75.0, 103.0, 75.0], "value": 82.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 118.0, 81.0, 81.0, 81.0, 106.0, 81.0, 81.0, 119.0], "value": 91.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 116.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 145.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 96.0, 95.0, 95.0, 96.0, 97.0, 95.0, 95.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 52.0, 49.0, 48.0, 49.0, 49.0, 49.0, 49.0, 49.0, 49.0], "value": 49.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 60.0, 59.0, 60.0, 60.0, 58.0, 59.0, 59.0, 60.0], "value": 59.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 76.0, 74.0, 75.0, 74.0, 79.0, 75.0, 74.0, 82.0, 73.0], "value": 76.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 120.0, 117.0, 122.0, 128.0, 120.0, 120.0, 119.0, 171.0, 130.0], "value": 127.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 156.0, 159.0, 154.0, 156.0, 153.0, 151.0, 153.0, 151.0, 153.0], "value": 154.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 63.0, 62.0, 62.0, 63.0, 62.0, 62.0, 61.0, 61.0], "value": 62.2, "unit": "score"}], "name": "kraken", "value": 1460.8000000000002, "alertThreshold": 2.0}]} 23:23:39 INFO - Return code: 0 23:23:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:23:39 INFO - # TBPL SUCCESS # 23:23:39 INFO - Running post-action listener: _resource_record_post_action 23:23:39 INFO - [mozharness: 2016-11-13 07:23:39.928367Z] Finished run-tests step (success) 23:23:39 INFO - Running post-run listener: _resource_record_post_run 23:23:40 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 7929856; Write bytes: 377749504; Read time: 2356; Write time: 299436 23:23:40 INFO - TinderboxPrint: CPU usage
13.0% 23:23:40 INFO - TinderboxPrint: I/O read bytes / time
7,929,856 / 2,356 23:23:40 INFO - TinderboxPrint: I/O write bytes / time
377,749,504 / 299,436 23:23:40 INFO - TinderboxPrint: CPU idle
8,272.9 (87.0%) 23:23:40 INFO - TinderboxPrint: CPU user
1,185.4 (12.5%) 23:23:40 INFO - TinderboxPrint: Swap in / out
0 / 0 23:23:40 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 12288; Write bytes: 47398912; Read time: 116; Write time: 33096 23:23:40 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 5894144; Write bytes: 261033984; Read time: 1852; Write time: 170172 23:23:40 INFO - Running post-run listener: _upload_blobber_files 23:23:40 INFO - Blob upload gear active. 23:23:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:23:40 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 23:23:40 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-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:23:40 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-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:23:40 INFO - (blobuploader) - INFO - Open directory for files ... 23:23:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:23:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:23:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:23:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:23:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:23:41 INFO - (blobuploader) - INFO - Done attempting. 23:23:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:23:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:23:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:23:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:23:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:23:42 INFO - (blobuploader) - INFO - Done attempting. 23:23:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:23:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:23:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:23:46 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:23:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:23:46 INFO - (blobuploader) - INFO - Done attempting. 23:23:46 INFO - (blobuploader) - INFO - Iteration through files over. 23:23:46 INFO - Return code: 0 23:23:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:23:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:23:46 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/64677e398f69490ca1027948da48aa9c5f744503941030d77e99b1ce2a0aebc3ac1a9e4383a6edd95e9ffcbc80366b0656fd794d4784be270bdab7b813bac4fd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0711b30bbfa26d8f19bf8b597c35ae85b29252bf994bea73c9da962d85618c0fc4cea6cb81da83997e99e04501ff4c6ae0f5b7c4f3e40f79fc0b2dfc6bf2ea25", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/28bccc729bc768e2ddb1714e93a6b7cd30410c37a686e2836033d029ca31dc231d2025b358ef448db5bf1ec45494ab8b973818ad14d32cfd28ff309fe1d4d5b6"} 23:23:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:23:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:23:46 INFO - Contents: 23:23:46 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/64677e398f69490ca1027948da48aa9c5f744503941030d77e99b1ce2a0aebc3ac1a9e4383a6edd95e9ffcbc80366b0656fd794d4784be270bdab7b813bac4fd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0711b30bbfa26d8f19bf8b597c35ae85b29252bf994bea73c9da962d85618c0fc4cea6cb81da83997e99e04501ff4c6ae0f5b7c4f3e40f79fc0b2dfc6bf2ea25", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/28bccc729bc768e2ddb1714e93a6b7cd30410c37a686e2836033d029ca31dc231d2025b358ef448db5bf1ec45494ab8b973818ad14d32cfd28ff309fe1d4d5b6"} 23:23:46 INFO - Running post-run listener: copy_logs_to_upload_dir 23:23:46 INFO - Copying logs to upload dir... 23:23:46 INFO - mkdir: /builds/slave/test/build/upload/logs 23:23:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1237.413476 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-11-12 23:23:46.995106) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-12 23:23:47.003458) ========= 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=0e2a256762fed810e2f0f810000001b3-1479020533.603996-940162487 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-Aurora/sha512/64677e398f69490ca1027948da48aa9c5f744503941030d77e99b1ce2a0aebc3ac1a9e4383a6edd95e9ffcbc80366b0656fd794d4784be270bdab7b813bac4fd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0711b30bbfa26d8f19bf8b597c35ae85b29252bf994bea73c9da962d85618c0fc4cea6cb81da83997e99e04501ff4c6ae0f5b7c4f3e40f79fc0b2dfc6bf2ea25", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/28bccc729bc768e2ddb1714e93a6b7cd30410c37a686e2836033d029ca31dc231d2025b358ef448db5bf1ec45494ab8b973818ad14d32cfd28ff309fe1d4d5b6"} build_url:https://queue.taskcluster.net/v1/task/E_1Ylpp7Scy-axU0GHGprw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011318 build_url: 'https://queue.taskcluster.net/v1/task/E_1Ylpp7Scy-axU0GHGprw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/64677e398f69490ca1027948da48aa9c5f744503941030d77e99b1ce2a0aebc3ac1a9e4383a6edd95e9ffcbc80366b0656fd794d4784be270bdab7b813bac4fd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0711b30bbfa26d8f19bf8b597c35ae85b29252bf994bea73c9da962d85618c0fc4cea6cb81da83997e99e04501ff4c6ae0f5b7c4f3e40f79fc0b2dfc6bf2ea25", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/28bccc729bc768e2ddb1714e93a6b7cd30410c37a686e2836033d029ca31dc231d2025b358ef448db5bf1ec45494ab8b973818ad14d32cfd28ff309fe1d4d5b6"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-12 23:23:47.029497) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-12 23:23:47.029821) ========= 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=0e2a256762fed810e2f0f810000001b3-1479020533.603996-940162487 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004484 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-12 23:23:47.112778) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-12 23:23:47.116153) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-12 23:23:47.116450) ========= ========= Total master_lag: 0.22 =========