builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-004 starttime: 1481496183.88 results: success (0) buildid: 20161211134251 builduid: 5dab4e0f43c44a9e9fac6a5e5291ea97 revision: 374b5032f8f43f10d533a0d7b827ad926e4fb1f3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-11 14:43:03.880845) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-11 14:43:03.881299) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-11 14:43:03.881777) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-11 14:43:03.930092) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-11 14:43:03.930444) ========= 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=c6927cf182745ad25b1d38b9000001fe-1481484085.398759-241147463 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-11 14:43:04-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 45.0M=0s 2016-12-11 14:43:05 (45.0 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.118063 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-11 14:43:04.063432) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-11 14:43:04.063757) ========= 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=c6927cf182745ad25b1d38b9000001fe-1481484085.398759-241147463 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016809 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-11 14:43:04.113661) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-11 14:43:04.114054) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 374b5032f8f43f10d533a0d7b827ad926e4fb1f3 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 374b5032f8f43f10d533a0d7b827ad926e4fb1f3 --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=c6927cf182745ad25b1d38b9000001fe-1481484085.398759-241147463 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-11 14:43:05,122 truncating revision to first 12 chars 2016-12-11 14:43:05,122 Setting DEBUG logging. 2016-12-11 14:43:05,122 attempt 1/10 2016-12-11 14:43:05,122 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/374b5032f8f4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-11 14:43:05,904 unpacking tar archive at: mozilla-central-374b5032f8f4/testing/mozharness/ program finished with exit code 0 elapsedTime=1.155055 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-11 14:43:05.285163) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-11 14:43:05.285890) ========= script_repo_revision: 374b5032f8f43f10d533a0d7b827ad926e4fb1f3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-11 14:43:05.286385) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-11 14:43:05.286706) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-11 14:43:05.299634) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2016-12-11 14:43:05.299959) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 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', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] 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=c6927cf182745ad25b1d38b9000001fe-1481484085.398759-241147463 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:43:06 INFO - MultiFileLogger online at 20161211 14:43:06 in /builds/slave/test 14:43:06 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 14:43:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:43:06 INFO - {'append_to_log': False, 14:43:06 INFO - 'base_work_dir': '/builds/slave/test', 14:43:06 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 14:43:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:43:06 INFO - 'branch': 'Firefox-Non-PGO', 14:43:06 INFO - 'buildbot_json_path': 'buildprops.json', 14:43:06 INFO - 'config_files': ('talos/linux_config.py',), 14:43:06 INFO - 'default_actions': ('clobber', 14:43:06 INFO - 'read-buildbot-config', 14:43:06 INFO - 'download-and-extract', 14:43:06 INFO - 'populate-webroot', 14:43:06 INFO - 'create-virtualenv', 14:43:06 INFO - 'install', 14:43:06 INFO - 'run-tests'), 14:43:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:43:06 INFO - 'download_minidump_stackwalk': True, 14:43:06 INFO - 'download_symbols': 'ondemand', 14:43:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:43:06 INFO - 'tooltool.py': '/tools/tooltool.py', 14:43:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:43:06 INFO - '/tools/misc-python/virtualenv.py')}, 14:43:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:43:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:43:06 INFO - 'installer_path': 'installer.exe', 14:43:06 INFO - 'log_level': 'info', 14:43:06 INFO - 'log_name': 'talos', 14:43:06 INFO - 'log_to_console': True, 14:43:06 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:43:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:43:06 INFO - 'opt_config_files': (), 14:43:06 INFO - 'pip_index': False, 14:43:06 INFO - 'sps_profile': False, 14:43:06 INFO - 'sps_profile_interval': 0, 14:43:06 INFO - 'suite': 'dromaeojs', 14:43:06 INFO - 'system_bits': '32', 14:43:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:43:06 INFO - 'title': 'talos-linux64-ix-004', 14:43:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:43:06 INFO - 'use_talos_json': True, 14:43:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:43:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:43:06 INFO - 'work_dir': 'build'} 14:43:06 INFO - [mozharness: 2016-12-11 22:43:06.408825Z] Running clobber step. 14:43:06 INFO - Running pre-action listener: _resource_record_pre_action 14:43:06 INFO - Running main action method: clobber 14:43:06 INFO - rmtree: /builds/slave/test/build 14:43:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:43:06 INFO - Running post-action listener: _resource_record_post_action 14:43:06 INFO - [mozharness: 2016-12-11 22:43:06.873787Z] Finished clobber step (success) 14:43:06 INFO - [mozharness: 2016-12-11 22:43:06.873887Z] Running read-buildbot-config step. 14:43:06 INFO - Running pre-action listener: _resource_record_pre_action 14:43:06 INFO - Running main action method: read_buildbot_config 14:43:06 INFO - Using buildbot properties: 14:43:06 INFO - { 14:43:06 INFO - "project": "", 14:43:06 INFO - "product": "firefox", 14:43:06 INFO - "script_repo_revision": "production", 14:43:06 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 14:43:06 INFO - "repository": "", 14:43:06 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 14:43:06 INFO - "buildid": "20161211134251", 14:43:06 INFO - "pgo_build": "False", 14:43:06 INFO - "basedir": "/builds/slave/test", 14:43:06 INFO - "buildnumber": 865, 14:43:06 INFO - "slavename": "talos-linux64-ix-004", 14:43:06 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 14:43:06 INFO - "platform": "ubuntu64_hw", 14:43:06 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 14:43:06 INFO - "branch": "mozilla-central", 14:43:06 INFO - "repo_path": "mozilla-central", 14:43:06 INFO - "revision": "374b5032f8f43f10d533a0d7b827ad926e4fb1f3", 14:43:06 INFO - "stage_platform": "linux64", 14:43:06 INFO - "builduid": "5dab4e0f43c44a9e9fac6a5e5291ea97", 14:43:06 INFO - "slavebuilddir": "test" 14:43:06 INFO - } 14:43:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/Mtp0Yv3oTcWszgWQTimMsA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 14:43:06 INFO - Running post-action listener: _resource_record_post_action 14:43:06 INFO - [mozharness: 2016-12-11 22:43:06.876278Z] Finished read-buildbot-config step (success) 14:43:06 INFO - [mozharness: 2016-12-11 22:43:06.876366Z] Running download-and-extract step. 14:43:06 INFO - Running pre-action listener: _resource_record_pre_action 14:43:06 INFO - Running main action method: download_and_extract 14:43:06 INFO - mkdir: /builds/slave/test/build/tests 14:43:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:43:06 INFO - trying https://queue.taskcluster.net/v1/task/Mtp0Yv3oTcWszgWQTimMsA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 14:43:06 INFO - Downloading https://queue.taskcluster.net/v1/task/Mtp0Yv3oTcWszgWQTimMsA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 14:43:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Mtp0Yv3oTcWszgWQTimMsA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 14:43:07 INFO - Downloaded 1361 bytes. 14:43:07 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 14:43:07 INFO - Using the following test package requirements: 14:43:07 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 14:43:07 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 14:43:07 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 14:43:07 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 14:43:07 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 14:43:07 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 14:43:07 INFO - u'jsshell-linux-x86_64.zip'], 14:43:07 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 14:43:07 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 14:43:07 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 14:43:07 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 14:43:07 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 14:43:07 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 14:43:07 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 14:43:07 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 14:43:07 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 14:43:07 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 14:43:07 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 14:43:07 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 14:43:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Mtp0Yv3oTcWszgWQTimMsA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 14:43:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Mtp0Yv3oTcWszgWQTimMsA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:43:07 INFO - Fetch https://queue.taskcluster.net/v1/task/Mtp0Yv3oTcWszgWQTimMsA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 14:43:08 INFO - Expected file size: 20224488 14:43:08 INFO - Obtained file size: 20224488 14:43:12 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 14:43:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Mtp0Yv3oTcWszgWQTimMsA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 14:43:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Mtp0Yv3oTcWszgWQTimMsA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:43:12 INFO - Fetch https://queue.taskcluster.net/v1/task/Mtp0Yv3oTcWszgWQTimMsA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 14:43:13 INFO - Expected file size: 20224488 14:43:13 INFO - Obtained file size: 20224488 14:43:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Mtp0Yv3oTcWszgWQTimMsA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 14:43:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Mtp0Yv3oTcWszgWQTimMsA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 14:43:17 INFO - Fetch https://queue.taskcluster.net/v1/task/Mtp0Yv3oTcWszgWQTimMsA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 14:43:18 INFO - Expected file size: 14409734 14:43:18 INFO - Obtained file size: 14409734 14:43:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:43:18 INFO - trying https://queue.taskcluster.net/v1/task/Mtp0Yv3oTcWszgWQTimMsA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 14:43:18 INFO - Downloading https://queue.taskcluster.net/v1/task/Mtp0Yv3oTcWszgWQTimMsA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 14:43:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Mtp0Yv3oTcWszgWQTimMsA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 14:43:19 INFO - Downloaded 55779438 bytes. 14:43:19 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Mtp0Yv3oTcWszgWQTimMsA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 14:43:19 INFO - mkdir: /builds/slave/test/properties 14:43:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:43:19 INFO - Writing to file /builds/slave/test/properties/build_url 14:43:19 INFO - Contents: 14:43:19 INFO - build_url:https://queue.taskcluster.net/v1/task/Mtp0Yv3oTcWszgWQTimMsA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 14:43:20 INFO - Running post-action listener: _resource_record_post_action 14:43:20 INFO - Running post-action listener: set_extra_try_arguments 14:43:20 INFO - [mozharness: 2016-12-11 22:43:20.247331Z] Finished download-and-extract step (success) 14:43:20 INFO - [mozharness: 2016-12-11 22:43:20.247554Z] Running populate-webroot step. 14:43:20 INFO - Running pre-action listener: _resource_record_pre_action 14:43:20 INFO - Running main action method: populate_webroot 14:43:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:43:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:43:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:43:20 INFO - '--tppagecycles', 14:43:20 INFO - '7'], 14:43:20 INFO - 'tests': ['tsvgm']}}, 14:43:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:43:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:43:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:43:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:43:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:43:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:43:20 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 14:43:20 INFO - 'talos_options': ['--disable-e10s'], 14:43:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:43:20 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:43:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:43:20 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 14:43:20 INFO - 'talos_options': ['--disable-e10s'], 14:43:20 INFO - 'tests': ['damp', 'tps']}, 14:43:20 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:43:20 INFO - 'tests': ['damp', 'tps']}, 14:43:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:43:20 INFO - 'tests': ['dromaeo_dom']}, 14:43:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:43:20 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:43:20 INFO - 'tests': ['basic_compositor_video']}, 14:43:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:43:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:43:20 INFO - 'tests': ['a11yr', 14:43:20 INFO - 'ts_paint', 14:43:20 INFO - 'tpaint', 14:43:20 INFO - 'sessionrestore', 14:43:20 INFO - 'sessionrestore_no_auto_restore', 14:43:20 INFO - 'tabpaint']}, 14:43:20 INFO - 'other-e10s': {'tests': ['a11yr', 14:43:20 INFO - 'ts_paint', 14:43:20 INFO - 'tpaint', 14:43:20 INFO - 'sessionrestore', 14:43:20 INFO - 'sessionrestore_no_auto_restore', 14:43:20 INFO - 'tabpaint']}, 14:43:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:43:20 INFO - 'ts_paint', 14:43:20 INFO - 'tpaint', 14:43:20 INFO - 'sessionrestore', 14:43:20 INFO - 'sessionrestore_no_auto_restore', 14:43:20 INFO - 'tabpaint']}, 14:43:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:43:20 INFO - 'ts_paint', 14:43:20 INFO - 'tpaint', 14:43:20 INFO - 'sessionrestore', 14:43:20 INFO - 'sessionrestore_no_auto_restore', 14:43:20 INFO - 'tabpaint']}, 14:43:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:43:20 INFO - 'tests': ['a11yr', 14:43:20 INFO - 'ts_paint', 14:43:20 INFO - 'tpaint', 14:43:20 INFO - 'sessionrestore', 14:43:20 INFO - 'sessionrestore_no_auto_restore', 14:43:20 INFO - 'tabpaint']}, 14:43:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:43:20 INFO - 'tests': ['a11yr', 14:43:20 INFO - 'ts_paint', 14:43:20 INFO - 'tpaint', 14:43:20 INFO - 'sessionrestore', 14:43:20 INFO - 'sessionrestore_no_auto_restore', 14:43:20 INFO - 'tabpaint']}, 14:43:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:43:20 INFO - 'tests': ['tsvgx', 14:43:20 INFO - 'tsvgr_opacity', 14:43:20 INFO - 'tart', 14:43:20 INFO - 'tscrollx', 14:43:20 INFO - 'cart', 14:43:20 INFO - 'tsvg_static']}, 14:43:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:43:20 INFO - 'tsvgr_opacity', 14:43:20 INFO - 'tart', 14:43:20 INFO - 'tscrollx', 14:43:20 INFO - 'cart', 14:43:20 INFO - 'tsvg_static']}, 14:43:20 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 14:43:20 INFO - 'talos_options': ['--disable-e10s'], 14:43:20 INFO - 'tests': ['tp5o']}, 14:43:20 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:43:20 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 14:43:20 INFO - 'talos_options': ['--disable-e10s', 14:43:20 INFO - '--xperf_path', 14:43:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:43:20 INFO - 'tests': ['tp5n']}, 14:43:20 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:43:20 INFO - 'talos_options': ['--xperf_path', 14:43:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:43:20 INFO - 'tests': ['tp5n']}}, 14:43:20 INFO - 'talos.zip': {'path': '', 14:43:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:43:20 INFO - Running post-action listener: _resource_record_post_action 14:43:20 INFO - [mozharness: 2016-12-11 22:43:20.263690Z] Finished populate-webroot step (success) 14:43:20 INFO - [mozharness: 2016-12-11 22:43:20.263776Z] Running create-virtualenv step. 14:43:20 INFO - Running pre-action listener: _resource_record_pre_action 14:43:20 INFO - Running main action method: create_virtualenv 14:43:20 INFO - Creating virtualenv /builds/slave/test/build/venv 14:43:20 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 14:43:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:43:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:43:20 INFO - Using real prefix '/usr' 14:43:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:43:20 INFO - Installing distribute.............................................................................................................................................................................................done. 14:43:22 INFO - Installing pip.................done. 14:43:22 INFO - Return code: 0 14:43:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:43:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:43:23 INFO - Reading from file tmpfile_stdout 14:43:23 INFO - Output received: 14:43:23 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 14:43:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:43: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')]} 14:43:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:43:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:43: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')]} 14:43:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:43:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:43:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4a21f6e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1564a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1702120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1792f20>, '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 0x16ffc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16fff90>, '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': 'c6927cf182745ad25b1d38b9000001fe-1481484085.398759-241147463', '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 14:43:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 14:43:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:43:23 INFO - Using env: {'DISPLAY': ':0', 14:43:23 INFO - 'HOME': '/home/cltbld', 14:43:23 INFO - 'LANG': 'en_US.UTF-8', 14:43:23 INFO - 'LANGUAGE': 'en_US:en', 14:43:23 INFO - 'LOGNAME': 'cltbld', 14:43:23 INFO - 'MAIL': '/var/mail/cltbld', 14:43:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:43:23 INFO - 'MOZ_NO_REMOTE': '1', 14:43:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:43:23 INFO - 'NO_EM_RESTART': '1', 14:43:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:43:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:43:23 INFO - 'PWD': '/builds/slave/test', 14:43:23 INFO - 'SHELL': '/bin/bash', 14:43:23 INFO - 'SHLVL': '1', 14:43:23 INFO - 'TERM': 'linux', 14:43:23 INFO - 'TMOUT': '86400', 14:43:23 INFO - 'USER': 'cltbld', 14:43:23 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1481484085.398759-241147463', 14:43:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:43:23 INFO - '_': '/tools/buildbot/bin/python'} 14:43:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:43:23 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 14:43:23 INFO - Cleaning up... 14:43:23 INFO - Return code: 0 14:43:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:43: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')]} 14:43:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:43:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:43: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')]} 14:43:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:43:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:43:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fb4a21f6e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1564a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1702120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1792f20>, '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 0x16ffc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16fff90>, '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': 'c6927cf182745ad25b1d38b9000001fe-1481484085.398759-241147463', '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 14:43:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 14:43:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:43:23 INFO - Using env: {'DISPLAY': ':0', 14:43:23 INFO - 'HOME': '/home/cltbld', 14:43:23 INFO - 'LANG': 'en_US.UTF-8', 14:43:23 INFO - 'LANGUAGE': 'en_US:en', 14:43:23 INFO - 'LOGNAME': 'cltbld', 14:43:23 INFO - 'MAIL': '/var/mail/cltbld', 14:43:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:43:23 INFO - 'MOZ_NO_REMOTE': '1', 14:43:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:43:23 INFO - 'NO_EM_RESTART': '1', 14:43:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:43:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:43:23 INFO - 'PWD': '/builds/slave/test', 14:43:23 INFO - 'SHELL': '/bin/bash', 14:43:23 INFO - 'SHLVL': '1', 14:43:23 INFO - 'TERM': 'linux', 14:43:23 INFO - 'TMOUT': '86400', 14:43:23 INFO - 'USER': 'cltbld', 14:43:23 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1481484085.398759-241147463', 14:43:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:43:23 INFO - '_': '/tools/buildbot/bin/python'} 14:43:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:43:23 INFO - Downloading/unpacking psutil>=3.1.1 14:43:23 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 14:43:23 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 14:43:23 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 14:43:23 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 14:43:23 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 14:43:23 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 14:43:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:43:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:43:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:43:25 INFO - Installing collected packages: psutil 14:43:25 INFO - Running setup.py install for psutil 14:43:25 INFO - building 'psutil._psutil_linux' extension 14:43:25 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 14:43:25 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 14:43:25 INFO - building 'psutil._psutil_posix' extension 14:43:25 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 14:43:25 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 14:43:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:43:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:43:26 INFO - Successfully installed psutil 14:43:26 INFO - Cleaning up... 14:43:26 INFO - Return code: 0 14:43:26 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:43:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:43:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:43:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:43:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:43:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:43:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:43:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4a21f6e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1564a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1702120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1792f20>, '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 0x16ffc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16fff90>, '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': 'c6927cf182745ad25b1d38b9000001fe-1481484085.398759-241147463', '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 14:43:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:43:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:43:26 INFO - Using env: {'DISPLAY': ':0', 14:43:26 INFO - 'HOME': '/home/cltbld', 14:43:26 INFO - 'LANG': 'en_US.UTF-8', 14:43:26 INFO - 'LANGUAGE': 'en_US:en', 14:43:26 INFO - 'LOGNAME': 'cltbld', 14:43:26 INFO - 'MAIL': '/var/mail/cltbld', 14:43:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:43:26 INFO - 'MOZ_NO_REMOTE': '1', 14:43:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:43:26 INFO - 'NO_EM_RESTART': '1', 14:43:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:43:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:43:26 INFO - 'PWD': '/builds/slave/test', 14:43:26 INFO - 'SHELL': '/bin/bash', 14:43:26 INFO - 'SHLVL': '1', 14:43:26 INFO - 'TERM': 'linux', 14:43:26 INFO - 'TMOUT': '86400', 14:43:26 INFO - 'USER': 'cltbld', 14:43:26 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1481484085.398759-241147463', 14:43:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:43:26 INFO - '_': '/tools/buildbot/bin/python'} 14:43:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:43:26 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:43:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:43:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:43:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:43:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:43:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:43:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:43:28 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:43:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:43:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:43:28 INFO - Installing collected packages: mozsystemmonitor 14:43:28 INFO - Running setup.py install for mozsystemmonitor 14:43:28 INFO - Successfully installed mozsystemmonitor 14:43:28 INFO - Cleaning up... 14:43:28 INFO - Return code: 0 14:43:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:43: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')]} 14:43:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:43:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:43: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')]} 14:43:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:43:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:43: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', '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 0x7fb4a21f6e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1564a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1702120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1792f20>, '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 0x16ffc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16fff90>, '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': 'c6927cf182745ad25b1d38b9000001fe-1481484085.398759-241147463', '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 14:43: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 14:43: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 jsonschema==2.5.1 14:43:28 INFO - Using env: {'DISPLAY': ':0', 14:43:28 INFO - 'HOME': '/home/cltbld', 14:43:28 INFO - 'LANG': 'en_US.UTF-8', 14:43:28 INFO - 'LANGUAGE': 'en_US:en', 14:43:28 INFO - 'LOGNAME': 'cltbld', 14:43:28 INFO - 'MAIL': '/var/mail/cltbld', 14:43:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:43:28 INFO - 'MOZ_NO_REMOTE': '1', 14:43:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:43:28 INFO - 'NO_EM_RESTART': '1', 14:43:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:43:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:43:28 INFO - 'PWD': '/builds/slave/test', 14:43:28 INFO - 'SHELL': '/bin/bash', 14:43:28 INFO - 'SHLVL': '1', 14:43:28 INFO - 'TERM': 'linux', 14:43:28 INFO - 'TMOUT': '86400', 14:43:28 INFO - 'USER': 'cltbld', 14:43:28 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1481484085.398759-241147463', 14:43:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:43:28 INFO - '_': '/tools/buildbot/bin/python'} 14:43:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:43:28 INFO - Downloading/unpacking jsonschema==2.5.1 14:43: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 14:43: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 14:43: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 14:43: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 14:43: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 14:43: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 14:43:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:43:30 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:43:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:43:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:43:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:43:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:43:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:43:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:43:30 INFO - Downloading functools32-3.2.3-2.tar.gz 14:43:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:43:31 INFO - warning: no files found matching '*.txt' 14:43:31 INFO - no previously-included directories found matching 'build' 14:43:31 INFO - no previously-included directories found matching 'dist' 14:43:31 INFO - no previously-included directories found matching '.git*' 14:43:31 INFO - Installing collected packages: jsonschema, functools32 14:43:31 INFO - Running setup.py install for functools32 14:43:31 INFO - warning: no files found matching '*.txt' 14:43:31 INFO - no previously-included directories found matching 'build' 14:43:31 INFO - no previously-included directories found matching 'dist' 14:43:31 INFO - no previously-included directories found matching '.git*' 14:43:31 INFO - Successfully installed jsonschema functools32 14:43:31 INFO - Cleaning up... 14:43:31 INFO - Return code: 0 14:43:31 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:43: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')]} 14:43:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:43:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:43: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')]} 14:43:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:43:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:43: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', '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 0x7fb4a21f6e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1564a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1702120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1792f20>, '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 0x16ffc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16fff90>, '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': 'c6927cf182745ad25b1d38b9000001fe-1481484085.398759-241147463', '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 14:43: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 14:43: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 functools32==3.2.3-2 14:43:31 INFO - Using env: {'DISPLAY': ':0', 14:43:31 INFO - 'HOME': '/home/cltbld', 14:43:31 INFO - 'LANG': 'en_US.UTF-8', 14:43:31 INFO - 'LANGUAGE': 'en_US:en', 14:43:31 INFO - 'LOGNAME': 'cltbld', 14:43:31 INFO - 'MAIL': '/var/mail/cltbld', 14:43:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:43:31 INFO - 'MOZ_NO_REMOTE': '1', 14:43:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:43:31 INFO - 'NO_EM_RESTART': '1', 14:43:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:43:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:43:31 INFO - 'PWD': '/builds/slave/test', 14:43:31 INFO - 'SHELL': '/bin/bash', 14:43:31 INFO - 'SHLVL': '1', 14:43:31 INFO - 'TERM': 'linux', 14:43:31 INFO - 'TMOUT': '86400', 14:43:31 INFO - 'USER': 'cltbld', 14:43:31 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1481484085.398759-241147463', 14:43:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:43:31 INFO - '_': '/tools/buildbot/bin/python'} 14:43:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:43:31 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:43:31 INFO - Cleaning up... 14:43:31 INFO - Return code: 0 14:43:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:43: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')]} 14:43:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:43:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:43: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')]} 14:43:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:43:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:43: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', '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 0x7fb4a21f6e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1564a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1702120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1792f20>, '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 0x16ffc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16fff90>, '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': 'c6927cf182745ad25b1d38b9000001fe-1481484085.398759-241147463', '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 14:43: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:43: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 blobuploader==1.2.4 14:43:31 INFO - Using env: {'DISPLAY': ':0', 14:43:31 INFO - 'HOME': '/home/cltbld', 14:43:31 INFO - 'LANG': 'en_US.UTF-8', 14:43:31 INFO - 'LANGUAGE': 'en_US:en', 14:43:31 INFO - 'LOGNAME': 'cltbld', 14:43:31 INFO - 'MAIL': '/var/mail/cltbld', 14:43:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:43:31 INFO - 'MOZ_NO_REMOTE': '1', 14:43:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:43:31 INFO - 'NO_EM_RESTART': '1', 14:43:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:43:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:43:31 INFO - 'PWD': '/builds/slave/test', 14:43:31 INFO - 'SHELL': '/bin/bash', 14:43:31 INFO - 'SHLVL': '1', 14:43:31 INFO - 'TERM': 'linux', 14:43:31 INFO - 'TMOUT': '86400', 14:43:31 INFO - 'USER': 'cltbld', 14:43:31 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1481484085.398759-241147463', 14:43:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:43:31 INFO - '_': '/tools/buildbot/bin/python'} 14:43:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:43:31 INFO - Downloading/unpacking blobuploader==1.2.4 14:43: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 14:43: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 14:43: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 14:43: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 14:43: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 14:43: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 14:43:33 INFO - Downloading blobuploader-1.2.4.tar.gz 14:43:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:43:33 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:43:33 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 14:43:33 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 14:43:33 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 14:43:33 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 14:43:33 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 14:43:33 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 14:43:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:43:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:43: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 14:43: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 14:43: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 14:43: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 14:43: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 14:43: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 14:43:34 INFO - Downloading docopt-0.6.1.tar.gz 14:43:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:43:34 INFO - Installing collected packages: blobuploader, requests, docopt 14:43:34 INFO - Running setup.py install for blobuploader 14:43:34 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:43:34 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:43:34 INFO - Running setup.py install for requests 14:43:34 INFO - Running setup.py install for docopt 14:43:34 INFO - Successfully installed blobuploader requests docopt 14:43:34 INFO - Cleaning up... 14:43:35 INFO - Return code: 0 14:43:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:43:35 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')]} 14:43:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:43:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:43:35 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')]} 14:43:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:43:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:43:35 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 0x7fb4a21f6e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1564a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1702120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1792f20>, '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 0x16ffc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16fff90>, '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': 'c6927cf182745ad25b1d38b9000001fe-1481484085.398759-241147463', '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 14:43:35 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 14:43:35 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 14:43:35 INFO - Using env: {'DISPLAY': ':0', 14:43:35 INFO - 'HOME': '/home/cltbld', 14:43:35 INFO - 'LANG': 'en_US.UTF-8', 14:43:35 INFO - 'LANGUAGE': 'en_US:en', 14:43:35 INFO - 'LOGNAME': 'cltbld', 14:43:35 INFO - 'MAIL': '/var/mail/cltbld', 14:43:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:43:35 INFO - 'MOZ_NO_REMOTE': '1', 14:43:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:43:35 INFO - 'NO_EM_RESTART': '1', 14:43:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:43:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:43:35 INFO - 'PWD': '/builds/slave/test', 14:43:35 INFO - 'SHELL': '/bin/bash', 14:43:35 INFO - 'SHLVL': '1', 14:43:35 INFO - 'TERM': 'linux', 14:43:35 INFO - 'TMOUT': '86400', 14:43:35 INFO - 'USER': 'cltbld', 14:43:35 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1481484085.398759-241147463', 14:43:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:43:35 INFO - '_': '/tools/buildbot/bin/python'} 14:43:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:43:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:43:35 INFO - Running setup.py (path:/tmp/pip-VcnkQy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:43:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:43:35 INFO - Running setup.py (path:/tmp/pip-1mQf3f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:43:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:43:35 INFO - Running setup.py (path:/tmp/pip-uKjqmb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:43:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:43:35 INFO - Running setup.py (path:/tmp/pip-PSuAN4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:43:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:43:35 INFO - Running setup.py (path:/tmp/pip-cs80JU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:43:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:43:35 INFO - Running setup.py (path:/tmp/pip-taWZA2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:43:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:43:35 INFO - Running setup.py (path:/tmp/pip-MBsn54-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:43:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:43:35 INFO - Running setup.py (path:/tmp/pip-tI97hK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:43:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:43:35 INFO - Running setup.py (path:/tmp/pip-RnYjkB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:43:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:43:35 INFO - Running setup.py (path:/tmp/pip-V3VWej-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:43:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:43:36 INFO - Running setup.py (path:/tmp/pip-Shq3z4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:43:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:43:36 INFO - Running setup.py (path:/tmp/pip-NEgnTB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:43:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:43:36 INFO - Running setup.py (path:/tmp/pip-iy47qE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:43:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:43:36 INFO - Running setup.py (path:/tmp/pip-BexiYg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:43:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:43:36 INFO - Running setup.py (path:/tmp/pip-RVY7Xm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:43:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:43:36 INFO - Running setup.py (path:/tmp/pip-5OehO6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:43:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:43:36 INFO - Running setup.py (path:/tmp/pip-CTQpoJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:43:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:43:36 INFO - Running setup.py install for manifestparser 14:43:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:43:36 INFO - Running setup.py install for mozcrash 14:43:36 INFO - Running setup.py install for mozdebug 14:43:36 INFO - Running setup.py install for mozdevice 14:43:36 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:43:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:43:36 INFO - Running setup.py install for mozfile 14:43:37 INFO - Running setup.py install for mozhttpd 14:43:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:43:37 INFO - Running setup.py install for mozinfo 14:43:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:43:37 INFO - Running setup.py install for mozInstall 14:43:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:43:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:43:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:43:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:43:37 INFO - Running setup.py install for mozleak 14:43:37 INFO - Running setup.py install for mozlog 14:43:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:43:37 INFO - Running setup.py install for moznetwork 14:43:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:43:37 INFO - Running setup.py install for mozprocess 14:43:37 INFO - Running setup.py install for mozprofile 14:43:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:43:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:43:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:43:37 INFO - Running setup.py install for mozrunner 14:43:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:43:38 INFO - Running setup.py install for mozscreenshot 14:43:38 INFO - Running setup.py install for moztest 14:43:38 INFO - Running setup.py install for mozversion 14:43:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:43:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:43:38 INFO - Cleaning up... 14:43:38 INFO - Return code: 0 14:43:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:43:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:43:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:43:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:43:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:43:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:43:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:43:38 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 0x7fb4a21f6e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1564a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1702120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1792f20>, '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 0x16ffc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16fff90>, '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': 'c6927cf182745ad25b1d38b9000001fe-1481484085.398759-241147463', '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 14:43:38 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 14:43:38 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 14:43:38 INFO - Using env: {'DISPLAY': ':0', 14:43:38 INFO - 'HOME': '/home/cltbld', 14:43:38 INFO - 'LANG': 'en_US.UTF-8', 14:43:38 INFO - 'LANGUAGE': 'en_US:en', 14:43:38 INFO - 'LOGNAME': 'cltbld', 14:43:38 INFO - 'MAIL': '/var/mail/cltbld', 14:43:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:43:38 INFO - 'MOZ_NO_REMOTE': '1', 14:43:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:43:38 INFO - 'NO_EM_RESTART': '1', 14:43:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:43:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:43:38 INFO - 'PWD': '/builds/slave/test', 14:43:38 INFO - 'SHELL': '/bin/bash', 14:43:38 INFO - 'SHLVL': '1', 14:43:38 INFO - 'TERM': 'linux', 14:43:38 INFO - 'TMOUT': '86400', 14:43:38 INFO - 'USER': 'cltbld', 14:43:38 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1481484085.398759-241147463', 14:43:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:43:38 INFO - '_': '/tools/buildbot/bin/python'} 14:43:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:43:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:43:38 INFO - Running setup.py (path:/tmp/pip-W4CK4O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:43:38 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)) 14:43:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:43:38 INFO - Running setup.py (path:/tmp/pip-t1iRJ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:43:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 14:43:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:43:38 INFO - Running setup.py (path:/tmp/pip-myq5Vk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:43:38 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)) 14:43:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:43:38 INFO - Running setup.py (path:/tmp/pip-PsBmF9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:43:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 14:43:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:43:38 INFO - Running setup.py (path:/tmp/pip-MywOF4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:43:38 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)) 14:43:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:43:38 INFO - Running setup.py (path:/tmp/pip-hReD5o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:43:39 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)) 14:43:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:43:39 INFO - Running setup.py (path:/tmp/pip-O1vJaY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:43:39 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)) 14:43:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:43:39 INFO - Running setup.py (path:/tmp/pip-BTRDL3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:43:39 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)) 14:43:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:43:39 INFO - Running setup.py (path:/tmp/pip-XCrZVL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:43:39 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)) 14:43:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:43:39 INFO - Running setup.py (path:/tmp/pip-eoJTyM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:43:39 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)) 14:43:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:43:39 INFO - Running setup.py (path:/tmp/pip-2s0x1b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:43:39 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)) 14:43:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:43:39 INFO - Running setup.py (path:/tmp/pip-oiyRUh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:43:39 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)) 14:43:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:43:39 INFO - Running setup.py (path:/tmp/pip-RR9RCj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:43:39 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)) 14:43:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:43:39 INFO - Running setup.py (path:/tmp/pip-tI4hkR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:43:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 14:43:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:43:39 INFO - Running setup.py (path:/tmp/pip-_RpQ9K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:43:39 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)) 14:43:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:43:39 INFO - Running setup.py (path:/tmp/pip-firjxV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:43:39 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)) 14:43:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:43:39 INFO - Running setup.py (path:/tmp/pip-fupknT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:43:39 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)) 14:43:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:43:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:43:39 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)) 14:43:39 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:43:39 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:43:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:43: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 14:43: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 14:43: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 14:43: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 14:43: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 14:43: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 14:43:41 INFO - Downloading blessings-1.6.tar.gz 14:43:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:43:41 INFO - Installing collected packages: blessings 14:43:41 INFO - Running setup.py install for blessings 14:43:41 INFO - Successfully installed blessings 14:43:41 INFO - Cleaning up... 14:43:42 INFO - Return code: 0 14:43:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:43:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:43:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:43:42 INFO - Reading from file tmpfile_stdout 14:43:42 INFO - Current package versions: 14:43:42 INFO - argparse == 1.2.1 14:43:42 INFO - blessings == 1.6 14:43:42 INFO - blobuploader == 1.2.4 14:43:42 INFO - docopt == 0.6.1 14:43:42 INFO - functools32 == 3.2.3-2 14:43:42 INFO - jsonschema == 2.5.1 14:43:42 INFO - manifestparser == 1.1 14:43:42 INFO - mozInstall == 1.12 14:43:42 INFO - mozcrash == 1.0 14:43:42 INFO - mozdebug == 0.1 14:43:42 INFO - mozdevice == 0.49 14:43:42 INFO - mozfile == 1.2 14:43:42 INFO - mozhttpd == 0.7 14:43:42 INFO - mozinfo == 0.9 14:43:42 INFO - mozleak == 0.1 14:43:42 INFO - mozlog == 3.4 14:43:42 INFO - moznetwork == 0.27 14:43:42 INFO - mozprocess == 0.23 14:43:42 INFO - mozprofile == 0.28 14:43:42 INFO - mozrunner == 6.13 14:43:42 INFO - mozscreenshot == 0.1 14:43:42 INFO - mozsystemmonitor == 0.3 14:43:42 INFO - moztest == 0.7 14:43:42 INFO - mozversion == 1.4 14:43:42 INFO - psutil == 3.1.1 14:43:42 INFO - requests == 1.2.3 14:43:42 INFO - wsgiref == 0.1.2 14:43:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:43:42 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')]} 14:43:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:43:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:43:42 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')]} 14:43:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:43:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:43:42 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 0x7fb4a21f6e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1564a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1702120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1792f20>, '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 0x16ffc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16fff90>, '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': 'c6927cf182745ad25b1d38b9000001fe-1481484085.398759-241147463', '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 14:43:42 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 14:43:42 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 14:43:42 INFO - Using env: {'DISPLAY': ':0', 14:43:42 INFO - 'HOME': '/home/cltbld', 14:43:42 INFO - 'LANG': 'en_US.UTF-8', 14:43:42 INFO - 'LANGUAGE': 'en_US:en', 14:43:42 INFO - 'LOGNAME': 'cltbld', 14:43:42 INFO - 'MAIL': '/var/mail/cltbld', 14:43:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:43:42 INFO - 'MOZ_NO_REMOTE': '1', 14:43:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:43:42 INFO - 'NO_EM_RESTART': '1', 14:43:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:43:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:43:42 INFO - 'PWD': '/builds/slave/test', 14:43:42 INFO - 'SHELL': '/bin/bash', 14:43:42 INFO - 'SHLVL': '1', 14:43:42 INFO - 'TERM': 'linux', 14:43:42 INFO - 'TMOUT': '86400', 14:43:42 INFO - 'USER': 'cltbld', 14:43:42 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1481484085.398759-241147463', 14:43:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:43:42 INFO - '_': '/tools/buildbot/bin/python'} 14:43:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:43:42 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)) 14:43:42 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)) 14:43:42 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)) 14:43:42 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)) 14:43:42 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)) 14:43:42 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)) 14:43:42 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)) 14:43:42 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)) 14:43:42 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)) 14:43:42 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)) 14:43:42 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)) 14:43:42 INFO - Cleaning up... 14:43:42 INFO - Return code: 0 14:43:42 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:43:42 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')]} 14:43:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:43:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:43:42 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')]} 14:43:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:43:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:43:42 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 0x7fb4a21f6e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1564a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1702120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1792f20>, '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 0x16ffc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16fff90>, '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': 'c6927cf182745ad25b1d38b9000001fe-1481484085.398759-241147463', '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 14:43:42 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 14:43:42 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 14:43:42 INFO - Using env: {'DISPLAY': ':0', 14:43:42 INFO - 'HOME': '/home/cltbld', 14:43:42 INFO - 'LANG': 'en_US.UTF-8', 14:43:42 INFO - 'LANGUAGE': 'en_US:en', 14:43:42 INFO - 'LOGNAME': 'cltbld', 14:43:42 INFO - 'MAIL': '/var/mail/cltbld', 14:43:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:43:42 INFO - 'MOZ_NO_REMOTE': '1', 14:43:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:43:42 INFO - 'NO_EM_RESTART': '1', 14:43:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:43:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:43:42 INFO - 'PWD': '/builds/slave/test', 14:43:42 INFO - 'SHELL': '/bin/bash', 14:43:42 INFO - 'SHLVL': '1', 14:43:42 INFO - 'TERM': 'linux', 14:43:42 INFO - 'TMOUT': '86400', 14:43:42 INFO - 'USER': 'cltbld', 14:43:42 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1481484085.398759-241147463', 14:43:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:43:42 INFO - '_': '/tools/buildbot/bin/python'} 14:43:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:43:42 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:43:42 INFO - Cleaning up... 14:43:42 INFO - Return code: 0 14:43:42 INFO - Running post-action listener: _resource_record_post_action 14:43:42 INFO - Running post-action listener: _start_resource_monitoring 14:43:42 INFO - Starting resource monitoring. 14:43:42 INFO - [mozharness: 2016-12-11 22:43:42.781502Z] Finished create-virtualenv step (success) 14:43:42 INFO - [mozharness: 2016-12-11 22:43:42.782017Z] Running install step. 14:43:42 INFO - Running pre-action listener: _resource_record_pre_action 14:43:42 INFO - Running main action method: install 14:43:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:43:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:43:42 INFO - Reading from file tmpfile_stdout 14:43:42 INFO - Detecting whether we're running mozinstall >=1.0... 14:43:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:43:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:43:43 INFO - Reading from file tmpfile_stdout 14:43:43 INFO - Output received: 14:43:43 INFO - Usage: mozinstall [options] installer 14:43:43 INFO - Options: 14:43:43 INFO - -h, --help show this help message and exit 14:43:43 INFO - -d DEST, --destination=DEST 14:43:43 INFO - Directory to install application into. [default: 14:43:43 INFO - "/builds/slave/test"] 14:43:43 INFO - --app=APP Application being installed. [default: firefox] 14:43:43 INFO - mkdir: /builds/slave/test/build/application 14:43:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 14:43:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 14:43:59 INFO - Reading from file tmpfile_stdout 14:43:59 INFO - Output received: 14:43:59 INFO - /builds/slave/test/build/application/firefox/firefox 14:43:59 INFO - Running post-action listener: _resource_record_post_action 14:43:59 INFO - [mozharness: 2016-12-11 22:43:59.145038Z] Finished install step (success) 14:43:59 INFO - [mozharness: 2016-12-11 22:43:59.145278Z] Running run-tests step. 14:43:59 INFO - Running pre-action listener: _resource_record_pre_action 14:43:59 INFO - Running main action method: run_tests 14:43:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:43:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:43:59 INFO - Python 2.7.3 14:43:59 INFO - Return code: 0 14:43:59 INFO - grabbing minidump binary from tooltool 14:43:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:43:59 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1792f20>, '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 0x16ffc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16fff90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:43:59 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 14:43:59 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 14:43:59 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 14:43:59 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:43:59 INFO - Return code: 0 14:43:59 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:43:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:43:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:43:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:43:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:43:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Mtp0Yv3oTcWszgWQTimMsA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--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 14:43:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Mtp0Yv3oTcWszgWQTimMsA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-004 --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 14:43:59 INFO - Using env: {'DISPLAY': ':0', 14:43:59 INFO - 'HOME': '/home/cltbld', 14:43:59 INFO - 'LANG': 'en_US.UTF-8', 14:43:59 INFO - 'LANGUAGE': 'en_US:en', 14:43:59 INFO - 'LOGNAME': 'cltbld', 14:43:59 INFO - 'MAIL': '/var/mail/cltbld', 14:43:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:43:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:43:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:43:59 INFO - 'MOZ_NO_REMOTE': '1', 14:43:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:43:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:43:59 INFO - 'NO_EM_RESTART': '1', 14:43:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:43:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:43:59 INFO - 'PWD': '/builds/slave/test', 14:43:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:43:59 INFO - 'SHELL': '/bin/bash', 14:43:59 INFO - 'SHLVL': '1', 14:43:59 INFO - 'TERM': 'linux', 14:43:59 INFO - 'TMOUT': '86400', 14:43:59 INFO - 'USER': 'cltbld', 14:43:59 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1481484085.398759-241147463', 14:43:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:43:59 INFO - '_': '/tools/buildbot/bin/python'} 14:43:59 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Mtp0Yv3oTcWszgWQTimMsA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--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 14:43:59 INFO - mozversion application_buildid: 20161211134251 14:43:59 INFO - mozversion application_changeset: 374b5032f8f43f10d533a0d7b827ad926e4fb1f3 14:43:59 INFO - mozversion application_display_name: Nightly 14:43:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:43:59 INFO - mozversion application_name: Firefox 14:43:59 INFO - mozversion application_remotingname: firefox 14:43:59 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 14:43:59 INFO - mozversion application_vendor: Mozilla 14:43:59 INFO - mozversion application_version: 53.0a1 14:43:59 INFO - mozversion platform_buildid: 20161211134251 14:43:59 INFO - mozversion platform_changeset: 374b5032f8f43f10d533a0d7b827ad926e4fb1f3 14:43:59 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 14:43:59 INFO - mozversion platform_version: 53.0a1 14:43:59 INFO - using testdate: 1481496239 14:43:59 INFO - actual date: 1481496239 14:43:59 INFO - starting webserver on 'localhost:33985' 14:43:59 INFO - SUITE-START | Running 2 tests 14:43:59 INFO - TEST-START | dromaeo_css 14:43:59 INFO - operating with platform_type : linux_ 14:43:59 INFO - Initialising browser for dromaeo_css test... 14:43:59 INFO - TEST-INFO | started process 10818 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIciExP/profile http://localhost:33985/getInfo.html) 14:44:03 INFO - PROCESS | 10818 | __metrics Screen width/height:1600/1200 14:44:03 INFO - PROCESS | 10818 | colorDepth:24 14:44:03 INFO - PROCESS | 10818 | Browser inner width/height: 1024/697 14:44:03 INFO - PROCESS | 10818 | __metrics 14:44:04 INFO - PROCESS | 10818 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:44:04 INFO - PROCESS | 10818 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:44:04 INFO - PROCESS | 10818 | console.error: 14:44:04 INFO - PROCESS | 10818 | Message: Error: Connection closed before committing the transaction. 14:44:04 INFO - PROCESS | 10818 | Stack: 14:44:04 INFO - PROCESS | 10818 | executeTransaction/promise resource://gre/modules/Promise-backend.js:932:23 14:44:04 INFO - PROCESS | 10818 | walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:813:7 14:44:04 INFO - PROCESS | 10818 | Promise*scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:744:11 14:44:04 INFO - PROCESS | 10818 | schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:776:7 14:44:04 INFO - PROCESS | 10818 | completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:711:7 14:44:04 INFO - PROCESS | 10818 | TaskImpl_run@resource://gre/modules/Task.jsm:324:15 14:44:04 INFO - PROCESS | 10818 | observe@resource://gre/modules/AsyncShutdown.jsm:550:9 14:44:04 INFO - PROCESS | 10818 | addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 14:44:04 INFO - PROCESS | 10818 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:386:5 14:44:04 INFO - PROCESS | 10818 | moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 14:44:04 INFO - PROCESS | 10818 | trigger@resource://gre/modules/AsyncShutdown.jsm:719:23 14:44:04 INFO - PROCESS | 10818 | _wait@resource://gre/modules/AsyncShutdown.jsm:866:7 14:44:04 INFO - PROCESS | 10818 | wait@resource://gre/modules/AsyncShutdown.jsm:850:28 14:44:04 INFO - PROCESS | 10818 | observe@resource://gre/modules/AsyncShutdown.jsm:533:17 14:44:04 INFO - PROCESS | 10818 | 14:44:04 INFO - PROCESS | 10818 | console.error: PushService: 14:44:04 INFO - PROCESS | 10818 | stateChangeProcessEnqueue: Error transitioning state 14:44:04 INFO - PROCESS | 10818 | UnknownError 14:44:05 INFO - TEST-INFO | 10818: exit 0 14:44:05 INFO - Browser initialized. 14:44:05 INFO - Running cycle 1/1 for dromaeo_css test... 14:44:05 INFO - TEST-INFO | started process 10904 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIciExP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:44:17 INFO - PROCESS | 10904 | RSS: Main: 173404160 14:44:17 INFO - PROCESS | 10904 | 14:45:09 INFO - PROCESS | 10904 | 1481496309380 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] 14:47:23 INFO - PROCESS | 10904 | Cycle 1(1): loaded http://localhost:33985/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33985/tests/dromaeo/cssquery-ext.html) 14:47:25 INFO - PROCESS | 10904 | RSS: Main: 338616320 14:47:25 INFO - PROCESS | 10904 | 14:50:28 INFO - PROCESS | 10904 | Cycle 1(1): loaded http://localhost:33985/tests/dromaeo/cssquery-ext.html (next: http://localhost:33985/tests/dromaeo/cssquery-jquery.html) 14:50:29 INFO - PROCESS | 10904 | RSS: Main: 277245952 14:50:29 INFO - PROCESS | 10904 | 14:53:32 INFO - PROCESS | 10904 | Cycle 1(1): loaded http://localhost:33985/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33985/tests/dromaeo/cssquery-mootools.html) 14:53:32 INFO - PROCESS | 10904 | RSS: Main: 218824704 14:53:32 INFO - PROCESS | 10904 | 14:56:34 INFO - PROCESS | 10904 | Cycle 1(1): loaded http://localhost:33985/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33985/tests/dromaeo/cssquery-prototype.html) 14:56:35 INFO - PROCESS | 10904 | RSS: Main: 217833472 14:56:35 INFO - PROCESS | 10904 | 14:59:38 INFO - PROCESS | 10904 | Cycle 1(1): loaded http://localhost:33985/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33985/tests/dromaeo/cssquery-yui.html) 14:59:39 INFO - PROCESS | 10904 | RSS: Main: 218120192 14:59:39 INFO - PROCESS | 10904 | 15:02:42 INFO - PROCESS | 10904 | Cycle 1(1): loaded http://localhost:33985/tests/dromaeo/cssquery-yui.html (next: http://localhost:33985/tests/dromaeo/cssquery-dojo.html) 15:02:42 INFO - PROCESS | 10904 | RSS: Main: 312647680 15:02:42 INFO - PROCESS | 10904 | 15:02:42 INFO - PROCESS | 10904 | __start_tp_report 15:02:42 INFO - PROCESS | 10904 | _x_x_mozilla_page_load 15:02:42 INFO - PROCESS | 10904 | _x_x_mozilla_page_load_details 15:02:42 INFO - PROCESS | 10904 | |i|pagename|runs| 15:02:42 INFO - PROCESS | 10904 | |0;dojo.html;2629.355807827082;2643.603459481079;2663.9866800665995;2678.3304173956512;2685.8657067146637;4684.484706682262;4783.2346824507995;4795.184818580841;4835.10550548148;4839.7338146401935;4797.47227804942;4810.855674329767;4843.273508973647;4858.101251268517;4899.730514821684;4385.188740083079;4402.735835849843;4449.777511124447;4498.392716983198;4579.107074120548;4047.271491131595;4413.205622987877;4539.432570928634;4564.15563120822;4616.699914505546;4333;4357.128574285156;4524.547545245482;4563.315502674593;4575.9771201143785;4394.934075988863;4416.293393057095;4422.933655995163;4471.5081341052455;4474.709143905636;4441.222786012468;4448.33275008748;4451.310046942729;4498.752568608726;4503.482099558527;4800.159972004885;4826.686265392774;4844.32179494871;4851.975740121276;4888.633352498577;5019.799208031674;5049.570786483153;5053.620978426633;5099.4900509949075;5201.9479805201845;5170.095233334151;5238.083335416287;5245.580353571705;5296.523312901819;5296.867578310535;4839.346688197119;4855.4901735317635;4858.101251268456;4861.076395484846;4885.780139893714;4981.501849814989;4998.175301075282;5342.839714808562;5346.572274218053;5351.866203344947;4881.755912204376;5035.420926593415;5075.416327122354;5083.440821509631;5162.948370516322;5182.585393168538;5187.974060129675;5189.455107213764;5193.143131383279;5197.870053248699;5149.948500514947;5154.30416893722;5156.587473002151;5157.8452646420665;5160.8967820643375;4962.429320628102;4978.90042199154;4982.252662100714;5059.19052951526;5063.797448102108;4422.601965823092;4446.288593825037;4447.644188464915;4489.73061616304;4496.7301961882395;3855.768653880776;3860.3823388258306;3860.6332398422105;3870.9225815483533;3894.3768996960916;4320.870373888788;4371.103923695635;4459.598636122764;4466.0844526871915;4513.526079761577;5149.9742501288;5168.508991645787;5176.482351764794;5200.089984252741;5252.527272545489;4422.425084738963;4429.579189976947;4450.35469856869;4450.799714012877;4490.50604433512;4557.589816916494;4685.390899183084;4690.9765451172525;4691.976540117277;4694.36626055485;4733.952660473489;4788.95211047899;4809.254565542346;4816.614670826255;4820.132376172322;5196.454372290855;5200.583953283653;5201.531862132426;5243;5244.213367994831;4851.151048566558;4858.927116093332;4860.465348811557;4862;4912.189488734319;4948.901021979612;4986.825461108844;5081.390233172043;5131.127708289525;5176.689398636094;5144.9742751286;5166.095933211748;5166.819161329486;5196.454372290855;5248;5006.974965125151;5045.470225626256;5051.646384753179;5052.1411360069615;5103.336566246364;4922.335484709662;5024.045431368028;5038.521340472649;5039.496050394931;5043.596512279082;4886.975565122151;4938.30863679078;4948.925766113583;4957.405111386656;5042.420121686053;4915.606751459946;4947.604191664586;5073;5077.7715002824225;5084.00861831954;4911.0423467424935;4911.754412279301;4912.189488734319;4913.140200464833;4913.361263035782 15:02:42 INFO - PROCESS | 10904 | |1;ext.html;511.1540400636946;523.8219005538116;525.39054696552;534.6978956889358;542.2056686953612;27414.588781168273;28019.43961120779;36976;38553.61446385535;38803.80598097012;39728.22581854727;40099.59900400995;40246.59753402465;40249.39625905606;40254;7623.961880190592;7798.337141342992;7802.297793198611;7804.3756499479905;7808.570528620923;7370.705171793122;7532.585707786069;9754.170895473857;9977.950110249474;9978.800423991515;9977.700668979942;10017.899821001769;10038.347507411994;10045.397276163454;10051.396916185053;9767.169790567776;9782.853257201077;9803;9807.607695692164;9841.55712992917;9805.460699661517;9896.25778066648;9897.653582124663;9902.80194396108;9907.405555666612;36027.63972360269;36273.637263627286;36286.63713362885;36376;36435.27129457396;2808.2277373722186;2905.970940290591;3011.8192908425344;3221.01758963516;3297.55483009793;25297.62199775992;25546;25720.485590288277;27772.58341124885;27924.720752792415;3411.198368383428;3456.948145777815;3461.13471632092;3463.6363181865795;3503.1592417819793;243542;245900;246219.76890115615;246534;247000.35498757637;45682.771586141855;46053.30920036202;46088.308675369895;46147.07705845864;46148.538514615095;43972.3404148938;44020.3396949046;44025.339619905724;44093.33859992103;44133.558664413584;12532.310722910328;13041.869581304256;13054.412551435209;13055.738885222243;13056.673583160496;8346.749597512084;8466.830663386698;9946.452945087945;10096.34373765703;10104.29269951111;13628.523001695092;13747.175169489663;13770.449182032808;14947.252637368088;15319.540413787605;5981.61119527238;6153.630782153085;6181.938180618136;6259.874802503924;6406.615603063831;736.5359823311279;814.9388795840335;890.8129292848574;906.1934877958622;966.0436168193438;25674.48651026969;27964.300892477444;28044;28065.298367540567;28151.296217594314;892.8437523433373;922.1976880114344;944.6693657220055;954.6372378496127;1031.0205304960318;14057.64213093944;14462.589073634079;14818.481353152589;14854.2572871356;15014.174220417983;619538;627962;629553;630258;633125;36772.26455470876;37643;40477.39283910744;41441.58558414377;41512.37731434031;1138.459231864841;1149.3046706742468;1173.7945859474728;1176.7528818947947;1182.2847177457513;24457;25023.249302520955;25652.230433087036;26003.869980650732;26091.478170436865;36576.81711591425;36974;37142;37167.44248836324;37207.81396093002;330661;332972;334135;334366;335037;23244.651330230397;23340.76659233386;23610.763892361545;23616.88191559031;23621.52756944886;34225.00248237494;34813.47779783356;34876.825615871756;35012.47481287834;35238.47142292817;29609.5558566626;29742.851285743433;30114.69885301119;30352.84823575868;30565;29200.41599168047;30608.387832243676;30953.69046309508;31274.53088203724;32101.357972839945;29562.408751825275;30151.396972060873;30325.545116823705;30399.39201215919;30533.847330763205;26363.59134353428;28150.718492815628;28276.43447131087;28406.28984275327;28939.565906510998;22281;22327.44181395413;22451.887740561848;22520;22648.886755566116 15:02:42 INFO - PROCESS | 10904 | |2;jquery.html;346.8335199104428;360.1159154276252;373.6357051874422;374.4532981846504;392.8958825911132;12501.562445314405;12538.56115035974;14075.788863167048;16709.83290167098;16889.493315200532;14749.852501474981;15935.84064159361;17228.655426891455;17350.56623584411;17367.91316043421;4664.813407463697;4948.084604348189;4963.553280204781;4973.353464049668;4979.950200498003;3664;4891.021795640868;4972.776225069881;4976.427710813248;4979.203327467606;3821.9235615287676;5521.5306698930435;5789.39211382805;5792.218050563183;5901.144334071579;5577.888442231173;5729.914051289234;5881.059030555112;5883.235179426669;5890.764369425217;5123.7438128093445;5128.333316668809;5133.537981581639;5134.0758663440565;5135.050015747099;20278;20495.590088198154;20512.28207012762;20548.38354849357;20648.174073037062;1793.8654600904983;1847.1503108570073;1901.2870173684869;2055.856090073695;2462.495188486356;12529.874701252962;12864.163829351062;13156.60530184096;13365.799513007312;13735.587932362045;1696.7030769615392;1748.589081565831;1805.3951926104771;1882.6799444094536;1900.1354383755358;67046.32953670449;67345.3265467344;67411;68878.65560672215;69075.30924690739;9812.95093524542;9954.352967057119;9989.350692204984;10126.189904807597;10188.949055254676;13651.38568764408;13652.112612680145;13659.658508537168;13682.93158534201;13697.246651434267;6837.316268373223;6958.199807022257;6977.69735987123;7097.858042839218;7160.069191005136;6525.347465253437;6617.503687223478;6746.325367463214;6752.5948443093575;6770.526063175629;4229.365595160689;4296.462942132233;4366.039471316305;4394.340848872695;4416.072624748838;1701.7192163292918;1802.4322338463342;1822.3621732393822;1841.6132612151598;1844.4651051195003;796.9800754981172;821.8068753842842;832.9000519937646;851.7955690634203;896.6323807239;15920.203989800464;16959.23683434224;17388.391406300718;17571.73642395365;17618.735718964224;784.2510402565537;808.975730728079;816.848882956655;829.9917000830035;832.388194676912;9218.907810921939;9345.15893569582;9649.565769540388;9653.662121825702;9903;270300;275353;275381;368267;372177;54493;55290.72354638201;57402.71298643564;66000;75439.62280188563;874.8862647855738;893.2541327991071;916.9908300917039;917.2570218123208;932.38462614674;11406.429678515875;15521.292657807406;16028.839711602735;16370.754438683667;16374.75437868409;34476.655233447345;35488.64511354853;35747.28505429929;36299.63700362963;36365.45451818278;42032;43712;45441;45607;48027.279590806866;7265.782026539212;7302.087239095114;7336.596487193256;7502.3622992046285;7670.964419803255;10398.896011039793;11383.487743051417;11532.538698451968;11624.302541847515;11643.708907277385;9571.994940531144;9897.35667181631;10101.040401161878;10121.544530495998;10497.47512624381;9922.156616687662;9985.400875947467;10021.448820314661;10041.397516149054;10274;7133.037039999741;9991.450470224207;10333.070023697903;10387.169026478015;10453.216008799523;7340.889886651597;7588.430867684835;7615.619219039137;7674;7706.576138312446;9752.707418777449;9964.60141594357;10286.588536458457;10287.897121028695;10464.947675261576 15:02:42 INFO - PROCESS | 10904 | |3;mootools.html;1322.9470821167151;1346.8181795457613;1425.9786103208446;1631.706292867285;1670.2483882252989;7213.5311204771715;7538.057742782153;7538.585377804226;7543.773686789392;7548.5848278344665;6494.350564943504;6655.500837437187;7160.176579693337;7318.7438439654625;7395.889061664079;2110.472381904524;2144.3781303422065;2147.6885851551515;2154.13834466214;2157.7087093242367;2124.925627603034;2139.465133716571;2151.6880052392394;2155.181031208139;2155.81675557577;1920.7983161768022;1937.0218039889905;1942.6406114868723;1943.1255934829312;2053.9486512837148;2045.1308194017558;2061.783512731164;2079.1371580793952;2081.95836083279;2083.2812679625504;2090.2997495838913;2112.5458026524216;2117.608242475147;2118.6186486432434;2122.5118222808687;7375.963120184365;7550;7556.962215188943;7563.546187228729;7566.886496702554;4396.89007774805;4545.909081818345;4548.4541854977615;4551.453825540923;4570.451545814523;2825.1242114944434;2920.1677521906367;3019.3357461358687;3043.132707178466;3048.9695103048907;911.9042500537414;912.4981260306805;951.4243882451086;974.3374505336368;978.1294647763497;9908.20734341251;9939.254555908336;9963.302568820185;9982.401055936594;10029.849552256723;4023.215472982799;4237.766922819213;4273.102648443862;4297.5702429756775;4305.870823875289;8548.786280343042;8576.356773242032;8580.313574913991;8582.87125693115;8590.699325523577;4597.977010114928;4598.965232822588;4599.057193275437;4601.884952876205;4603.332516785047;3769.849206031728;3792.469054332396;4091.97954010228;4153.190127925086;4161.667066634662;3514.68367846895;3549.378858699717;3634.23681026982;3638;3642.817859107034;1173.9823902641467;1201.669540876266;1201.9939900300442;1203.0856549022806;1212.3695678247439;701.6877489517116;822.4736168851939;824.2581676491086;844.8141408890034;878.2271600991214;4940.530649588283;5002.674826136289;5007.4491805901325;5117.667351622133;5181.4041385240425;401.12754758400365;410.347547399636;415.2753445238079;415.5802639334234;419.1721350333068;3270.394976929228;3454.3954807909017;3457.533233013561;3463.8614455421502;3478.0261526772542;8407.62165702545;8844.601992910335;8853.335999800041;8857.29141668665;8866.113388661082;8534.573271336409;8774.649014039367;8816.162464565856;8816.911830881738;8822.823543529223;6746.257911629717;6923.411510021604;6926.064981227571;6928.44572434204;6930.341617546325;4714.3399924011355;4815.638827087912;4898.681585696918;4994.4006719192475;5086.847394578169;7832.765017049498;7959.920400795918;7971.880421793794;8067.435279530377;8090.11008789021;7958.283754462126;8147.592620369076;8486.830263394573;8494.32045436376;8735.868961965703;6332;6508.316626754312;6752.358525940028;7096.574205547684;7112.822179445555;6877.2091209509545;7029.226785053742;7089.893651595334;7200.279972002967;7205.459590530836;6064.241969753781;6456.483481321577;6522.315156908457;6547.410733034049;6630.734770609121;6287.811365659038;6404.295527492065;6432.774852880127;6443.806685799434;6529;6154.107654389999;6226.470749986302;6252.1559589456665;6314.179156709598;6331.113644089739;4270.316749320094;4337.5011873633675;4340.631046361094;4362.629176520031;4626.23667094939;4305.97847010763;4346.826126954887;4398.164348773723;4542.931856022096;4609.562091601292 15:02:42 INFO - PROCESS | 10904 | |4;prototype.html;339.75028354159696;350.47778809573725;353.31985927090346;353.4168621774071;359.05031192495835;4836.8065277388905;4855.611551075915;4893.535114164153;4908.607311415087;4987.177115775901;4882.829100981467;4904.48502907195;4905.803767849282;4909.459959404463;4912.557869791727;3715.2538306995734;3833.501644786174;3842.289176502342;3842.442845787359;3882.2429626222897;3896.4350169225445;3913.2956067907767;3920.4903362562827;3946.151577410856;3951.2097580483874;3882.592327805568;3887;3890.319194141028;3892.70804689648;3914.9804250978846;3672.4491326301136;3776.4712940188397;3776.9433458498142;3891.6692081173132;3893.922121557567;3489.8080605566684;3500.1424650960676;3505.842237099311;3516.683498485123;3519.4896739972814;4736.218523943545;4754;4760.714856868723;4761.357216775726;4762.64280178988;4327.0913108247305;4452.532484089156;4460.973975985541;4463.24124898768;4470.2177119004045;5063.873403164914;5089.068684458788;5100;5100.285959965608;5102.336696229467;3802.125511132427;3805.5243094613174;3808.295465338921;3837.884863454101;3848.364445110845;5138.203578445347;5314.9468505314835;5336.252924590522;5338.145896656555;5369.758360873731;3903.4144878268485;3950.48643676326;3955.624215699504;3956.287868183697;3968.722189446711;4006.819693113817;4010.2981978153707;4010.7165706974074;4015.19696060789;4025.2150830587752;4447.199504089295;4450.287953927356;4451.732896026248;4467.620252278528;4470.173017991682;4977.90044199114;5068.391792984866;5072.492750724898;5099.235114732745;5107.744612769421;1348.7707089794758;1360.768669326217;1363.8704323089291;1368.9589312320647;1372.1629805818443;913.3925939250457;969.1568335547977;970.6408628807386;972.3242346569067;980.7106903463496;4334.93794020467;4434.800433980478;4452.198604251201;4570.520095390003;4580.908381832344;5280.181571856291;5299.834036511961;5313.973430132824;5316.175992721134;5320.973395133;4557.703749256288;4567.474740404829;4569.246074397754;4569.469227808655;4574.222382194968;2558.4499332643572;2728.249731323902;2733.72662733729;2763.6269103671148;2766.192314800479;4777.832775852898;4843.515648435128;4843.830465933675;4846.273059041101;4848.515148485123;5092.344987878954;5221.530062294336;5226.203980870292;5230.712310822942;5288.995090932711;4777.737224452689;4795.95204047955;4836.806527738851;4852.544236728967;4857.635677324144;5104.770285337243;5125.384953805567;5131;5131.332926719502;5131.666441681279;4848.903021939612;4980.783551319628;5017.698938063728;5042.520960508746;5085.440601533903;4786.7502273931195;4797.280407938837;4807.471178170468;4818.373611430632;4827.710337379768;3833.559140698855;3853.2641824305056;3895.24042811649;3918.74528155669;3923.2741942740686;4873.690115116007;5021.322121513695;5041.269015992587;5078.695278283315;5099.719515426539;4888.9755551222015;4907.68100073494;4926.679765815211;4934;4946.86222168896;4904.754762261801;4910.042541704333;4919.556310582535;4927.236278376858;4961.5534601886;4683.672142949961;4861.319415281792;4877.975610121926;4879.926801098058;4915.901681966412;4799.688020278671;4890.877728056827;4941.6293777966075;4954.133026720381;4968.503149685147;4944.381952255968;4963.8262660806695;4972.557958192084;4988.925166122439;5023.497650234947 15:02:42 INFO - PROCESS | 10904 | |5;yui.html;573.0969390518297;603.7072020070268;625.634004107597;655.6656105386252;693.2686016252853;12569.622911312665;14795.556133316004;15410.30653620587;15428.382864685413;16217.108059056747;15049.774253386207;15055.473058442956;15120.697586048273;15231.08613483189;15252.694946101099;618.4619381138393;636.045931103345;745.9552426854392;767.9731209407672;772.3859531672334;748.143375834669;769.7921561178479;771.7607541662103;779.0845756236399;779.7894568466511;804.859149648812;806.1334065879174;806.6047636658025;806.6491076381793;806.9757907262762;649.8310439285773;800.8038030682491;801.0547553886412;801.1387758159943;801.3028665061375;488.6750311043152;572.5791543215734;595.553334998751;597.3399393669976;598.7994022002634;14020.579382618538;14039.438422463089;14055.578332650037;14060.789088163685;14069.36687849039;12166.756664866652;12250.203736757083;12250.571230006906;12378.381080945917;12439.564615238423;11391.829122563167;11409.885901140966;11457.140714446366;11492.482838272213;11527.42362881861;3903.687704983623;3923.548791888912;3933.9803300983313;3949.5852935441653;3990.1820126874213;65350;66343;66823;67505;69396;9341.299402544837;9705.417674939526;9734.075262850018;9735.075167859042;9988.200943924468;17335.306587736348;17409.825901741075;17603.647927041387;17818.643627127643;17840.643187136186;6472.805815825533;6492.870632200509;6566.179227596551;6721.193456785217;6735.057092007123;3604.85580576774;3692.2430901665093;3696.5748938871834;3727.142757165867;3728.217074414349;2606.713261541229;2684.3960108975325;2694.1109433887164;2697.325668582854;2738.5755207942802;474.8931490414698;481.4896210017393;487.4564860180873;492.05525391248176;498.34716521356415;523.14204704285;524.4755244755245;537.5001248838618;569.8233547600257;572.0703856233621;7256.310650488195;7552;7620.466567340344;7693.4614576979075;8041.477303975223;547.4771593128553;558.6006005705858;574.319431473705;578.5400606517828;582.8513728999078;3364.4616861302075;3454.4300190468794;3579.2125732338845;3624.3294990426857;3700.796456194881;66969.33030669729;67096.32903670998;67244;68862;68899.31100688929;58913.70543147257;59111.40888591145;59324.703376482845;60008.699956499935;61879.38120618826;54031.72984135054;55338;55505.722471388195;55973.160402593996;56219;6547.181602299713;6787.321267873173;6859.657017149023;6868.038474613458;7142.178649455172;32843.34313313768;35518.46722299116;36796;38599;38937.88496920183;47299.290510643055;47783.28325075196;51193.2082377115;51905.740471298916;52256.21615675692;8602.139786021347;8732.607032683418;8783.385163038525;8805.150918307227;8840;9796.412215266822;9984.650537231162;9999.650012249536;10036.849447258151;10175.28772985884;9751.75620609462;9861.161801246973;10592.947035264775;10629.681109566725;10982.780344393228;11121.27711698737;11543.76912461763;11820.23168494045;12449.821281275921;12460.43928023223;10996.725081873017;11152.888471115184;11834.467448964646;12098.334591597195;12120.27278363301;9223.538823058687;9414.105659962293;9589.856152157583;9771.31600787938;9940;8785.912140878509;9573.569189386355;10022.849657255001;10116.08955194036;10124.34191777532 15:02:42 INFO - PROCESS | 10904 | __end_tp_report 15:02:42 INFO - PROCESS | 10904 | __start_cc_report 15:02:42 INFO - PROCESS | 10904 | _x_x_mozilla_cycle_collect,1918 15:02:42 INFO - PROCESS | 10904 | __end_cc_report 15:02:42 INFO - PROCESS | 10904 | __startTimestamp1481497362836__endTimestamp 15:02:42 INFO - PROCESS | 10904 | 15:02:42 INFO - PROCESS | 10904 | ------- Summary: start ------- 15:02:42 INFO - PROCESS | 10904 | Number of tests: 6 15:02:42 INFO - PROCESS | 10904 | 15:02:42 INFO - PROCESS | 10904 | [#0] dojo.html Cycles:165 Average:4768.11 Median:4885.78 stddev:497.92 (10.2%) stddev-sans-first:470.33 15:02:42 INFO - PROCESS | 10904 | Values: 2629.4 2643.6 2664.0 2678.3 2685.9 4684.5 4783.2 4795.2 4835.1 4839.7 4797.5 4810.9 4843.3 4858.1 4899.7 4385.2 4402.7 4449.8 4498.4 4579.1 4047.3 4413.2 4539.4 4564.2 4616.7 4333.0 4357.1 4524.5 4563.3 4576.0 4394.9 4416.3 4422.9 4471.5 4474.7 4441.2 4448.3 4451.3 4498.8 4503.5 4800.2 4826.7 4844.3 4852.0 4888.6 5019.8 5049.6 5053.6 5099.5 5201.9 5170.1 5238.1 5245.6 5296.5 5296.9 4839.3 4855.5 4858.1 4861.1 4885.8 4981.5 4998.2 5342.8 5346.6 5351.9 4881.8 5035.4 5075.4 5083.4 5162.9 5182.6 5188.0 5189.5 5193.1 5197.9 5149.9 5154.3 5156.6 5157.8 5160.9 4962.4 4978.9 4982.3 5059.2 5063.8 4422.6 4446.3 4447.6 4489.7 4496.7 3855.8 3860.4 3860.6 3870.9 3894.4 4320.9 4371.1 4459.6 4466.1 4513.5 5150.0 5168.5 5176.5 5200.1 5252.5 4422.4 4429.6 4450.4 4450.8 4490.5 4557.6 4685.4 4691.0 4692.0 4694.4 4734.0 4789.0 4809.3 4816.6 4820.1 5196.5 5200.6 5201.5 5243.0 5244.2 4851.2 4858.9 4860.5 4862.0 4912.2 4948.9 4986.8 5081.4 5131.1 5176.7 5145.0 5166.1 5166.8 5196.5 5248.0 5007.0 5045.5 5051.6 5052.1 5103.3 4922.3 5024.0 5038.5 5039.5 5043.6 4887.0 4938.3 4948.9 4957.4 5042.4 4915.6 4947.6 5073.0 5077.8 5084.0 4911.0 4911.8 4912.2 4913.1 4913.4 15:02:42 INFO - PROCESS | 10904 | 15:02:42 INFO - PROCESS | 10904 | [#1] ext.html Cycles:180 Average:52155.76 Median:18840.98 stddev:116802.90 (619.9%) stddev-sans-first:117066.20 15:02:42 INFO - PROCESS | 10904 | Values: 511.2 523.8 525.4 534.7 542.2 27414.6 28019.4 36976.0 38553.6 38803.8 39728.2 40099.6 40246.6 40249.4 40254.0 7624.0 7798.3 7802.3 7804.4 7808.6 7370.7 7532.6 9754.2 9978.0 9978.8 9977.7 10017.9 10038.3 10045.4 10051.4 9767.2 9782.9 9803.0 9807.6 9841.6 9805.5 9896.3 9897.7 9902.8 9907.4 36027.6 36273.6 36286.6 36376.0 36435.3 2808.2 2906.0 3011.8 3221.0 3297.6 25297.6 25546.0 25720.5 27772.6 27924.7 3411.2 3456.9 3461.1 3463.6 3503.2 243542.0 245900.0 246219.8 246534.0 247000.4 45682.8 46053.3 46088.3 46147.1 46148.5 43972.3 44020.3 44025.3 44093.3 44133.6 12532.3 13041.9 13054.4 13055.7 13056.7 8346.7 8466.8 9946.5 10096.3 10104.3 13628.5 13747.2 13770.4 14947.3 15319.5 5981.6 6153.6 6181.9 6259.9 6406.6 736.5 814.9 890.8 906.2 966.0 25674.5 27964.3 28044.0 28065.3 28151.3 892.8 922.2 944.7 954.6 1031.0 14057.6 14462.6 14818.5 14854.3 15014.2 619538.0 627962.0 629553.0 630258.0 633125.0 36772.3 37643.0 40477.4 41441.6 41512.4 1138.5 1149.3 1173.8 1176.8 1182.3 24457.0 25023.2 25652.2 26003.9 26091.5 36576.8 36974.0 37142.0 37167.4 37207.8 330661.0 332972.0 334135.0 334366.0 335037.0 23244.7 23340.8 23610.8 23616.9 23621.5 34225.0 34813.5 34876.8 35012.5 35238.5 29609.6 29742.9 30114.7 30352.8 30565.0 29200.4 30608.4 30953.7 31274.5 32101.4 29562.4 30151.4 30325.5 30399.4 30533.8 26363.6 28150.7 28276.4 28406.3 28939.6 22281.0 22327.4 22451.9 22520.0 22648.9 15:02:42 INFO - PROCESS | 10904 | 15:02:42 INFO - PROCESS | 10904 | [#2] jquery.html Cycles:180 Average:21429.59 Median:24053.17 stddev:52298.32 (217.4%) stddev-sans-first:52421.08 15:02:42 INFO - PROCESS | 10904 | Values: 346.8 360.1 373.6 374.5 392.9 12501.6 12538.6 14075.8 16709.8 16889.5 14749.9 15935.8 17228.7 17350.6 17367.9 4664.8 4948.1 4963.6 4973.4 4980.0 3664.0 4891.0 4972.8 4976.4 4979.2 3821.9 5521.5 5789.4 5792.2 5901.1 5577.9 5729.9 5881.1 5883.2 5890.8 5123.7 5128.3 5133.5 5134.1 5135.1 20278.0 20495.6 20512.3 20548.4 20648.2 1793.9 1847.2 1901.3 2055.9 2462.5 12529.9 12864.2 13156.6 13365.8 13735.6 1696.7 1748.6 1805.4 1882.7 1900.1 67046.3 67345.3 67411.0 68878.7 69075.3 9813.0 9954.4 9989.4 10126.2 10188.9 13651.4 13652.1 13659.7 13682.9 13697.2 6837.3 6958.2 6977.7 7097.9 7160.1 6525.3 6617.5 6746.3 6752.6 6770.5 4229.4 4296.5 4366.0 4394.3 4416.1 1701.7 1802.4 1822.4 1841.6 1844.5 797.0 821.8 832.9 851.8 896.6 15920.2 16959.2 17388.4 17571.7 17618.7 784.3 809.0 816.8 830.0 832.4 9218.9 9345.2 9649.6 9653.7 9903.0 270300.0 275353.0 275381.0 368267.0 372177.0 54493.0 55290.7 57402.7 66000.0 75439.6 874.9 893.3 917.0 917.3 932.4 11406.4 15521.3 16028.8 16370.8 16374.8 34476.7 35488.6 35747.3 36299.6 36365.5 42032.0 43712.0 45441.0 45607.0 48027.3 7265.8 7302.1 7336.6 7502.4 7671.0 10398.9 11383.5 11532.5 11624.3 11643.7 9572.0 9897.4 10101.0 10121.5 10497.5 9922.2 9985.4 10021.4 10041.4 10274.0 7133.0 9991.5 10333.1 10387.2 10453.2 7340.9 7588.4 7615.6 7674.0 7706.6 9752.7 9964.6 10286.6 10287.9 10464.9 15:02:42 INFO - PROCESS | 10904 | 15:02:42 INFO - PROCESS | 10904 | [#3] mootools.html Cycles:180 Average:4879.46 Median:4998.54 stddev:2667.77 (53.4%) stddev-sans-first:2661.87 15:02:42 INFO - PROCESS | 10904 | Values: 1322.9 1346.8 1426.0 1631.7 1670.2 7213.5 7538.1 7538.6 7543.8 7548.6 6494.4 6655.5 7160.2 7318.7 7395.9 2110.5 2144.4 2147.7 2154.1 2157.7 2124.9 2139.5 2151.7 2155.2 2155.8 1920.8 1937.0 1942.6 1943.1 2053.9 2045.1 2061.8 2079.1 2082.0 2083.3 2090.3 2112.5 2117.6 2118.6 2122.5 7376.0 7550.0 7557.0 7563.5 7566.9 4396.9 4545.9 4548.5 4551.5 4570.5 2825.1 2920.2 3019.3 3043.1 3049.0 911.9 912.5 951.4 974.3 978.1 9908.2 9939.3 9963.3 9982.4 10029.8 4023.2 4237.8 4273.1 4297.6 4305.9 8548.8 8576.4 8580.3 8582.9 8590.7 4598.0 4599.0 4599.1 4601.9 4603.3 3769.8 3792.5 4092.0 4153.2 4161.7 3514.7 3549.4 3634.2 3638.0 3642.8 1174.0 1201.7 1202.0 1203.1 1212.4 701.7 822.5 824.3 844.8 878.2 4940.5 5002.7 5007.4 5117.7 5181.4 401.1 410.3 415.3 415.6 419.2 3270.4 3454.4 3457.5 3463.9 3478.0 8407.6 8844.6 8853.3 8857.3 8866.1 8534.6 8774.6 8816.2 8816.9 8822.8 6746.3 6923.4 6926.1 6928.4 6930.3 4714.3 4815.6 4898.7 4994.4 5086.8 7832.8 7959.9 7971.9 8067.4 8090.1 7958.3 8147.6 8486.8 8494.3 8735.9 6332.0 6508.3 6752.4 7096.6 7112.8 6877.2 7029.2 7089.9 7200.3 7205.5 6064.2 6456.5 6522.3 6547.4 6630.7 6287.8 6404.3 6432.8 6443.8 6529.0 6154.1 6226.5 6252.2 6314.2 6331.1 4270.3 4337.5 4340.6 4362.6 4626.2 4306.0 4346.8 4398.2 4542.9 4609.6 15:02:42 INFO - PROCESS | 10904 | 15:02:42 INFO - PROCESS | 10904 | [#4] prototype.html Cycles:180 Average:4240.28 Median:4798.48 stddev:1174.60 (24.5%) stddev-sans-first:1140.82 15:02:42 INFO - PROCESS | 10904 | Values: 339.8 350.5 353.3 353.4 359.1 4836.8 4855.6 4893.5 4908.6 4987.2 4882.8 4904.5 4905.8 4909.5 4912.6 3715.3 3833.5 3842.3 3842.4 3882.2 3896.4 3913.3 3920.5 3946.2 3951.2 3882.6 3887.0 3890.3 3892.7 3915.0 3672.4 3776.5 3776.9 3891.7 3893.9 3489.8 3500.1 3505.8 3516.7 3519.5 4736.2 4754.0 4760.7 4761.4 4762.6 4327.1 4452.5 4461.0 4463.2 4470.2 5063.9 5089.1 5100.0 5100.3 5102.3 3802.1 3805.5 3808.3 3837.9 3848.4 5138.2 5314.9 5336.3 5338.1 5369.8 3903.4 3950.5 3955.6 3956.3 3968.7 4006.8 4010.3 4010.7 4015.2 4025.2 4447.2 4450.3 4451.7 4467.6 4470.2 4977.9 5068.4 5072.5 5099.2 5107.7 1348.8 1360.8 1363.9 1369.0 1372.2 913.4 969.2 970.6 972.3 980.7 4334.9 4434.8 4452.2 4570.5 4580.9 5280.2 5299.8 5314.0 5316.2 5321.0 4557.7 4567.5 4569.2 4569.5 4574.2 2558.4 2728.2 2733.7 2763.6 2766.2 4777.8 4843.5 4843.8 4846.3 4848.5 5092.3 5221.5 5226.2 5230.7 5289.0 4777.7 4796.0 4836.8 4852.5 4857.6 5104.8 5125.4 5131.0 5131.3 5131.7 4848.9 4980.8 5017.7 5042.5 5085.4 4786.8 4797.3 4807.5 4818.4 4827.7 3833.6 3853.3 3895.2 3918.7 3923.3 4873.7 5021.3 5041.3 5078.7 5099.7 4889.0 4907.7 4926.7 4934.0 4946.9 4904.8 4910.0 4919.6 4927.2 4961.6 4683.7 4861.3 4878.0 4879.9 4915.9 4799.7 4890.9 4941.6 4954.1 4968.5 4944.4 4963.8 4972.6 4988.9 5023.5 15:02:42 INFO - PROCESS | 10904 | 15:02:42 INFO - PROCESS | 10904 | [#5] yui.html Cycles:180 Average:15129.14 Median:28032.16 stddev:19529.96 (69.7%) stddev-sans-first:19554.16 15:02:42 INFO - PROCESS | 10904 | Values: 573.1 603.7 625.6 655.7 693.3 12569.6 14795.6 15410.3 15428.4 16217.1 15049.8 15055.5 15120.7 15231.1 15252.7 618.5 636.0 746.0 768.0 772.4 748.1 769.8 771.8 779.1 779.8 804.9 806.1 806.6 806.6 807.0 649.8 800.8 801.1 801.1 801.3 488.7 572.6 595.6 597.3 598.8 14020.6 14039.4 14055.6 14060.8 14069.4 12166.8 12250.2 12250.6 12378.4 12439.6 11391.8 11409.9 11457.1 11492.5 11527.4 3903.7 3923.5 3934.0 3949.6 3990.2 65350.0 66343.0 66823.0 67505.0 69396.0 9341.3 9705.4 9734.1 9735.1 9988.2 17335.3 17409.8 17603.6 17818.6 17840.6 6472.8 6492.9 6566.2 6721.2 6735.1 3604.9 3692.2 3696.6 3727.1 3728.2 2606.7 2684.4 2694.1 2697.3 2738.6 474.9 481.5 487.5 492.1 498.3 523.1 524.5 537.5 569.8 572.1 7256.3 7552.0 7620.5 7693.5 8041.5 547.5 558.6 574.3 578.5 582.9 3364.5 3454.4 3579.2 3624.3 3700.8 66969.3 67096.3 67244.0 68862.0 68899.3 58913.7 59111.4 59324.7 60008.7 61879.4 54031.7 55338.0 55505.7 55973.2 56219.0 6547.2 6787.3 6859.7 6868.0 7142.2 32843.3 35518.5 36796.0 38599.0 38937.9 47299.3 47783.3 51193.2 51905.7 52256.2 8602.1 8732.6 8783.4 8805.2 8840.0 9796.4 9984.7 9999.7 10036.8 10175.3 9751.8 9861.2 10592.9 10629.7 10982.8 11121.3 11543.8 11820.2 12449.8 12460.4 10996.7 11152.9 11834.5 12098.3 12120.3 9223.5 9414.1 9589.9 9771.3 9940.0 8785.9 9573.6 10022.8 10116.1 10124.3 15:02:42 INFO - PROCESS | 10904 | -------- Summary: end -------- 15:02:42 INFO - PROCESS | 10904 | 15:02:43 INFO - TEST-INFO | 10904: exit 0 15:02:43 INFO - TEST-OK | dromaeo_css | took 1124492ms 15:02:43 INFO - TEST-START | kraken 15:02:43 INFO - operating with platform_type : linux_ 15:02:43 INFO - Initialising browser for kraken test... 15:02:43 INFO - TEST-INFO | started process 11210 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpP7FRE0/profile http://localhost:33985/getInfo.html) 15:02:48 INFO - PROCESS | 11210 | __metrics Screen width/height:1600/1200 15:02:48 INFO - PROCESS | 11210 | colorDepth:24 15:02:48 INFO - PROCESS | 11210 | Browser inner width/height: 1024/697 15:02:48 INFO - PROCESS | 11210 | __metrics 15:02:48 INFO - PROCESS | 11210 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:02:48 INFO - PROCESS | 11210 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:02:49 INFO - PROCESS | 11210 | console.error: PushService: 15:02:49 INFO - PROCESS | 11210 | stateChangeProcessEnqueue: Error transitioning state 15:02:49 INFO - PROCESS | 11210 | UnknownError 15:02:50 INFO - TEST-INFO | 11210: exit 0 15:02:50 INFO - Browser initialized. 15:02:50 INFO - Running cycle 1/1 for kraken test... 15:02:50 INFO - TEST-INFO | started process 11292 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpP7FRE0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:03:02 INFO - PROCESS | 11292 | RSS: Main: 173580288 15:03:02 INFO - PROCESS | 11292 | 15:03:05 INFO - PROCESS | 11292 | Cycle 1(1): loaded http://localhost:33985/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33985/tests/kraken/driver.html?testName=audio-beat-detection) 15:03:06 INFO - PROCESS | 11292 | RSS: Main: 240001024 15:03:06 INFO - PROCESS | 11292 | 15:03:09 INFO - PROCESS | 11292 | Cycle 1(1): loaded http://localhost:33985/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33985/tests/kraken/driver.html?testName=audio-dft) 15:03:09 INFO - PROCESS | 11292 | RSS: Main: 218091520 15:03:09 INFO - PROCESS | 11292 | 15:03:12 INFO - PROCESS | 11292 | Cycle 1(1): loaded http://localhost:33985/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33985/tests/kraken/driver.html?testName=audio-fft) 15:03:13 INFO - PROCESS | 11292 | RSS: Main: 301240320 15:03:13 INFO - PROCESS | 11292 | 15:03:15 INFO - PROCESS | 11292 | Cycle 1(1): loaded http://localhost:33985/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33985/tests/kraken/driver.html?testName=audio-oscillator) 15:03:16 INFO - PROCESS | 11292 | RSS: Main: 211374080 15:03:16 INFO - PROCESS | 11292 | 15:03:18 INFO - PROCESS | 11292 | Cycle 1(1): loaded http://localhost:33985/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33985/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:03:18 INFO - PROCESS | 11292 | RSS: Main: 215035904 15:03:18 INFO - PROCESS | 11292 | 15:03:22 INFO - PROCESS | 11292 | Cycle 1(1): loaded http://localhost:33985/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33985/tests/kraken/driver.html?testName=imaging-darkroom) 15:03:22 INFO - PROCESS | 11292 | RSS: Main: 253878272 15:03:22 INFO - PROCESS | 11292 | 15:03:26 INFO - PROCESS | 11292 | Cycle 1(1): loaded http://localhost:33985/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33985/tests/kraken/driver.html?testName=imaging-desaturate) 15:03:26 INFO - PROCESS | 11292 | RSS: Main: 248164352 15:03:26 INFO - PROCESS | 11292 | 15:03:29 INFO - PROCESS | 11292 | Cycle 1(1): loaded http://localhost:33985/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33985/tests/kraken/driver.html?testName=json-parse-financial) 15:03:30 INFO - PROCESS | 11292 | RSS: Main: 249614336 15:03:30 INFO - PROCESS | 11292 | 15:03:32 INFO - PROCESS | 11292 | Cycle 1(1): loaded http://localhost:33985/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33985/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:03:32 INFO - PROCESS | 11292 | RSS: Main: 213364736 15:03:32 INFO - PROCESS | 11292 | 15:03:34 INFO - PROCESS | 11292 | Cycle 1(1): loaded http://localhost:33985/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33985/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:03:35 INFO - PROCESS | 11292 | RSS: Main: 221052928 15:03:35 INFO - PROCESS | 11292 | 15:03:37 INFO - PROCESS | 11292 | Cycle 1(1): loaded http://localhost:33985/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33985/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:03:38 INFO - PROCESS | 11292 | RSS: Main: 229412864 15:03:38 INFO - PROCESS | 11292 | 15:03:40 INFO - PROCESS | 11292 | Cycle 1(1): loaded http://localhost:33985/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33985/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:03:41 INFO - PROCESS | 11292 | RSS: Main: 237862912 15:03:41 INFO - PROCESS | 11292 | 15:03:44 INFO - PROCESS | 11292 | Cycle 1(1): loaded http://localhost:33985/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33985/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:03:45 INFO - PROCESS | 11292 | RSS: Main: 235896832 15:03:45 INFO - PROCESS | 11292 | 15:03:47 INFO - PROCESS | 11292 | Cycle 1(1): loaded http://localhost:33985/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33985/tests/kraken/driver.html?testName=ai-astar) 15:03:47 INFO - PROCESS | 11292 | RSS: Main: 286056448 15:03:47 INFO - PROCESS | 11292 | 15:03:47 INFO - PROCESS | 11292 | __start_tp_report 15:03:47 INFO - PROCESS | 11292 | _x_x_mozilla_page_load 15:03:47 INFO - PROCESS | 11292 | _x_x_mozilla_page_load_details 15:03:47 INFO - PROCESS | 11292 | |i|pagename|runs| 15:03:47 INFO - PROCESS | 11292 | |0;ai-astar;106;113;112;114;114;114;114;116;115;113 15:03:47 INFO - PROCESS | 11292 | |1;audio-beat-detection;153;128;138;161;131;129;132;133;159;128 15:03:47 INFO - PROCESS | 11292 | |2;audio-dft;163;163;162;164;163;162;163;164;162;161 15:03:47 INFO - PROCESS | 11292 | |3;audio-fft;77;76;79;109;77;77;78;76;91;76 15:03:47 INFO - PROCESS | 11292 | |4;audio-oscillator;84;86;112;84;85;85;103;88;85;117 15:03:47 INFO - PROCESS | 11292 | |5;imaging-gaussian-blur;128;129;127;127;128;127;127;127;128;127 15:03:47 INFO - PROCESS | 11292 | |6;imaging-darkroom;148;148;147;147;146;147;147;147;148;148 15:03:47 INFO - PROCESS | 11292 | |7;imaging-desaturate;96;95;95;95;95;95;96;97;95;95 15:03:47 INFO - PROCESS | 11292 | |8;json-parse-financial;65;63;64;64;64;64;64;64;64;65 15:03:47 INFO - PROCESS | 11292 | |9;json-stringify-tinderbox;66;65;66;66;66;66;66;66;65;67 15:03:47 INFO - PROCESS | 11292 | |10;stanford-crypto-aes;85;84;83;84;84;89;87;84;85;91 15:03:47 INFO - PROCESS | 11292 | |11;stanford-crypto-ccm;140;146;154;157;144;144;140;140;195;140 15:03:47 INFO - PROCESS | 11292 | |12;stanford-crypto-pbkdf2;176;177;183;181;180;177;178;178;191;179 15:03:47 INFO - PROCESS | 11292 | |13;stanford-crypto-sha256-iterative;70;68;68;68;69;70;74;69;68;68 15:03:47 INFO - PROCESS | 11292 | __end_tp_report 15:03:47 INFO - PROCESS | 11292 | __start_cc_report 15:03:47 INFO - PROCESS | 11292 | _x_x_mozilla_cycle_collect,1938 15:03:47 INFO - PROCESS | 11292 | __end_cc_report 15:03:47 INFO - PROCESS | 11292 | __startTimestamp1481497427472__endTimestamp 15:03:47 INFO - PROCESS | 11292 | 15:03:47 INFO - PROCESS | 11292 | ------- Summary: start ------- 15:03:47 INFO - PROCESS | 11292 | Number of tests: 14 15:03:47 INFO - PROCESS | 11292 | 15:03:47 INFO - PROCESS | 11292 | [#0] ai-astar Cycles:10 Average:113.10 Median:114.00 stddev:2.73 (2.4%) stddev-sans-first:1.17 15:03:47 INFO - PROCESS | 11292 | Values: 106.0 113.0 112.0 114.0 114.0 114.0 114.0 116.0 115.0 113.0 15:03:47 INFO - PROCESS | 11292 | 15:03:47 INFO - PROCESS | 11292 | [#1] audio-beat-detection Cycles:10 Average:139.20 Median:135.50 stddev:13.21 (9.8%) stddev-sans-first:13.04 15:03:47 INFO - PROCESS | 11292 | Values: 153.0 128.0 138.0 161.0 131.0 129.0 132.0 133.0 159.0 128.0 15:03:47 INFO - PROCESS | 11292 | 15:03:47 INFO - PROCESS | 11292 | [#2] audio-dft Cycles:10 Average:162.70 Median:163.00 stddev:0.95 (0.6%) stddev-sans-first:1.00 15:03:47 INFO - PROCESS | 11292 | Values: 163.0 163.0 162.0 164.0 163.0 162.0 163.0 164.0 162.0 161.0 15:03:47 INFO - PROCESS | 11292 | 15:03:47 INFO - PROCESS | 11292 | [#3] audio-fft Cycles:10 Average:81.60 Median:77.00 stddev:10.63 (13.8%) stddev-sans-first:11.14 15:03:47 INFO - PROCESS | 11292 | Values: 77.0 76.0 79.0 109.0 77.0 77.0 78.0 76.0 91.0 76.0 15:03:47 INFO - PROCESS | 11292 | 15:03:47 INFO - PROCESS | 11292 | [#4] audio-oscillator Cycles:10 Average:92.90 Median:85.00 stddev:12.76 (15.0%) stddev-sans-first:13.12 15:03:47 INFO - PROCESS | 11292 | Values: 84.0 86.0 112.0 84.0 85.0 85.0 103.0 88.0 85.0 117.0 15:03:47 INFO - PROCESS | 11292 | 15:03:47 INFO - PROCESS | 11292 | [#5] imaging-gaussian-blur Cycles:10 Average:127.50 Median:127.50 stddev:0.71 (0.6%) stddev-sans-first:0.73 15:03:47 INFO - PROCESS | 11292 | Values: 128.0 129.0 127.0 127.0 128.0 127.0 127.0 127.0 128.0 127.0 15:03:47 INFO - PROCESS | 11292 | 15:03:47 INFO - PROCESS | 11292 | [#6] imaging-darkroom Cycles:10 Average:147.30 Median:147.50 stddev:0.67 (0.5%) stddev-sans-first:0.67 15:03:47 INFO - PROCESS | 11292 | Values: 148.0 148.0 147.0 147.0 146.0 147.0 147.0 147.0 148.0 148.0 15:03:47 INFO - PROCESS | 11292 | 15:03:47 INFO - PROCESS | 11292 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.00 stddev:0.70 (0.7%) stddev-sans-first:0.71 15:03:47 INFO - PROCESS | 11292 | Values: 96.0 95.0 95.0 95.0 95.0 95.0 96.0 97.0 95.0 95.0 15:03:47 INFO - PROCESS | 11292 | 15:03:47 INFO - PROCESS | 11292 | [#8] json-parse-financial Cycles:10 Average:64.10 Median:64.00 stddev:0.57 (0.9%) stddev-sans-first:0.50 15:03:47 INFO - PROCESS | 11292 | Values: 65.0 63.0 64.0 64.0 64.0 64.0 64.0 64.0 64.0 65.0 15:03:47 INFO - PROCESS | 11292 | 15:03:47 INFO - PROCESS | 11292 | [#9] json-stringify-tinderbox Cycles:10 Average:65.90 Median:66.00 stddev:0.57 (0.9%) stddev-sans-first:0.60 15:03:47 INFO - PROCESS | 11292 | Values: 66.0 65.0 66.0 66.0 66.0 66.0 66.0 66.0 65.0 67.0 15:03:47 INFO - PROCESS | 11292 | 15:03:47 INFO - PROCESS | 11292 | [#10] stanford-crypto-aes Cycles:10 Average:85.60 Median:85.00 stddev:2.59 (3.0%) stddev-sans-first:2.74 15:03:47 INFO - PROCESS | 11292 | Values: 85.0 84.0 83.0 84.0 84.0 89.0 87.0 84.0 85.0 91.0 15:03:47 INFO - PROCESS | 11292 | 15:03:47 INFO - PROCESS | 11292 | [#11] stanford-crypto-ccm Cycles:10 Average:150.00 Median:145.00 stddev:16.92 (11.7%) stddev-sans-first:17.56 15:03:47 INFO - PROCESS | 11292 | Values: 140.0 146.0 154.0 157.0 144.0 144.0 140.0 140.0 195.0 140.0 15:03:47 INFO - PROCESS | 11292 | 15:03:47 INFO - PROCESS | 11292 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.00 Median:179.50 stddev:4.40 (2.4%) stddev-sans-first:4.42 15:03:47 INFO - PROCESS | 11292 | Values: 176.0 177.0 183.0 181.0 180.0 177.0 178.0 178.0 191.0 179.0 15:03:47 INFO - PROCESS | 11292 | 15:03:47 INFO - PROCESS | 11292 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.20 Median:69.00 stddev:1.87 (2.7%) stddev-sans-first:1.96 15:03:47 INFO - PROCESS | 11292 | Values: 70.0 68.0 68.0 68.0 69.0 70.0 74.0 69.0 68.0 68.0 15:03:47 INFO - PROCESS | 11292 | -------- Summary: end -------- 15:03:47 INFO - PROCESS | 11292 | 15:03:48 INFO - TEST-INFO | 11292: exit 0 15:03:48 INFO - TEST-OK | kraken | took 64922ms 15:03:48 INFO - SUITE-END | took 1189s 15:03:49 INFO - Completed test suite (00:19:49) 15:03:49 INFO - javascript benchmark 15:03:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2629.355807827082, 2643.603459481079, 2663.9866800665995, 2678.3304173956512, 2685.8657067146637, 4684.484706682262, 4783.2346824507995, 4795.184818580841, 4835.10550548148, 4839.7338146401935, 4797.47227804942, 4810.855674329767, 4843.273508973647, 4858.101251268517, 4899.730514821684, 4385.188740083079, 4402.735835849843, 4449.777511124447, 4498.392716983198, 4579.107074120548, 4047.271491131595, 4413.205622987877, 4539.432570928634, 4564.15563120822, 4616.699914505546, 4333.0, 4357.128574285156, 4524.547545245482, 4563.315502674593, 4575.9771201143785, 4394.934075988863, 4416.293393057095, 4422.933655995163, 4471.5081341052455, 4474.709143905636, 4441.222786012468, 4448.33275008748, 4451.310046942729, 4498.752568608726, 4503.482099558527, 4800.159972004885, 4826.686265392774, 4844.32179494871, 4851.975740121276, 4888.633352498577, 5019.799208031674, 5049.570786483153, 5053.620978426633, 5099.4900509949075, 5201.9479805201845, 5170.095233334151, 5238.083335416287, 5245.580353571705, 5296.523312901819, 5296.867578310535, 4839.346688197119, 4855.4901735317635, 4858.101251268456, 4861.076395484846, 4885.780139893714, 4981.501849814989, 4998.175301075282, 5342.839714808562, 5346.572274218053, 5351.866203344947, 4881.755912204376, 5035.420926593415, 5075.416327122354, 5083.440821509631, 5162.948370516322, 5182.585393168538, 5187.974060129675, 5189.455107213764, 5193.143131383279, 5197.870053248699, 5149.948500514947, 5154.30416893722, 5156.587473002151, 5157.8452646420665, 5160.8967820643375, 4962.429320628102, 4978.90042199154, 4982.252662100714, 5059.19052951526, 5063.797448102108, 4422.601965823092, 4446.288593825037, 4447.644188464915, 4489.73061616304, 4496.7301961882395, 3855.768653880776, 3860.3823388258306, 3860.6332398422105, 3870.9225815483533, 3894.3768996960916, 4320.870373888788, 4371.103923695635, 4459.598636122764, 4466.0844526871915, 4513.526079761577, 5149.9742501288, 5168.508991645787, 5176.482351764794, 5200.089984252741, 5252.527272545489, 4422.425084738963, 4429.579189976947, 4450.35469856869, 4450.799714012877, 4490.50604433512, 4557.589816916494, 4685.390899183084, 4690.9765451172525, 4691.976540117277, 4694.36626055485, 4733.952660473489, 4788.95211047899, 4809.254565542346, 4816.614670826255, 4820.132376172322, 5196.454372290855, 5200.583953283653, 5201.531862132426, 5243.0, 5244.213367994831, 4851.151048566558, 4858.927116093332, 4860.465348811557, 4862.0, 4912.189488734319, 4948.901021979612, 4986.825461108844, 5081.390233172043, 5131.127708289525, 5176.689398636094, 5144.9742751286, 5166.095933211748, 5166.819161329486, 5196.454372290855, 5248.0, 5006.974965125151, 5045.470225626256, 5051.646384753179, 5052.1411360069615, 5103.336566246364, 4922.335484709662, 5024.045431368028, 5038.521340472649, 5039.496050394931, 5043.596512279082, 4886.975565122151, 4938.30863679078, 4948.925766113583, 4957.405111386656, 5042.420121686053, 4915.606751459946, 4947.604191664586, 5073.0, 5077.7715002824225, 5084.00861831954, 4911.0423467424935, 4911.754412279301, 4912.189488734319, 4913.140200464833, 4913.361263035782], "value": 4736.327807774125, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [511.1540400636946, 523.8219005538116, 525.39054696552, 534.6978956889358, 542.2056686953612, 27414.588781168273, 28019.43961120779, 36976.0, 38553.61446385535, 38803.80598097012, 39728.22581854727, 40099.59900400995, 40246.59753402465, 40249.39625905606, 40254.0, 7623.961880190592, 7798.337141342992, 7802.297793198611, 7804.3756499479905, 7808.570528620923, 7370.705171793122, 7532.585707786069, 9754.170895473857, 9977.950110249474, 9978.800423991515, 9977.700668979942, 10017.899821001769, 10038.347507411994, 10045.397276163454, 10051.396916185053, 9767.169790567776, 9782.853257201077, 9803.0, 9807.607695692164, 9841.55712992917, 9805.460699661517, 9896.25778066648, 9897.653582124663, 9902.80194396108, 9907.405555666612, 36027.63972360269, 36273.637263627286, 36286.63713362885, 36376.0, 36435.27129457396, 2808.2277373722186, 2905.970940290591, 3011.8192908425344, 3221.01758963516, 3297.55483009793, 25297.62199775992, 25546.0, 25720.485590288277, 27772.58341124885, 27924.720752792415, 3411.198368383428, 3456.948145777815, 3461.13471632092, 3463.6363181865795, 3503.1592417819793, 243542.0, 245900.0, 246219.76890115615, 246534.0, 247000.35498757637, 45682.771586141855, 46053.30920036202, 46088.308675369895, 46147.07705845864, 46148.538514615095, 43972.3404148938, 44020.3396949046, 44025.339619905724, 44093.33859992103, 44133.558664413584, 12532.310722910328, 13041.869581304256, 13054.412551435209, 13055.738885222243, 13056.673583160496, 8346.749597512084, 8466.830663386698, 9946.452945087945, 10096.34373765703, 10104.29269951111, 13628.523001695092, 13747.175169489663, 13770.449182032808, 14947.252637368088, 15319.540413787605, 5981.61119527238, 6153.630782153085, 6181.938180618136, 6259.874802503924, 6406.615603063831, 736.5359823311279, 814.9388795840335, 890.8129292848574, 906.1934877958622, 966.0436168193438, 25674.48651026969, 27964.300892477444, 28044.0, 28065.298367540567, 28151.296217594314, 892.8437523433373, 922.1976880114344, 944.6693657220055, 954.6372378496127, 1031.0205304960318, 14057.64213093944, 14462.589073634079, 14818.481353152589, 14854.2572871356, 15014.174220417983, 619538.0, 627962.0, 629553.0, 630258.0, 633125.0, 36772.26455470876, 37643.0, 40477.39283910744, 41441.58558414377, 41512.37731434031, 1138.459231864841, 1149.3046706742468, 1173.7945859474728, 1176.7528818947947, 1182.2847177457513, 24457.0, 25023.249302520955, 25652.230433087036, 26003.869980650732, 26091.478170436865, 36576.81711591425, 36974.0, 37142.0, 37167.44248836324, 37207.81396093002, 330661.0, 332972.0, 334135.0, 334366.0, 335037.0, 23244.651330230397, 23340.76659233386, 23610.763892361545, 23616.88191559031, 23621.52756944886, 34225.00248237494, 34813.47779783356, 34876.825615871756, 35012.47481287834, 35238.47142292817, 29609.5558566626, 29742.851285743433, 30114.69885301119, 30352.84823575868, 30565.0, 29200.41599168047, 30608.387832243676, 30953.69046309508, 31274.53088203724, 32101.357972839945, 29562.408751825275, 30151.396972060873, 30325.545116823705, 30399.39201215919, 30533.847330763205, 26363.59134353428, 28150.718492815628, 28276.43447131087, 28406.28984275327, 28939.565906510998, 22281.0, 22327.44181395413, 22451.887740561848, 22520.0, 22648.886755566116], "value": 16742.75487815879, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [346.8335199104428, 360.1159154276252, 373.6357051874422, 374.4532981846504, 392.8958825911132, 12501.562445314405, 12538.56115035974, 14075.788863167048, 16709.83290167098, 16889.493315200532, 14749.852501474981, 15935.84064159361, 17228.655426891455, 17350.56623584411, 17367.91316043421, 4664.813407463697, 4948.084604348189, 4963.553280204781, 4973.353464049668, 4979.950200498003, 3664.0, 4891.021795640868, 4972.776225069881, 4976.427710813248, 4979.203327467606, 3821.9235615287676, 5521.5306698930435, 5789.39211382805, 5792.218050563183, 5901.144334071579, 5577.888442231173, 5729.914051289234, 5881.059030555112, 5883.235179426669, 5890.764369425217, 5123.7438128093445, 5128.333316668809, 5133.537981581639, 5134.0758663440565, 5135.050015747099, 20278.0, 20495.590088198154, 20512.28207012762, 20548.38354849357, 20648.174073037062, 1793.8654600904983, 1847.1503108570073, 1901.2870173684869, 2055.856090073695, 2462.495188486356, 12529.874701252962, 12864.163829351062, 13156.60530184096, 13365.799513007312, 13735.587932362045, 1696.7030769615392, 1748.589081565831, 1805.3951926104771, 1882.6799444094536, 1900.1354383755358, 67046.32953670449, 67345.3265467344, 67411.0, 68878.65560672215, 69075.30924690739, 9812.95093524542, 9954.352967057119, 9989.350692204984, 10126.189904807597, 10188.949055254676, 13651.38568764408, 13652.112612680145, 13659.658508537168, 13682.93158534201, 13697.246651434267, 6837.316268373223, 6958.199807022257, 6977.69735987123, 7097.858042839218, 7160.069191005136, 6525.347465253437, 6617.503687223478, 6746.325367463214, 6752.5948443093575, 6770.526063175629, 4229.365595160689, 4296.462942132233, 4366.039471316305, 4394.340848872695, 4416.072624748838, 1701.7192163292918, 1802.4322338463342, 1822.3621732393822, 1841.6132612151598, 1844.4651051195003, 796.9800754981172, 821.8068753842842, 832.9000519937646, 851.7955690634203, 896.6323807239, 15920.203989800464, 16959.23683434224, 17388.391406300718, 17571.73642395365, 17618.735718964224, 784.2510402565537, 808.975730728079, 816.848882956655, 829.9917000830035, 832.388194676912, 9218.907810921939, 9345.15893569582, 9649.565769540388, 9653.662121825702, 9903.0, 270300.0, 275353.0, 275381.0, 368267.0, 372177.0, 54493.0, 55290.72354638201, 57402.71298643564, 66000.0, 75439.62280188563, 874.8862647855738, 893.2541327991071, 916.9908300917039, 917.2570218123208, 932.38462614674, 11406.429678515875, 15521.292657807406, 16028.839711602735, 16370.754438683667, 16374.75437868409, 34476.655233447345, 35488.64511354853, 35747.28505429929, 36299.63700362963, 36365.45451818278, 42032.0, 43712.0, 45441.0, 45607.0, 48027.279590806866, 7265.782026539212, 7302.087239095114, 7336.596487193256, 7502.3622992046285, 7670.964419803255, 10398.896011039793, 11383.487743051417, 11532.538698451968, 11624.302541847515, 11643.708907277385, 9571.994940531144, 9897.35667181631, 10101.040401161878, 10121.544530495998, 10497.47512624381, 9922.156616687662, 9985.400875947467, 10021.448820314661, 10041.397516149054, 10274.0, 7133.037039999741, 9991.450470224207, 10333.070023697903, 10387.169026478015, 10453.216008799523, 7340.889886651597, 7588.430867684835, 7615.619219039137, 7674.0, 7706.576138312446, 9752.707418777449, 9964.60141594357, 10286.588536458457, 10287.897121028695, 10464.947675261576], "value": 8001.695395441611, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1322.9470821167151, 1346.8181795457613, 1425.9786103208446, 1631.706292867285, 1670.2483882252989, 7213.5311204771715, 7538.057742782153, 7538.585377804226, 7543.773686789392, 7548.5848278344665, 6494.350564943504, 6655.500837437187, 7160.176579693337, 7318.7438439654625, 7395.889061664079, 2110.472381904524, 2144.3781303422065, 2147.6885851551515, 2154.13834466214, 2157.7087093242367, 2124.925627603034, 2139.465133716571, 2151.6880052392394, 2155.181031208139, 2155.81675557577, 1920.7983161768022, 1937.0218039889905, 1942.6406114868723, 1943.1255934829312, 2053.9486512837148, 2045.1308194017558, 2061.783512731164, 2079.1371580793952, 2081.95836083279, 2083.2812679625504, 2090.2997495838913, 2112.5458026524216, 2117.608242475147, 2118.6186486432434, 2122.5118222808687, 7375.963120184365, 7550.0, 7556.962215188943, 7563.546187228729, 7566.886496702554, 4396.89007774805, 4545.909081818345, 4548.4541854977615, 4551.453825540923, 4570.451545814523, 2825.1242114944434, 2920.1677521906367, 3019.3357461358687, 3043.132707178466, 3048.9695103048907, 911.9042500537414, 912.4981260306805, 951.4243882451086, 974.3374505336368, 978.1294647763497, 9908.20734341251, 9939.254555908336, 9963.302568820185, 9982.401055936594, 10029.849552256723, 4023.215472982799, 4237.766922819213, 4273.102648443862, 4297.5702429756775, 4305.870823875289, 8548.786280343042, 8576.356773242032, 8580.313574913991, 8582.87125693115, 8590.699325523577, 4597.977010114928, 4598.965232822588, 4599.057193275437, 4601.884952876205, 4603.332516785047, 3769.849206031728, 3792.469054332396, 4091.97954010228, 4153.190127925086, 4161.667066634662, 3514.68367846895, 3549.378858699717, 3634.23681026982, 3638.0, 3642.817859107034, 1173.9823902641467, 1201.669540876266, 1201.9939900300442, 1203.0856549022806, 1212.3695678247439, 701.6877489517116, 822.4736168851939, 824.2581676491086, 844.8141408890034, 878.2271600991214, 4940.530649588283, 5002.674826136289, 5007.4491805901325, 5117.667351622133, 5181.4041385240425, 401.12754758400365, 410.347547399636, 415.2753445238079, 415.5802639334234, 419.1721350333068, 3270.394976929228, 3454.3954807909017, 3457.533233013561, 3463.8614455421502, 3478.0261526772542, 8407.62165702545, 8844.601992910335, 8853.335999800041, 8857.29141668665, 8866.113388661082, 8534.573271336409, 8774.649014039367, 8816.162464565856, 8816.911830881738, 8822.823543529223, 6746.257911629717, 6923.411510021604, 6926.064981227571, 6928.44572434204, 6930.341617546325, 4714.3399924011355, 4815.638827087912, 4898.681585696918, 4994.4006719192475, 5086.847394578169, 7832.765017049498, 7959.920400795918, 7971.880421793794, 8067.435279530377, 8090.11008789021, 7958.283754462126, 8147.592620369076, 8486.830263394573, 8494.32045436376, 8735.868961965703, 6332.0, 6508.316626754312, 6752.358525940028, 7096.574205547684, 7112.822179445555, 6877.2091209509545, 7029.226785053742, 7089.893651595334, 7200.279972002967, 7205.459590530836, 6064.241969753781, 6456.483481321577, 6522.315156908457, 6547.410733034049, 6630.734770609121, 6287.811365659038, 6404.295527492065, 6432.774852880127, 6443.806685799434, 6529.0, 6154.107654389999, 6226.470749986302, 6252.1559589456665, 6314.179156709598, 6331.113644089739, 4270.316749320094, 4337.5011873633675, 4340.631046361094, 4362.629176520031, 4626.23667094939, 4305.97847010763, 4346.826126954887, 4398.164348773723, 4542.931856022096, 4609.562091601292], "value": 3907.462667229651, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [339.75028354159696, 350.47778809573725, 353.31985927090346, 353.4168621774071, 359.05031192495835, 4836.8065277388905, 4855.611551075915, 4893.535114164153, 4908.607311415087, 4987.177115775901, 4882.829100981467, 4904.48502907195, 4905.803767849282, 4909.459959404463, 4912.557869791727, 3715.2538306995734, 3833.501644786174, 3842.289176502342, 3842.442845787359, 3882.2429626222897, 3896.4350169225445, 3913.2956067907767, 3920.4903362562827, 3946.151577410856, 3951.2097580483874, 3882.592327805568, 3887.0, 3890.319194141028, 3892.70804689648, 3914.9804250978846, 3672.4491326301136, 3776.4712940188397, 3776.9433458498142, 3891.6692081173132, 3893.922121557567, 3489.8080605566684, 3500.1424650960676, 3505.842237099311, 3516.683498485123, 3519.4896739972814, 4736.218523943545, 4754.0, 4760.714856868723, 4761.357216775726, 4762.64280178988, 4327.0913108247305, 4452.532484089156, 4460.973975985541, 4463.24124898768, 4470.2177119004045, 5063.873403164914, 5089.068684458788, 5100.0, 5100.285959965608, 5102.336696229467, 3802.125511132427, 3805.5243094613174, 3808.295465338921, 3837.884863454101, 3848.364445110845, 5138.203578445347, 5314.9468505314835, 5336.252924590522, 5338.145896656555, 5369.758360873731, 3903.4144878268485, 3950.48643676326, 3955.624215699504, 3956.287868183697, 3968.722189446711, 4006.819693113817, 4010.2981978153707, 4010.7165706974074, 4015.19696060789, 4025.2150830587752, 4447.199504089295, 4450.287953927356, 4451.732896026248, 4467.620252278528, 4470.173017991682, 4977.90044199114, 5068.391792984866, 5072.492750724898, 5099.235114732745, 5107.744612769421, 1348.7707089794758, 1360.768669326217, 1363.8704323089291, 1368.9589312320647, 1372.1629805818443, 913.3925939250457, 969.1568335547977, 970.6408628807386, 972.3242346569067, 980.7106903463496, 4334.93794020467, 4434.800433980478, 4452.198604251201, 4570.520095390003, 4580.908381832344, 5280.181571856291, 5299.834036511961, 5313.973430132824, 5316.175992721134, 5320.973395133, 4557.703749256288, 4567.474740404829, 4569.246074397754, 4569.469227808655, 4574.222382194968, 2558.4499332643572, 2728.249731323902, 2733.72662733729, 2763.6269103671148, 2766.192314800479, 4777.832775852898, 4843.515648435128, 4843.830465933675, 4846.273059041101, 4848.515148485123, 5092.344987878954, 5221.530062294336, 5226.203980870292, 5230.712310822942, 5288.995090932711, 4777.737224452689, 4795.95204047955, 4836.806527738851, 4852.544236728967, 4857.635677324144, 5104.770285337243, 5125.384953805567, 5131.0, 5131.332926719502, 5131.666441681279, 4848.903021939612, 4980.783551319628, 5017.698938063728, 5042.520960508746, 5085.440601533903, 4786.7502273931195, 4797.280407938837, 4807.471178170468, 4818.373611430632, 4827.710337379768, 3833.559140698855, 3853.2641824305056, 3895.24042811649, 3918.74528155669, 3923.2741942740686, 4873.690115116007, 5021.322121513695, 5041.269015992587, 5078.695278283315, 5099.719515426539, 4888.9755551222015, 4907.68100073494, 4926.679765815211, 4934.0, 4946.86222168896, 4904.754762261801, 4910.042541704333, 4919.556310582535, 4927.236278376858, 4961.5534601886, 4683.672142949961, 4861.319415281792, 4877.975610121926, 4879.926801098058, 4915.901681966412, 4799.688020278671, 4890.877728056827, 4941.6293777966075, 4954.133026720381, 4968.503149685147, 4944.381952255968, 4963.8262660806695, 4972.557958192084, 4988.925166122439, 5023.497650234947], "value": 3884.9116256805564, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [573.0969390518297, 603.7072020070268, 625.634004107597, 655.6656105386252, 693.2686016252853, 12569.622911312665, 14795.556133316004, 15410.30653620587, 15428.382864685413, 16217.108059056747, 15049.774253386207, 15055.473058442956, 15120.697586048273, 15231.08613483189, 15252.694946101099, 618.4619381138393, 636.045931103345, 745.9552426854392, 767.9731209407672, 772.3859531672334, 748.143375834669, 769.7921561178479, 771.7607541662103, 779.0845756236399, 779.7894568466511, 804.859149648812, 806.1334065879174, 806.6047636658025, 806.6491076381793, 806.9757907262762, 649.8310439285773, 800.8038030682491, 801.0547553886412, 801.1387758159943, 801.3028665061375, 488.6750311043152, 572.5791543215734, 595.553334998751, 597.3399393669976, 598.7994022002634, 14020.579382618538, 14039.438422463089, 14055.578332650037, 14060.789088163685, 14069.36687849039, 12166.756664866652, 12250.203736757083, 12250.571230006906, 12378.381080945917, 12439.564615238423, 11391.829122563167, 11409.885901140966, 11457.140714446366, 11492.482838272213, 11527.42362881861, 3903.687704983623, 3923.548791888912, 3933.9803300983313, 3949.5852935441653, 3990.1820126874213, 65350.0, 66343.0, 66823.0, 67505.0, 69396.0, 9341.299402544837, 9705.417674939526, 9734.075262850018, 9735.075167859042, 9988.200943924468, 17335.306587736348, 17409.825901741075, 17603.647927041387, 17818.643627127643, 17840.643187136186, 6472.805815825533, 6492.870632200509, 6566.179227596551, 6721.193456785217, 6735.057092007123, 3604.85580576774, 3692.2430901665093, 3696.5748938871834, 3727.142757165867, 3728.217074414349, 2606.713261541229, 2684.3960108975325, 2694.1109433887164, 2697.325668582854, 2738.5755207942802, 474.8931490414698, 481.4896210017393, 487.4564860180873, 492.05525391248176, 498.34716521356415, 523.14204704285, 524.4755244755245, 537.5001248838618, 569.8233547600257, 572.0703856233621, 7256.310650488195, 7552.0, 7620.466567340344, 7693.4614576979075, 8041.477303975223, 547.4771593128553, 558.6006005705858, 574.319431473705, 578.5400606517828, 582.8513728999078, 3364.4616861302075, 3454.4300190468794, 3579.2125732338845, 3624.3294990426857, 3700.796456194881, 66969.33030669729, 67096.32903670998, 67244.0, 68862.0, 68899.31100688929, 58913.70543147257, 59111.40888591145, 59324.703376482845, 60008.699956499935, 61879.38120618826, 54031.72984135054, 55338.0, 55505.722471388195, 55973.160402593996, 56219.0, 6547.181602299713, 6787.321267873173, 6859.657017149023, 6868.038474613458, 7142.178649455172, 32843.34313313768, 35518.46722299116, 36796.0, 38599.0, 38937.88496920183, 47299.290510643055, 47783.28325075196, 51193.2082377115, 51905.740471298916, 52256.21615675692, 8602.139786021347, 8732.607032683418, 8783.385163038525, 8805.150918307227, 8840.0, 9796.412215266822, 9984.650537231162, 9999.650012249536, 10036.849447258151, 10175.28772985884, 9751.75620609462, 9861.161801246973, 10592.947035264775, 10629.681109566725, 10982.780344393228, 11121.27711698737, 11543.76912461763, 11820.23168494045, 12449.821281275921, 12460.43928023223, 10996.725081873017, 11152.888471115184, 11834.467448964646, 12098.334591597195, 12120.27278363301, 9223.538823058687, 9414.105659962293, 9589.856152157583, 9771.31600787938, 9940.0, 8785.912140878509, 9573.569189386355, 10022.849657255001, 10116.08955194036, 10124.34191777532], "value": 6125.944610234101, "unit": "score"}], "name": "dromaeo_css", "value": 6239.617585870221, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 113.0, 112.0, 114.0, 114.0, 114.0, 114.0, 116.0, 115.0, 113.0], "value": 113.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 128.0, 138.0, 161.0, 131.0, 129.0, 132.0, 133.0, 159.0, 128.0], "value": 139.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 162.0, 164.0, 163.0, 162.0, 163.0, 164.0, 162.0, 161.0], "value": 162.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 79.0, 109.0, 77.0, 77.0, 78.0, 76.0, 91.0, 76.0], "value": 81.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 112.0, 84.0, 85.0, 85.0, 103.0, 88.0, 85.0, 117.0], "value": 92.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 129.0, 127.0, 127.0, 128.0, 127.0, 127.0, 127.0, 128.0, 127.0], "value": 127.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 147.0, 147.0, 146.0, 147.0, 147.0, 147.0, 148.0, 148.0], "value": 147.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 97.0, 95.0, 95.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 63.0, 64.0, 64.0, 64.0, 64.0, 64.0, 64.0, 64.0, 65.0], "value": 64.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 66.0, 66.0, 66.0, 66.0, 66.0, 66.0, 65.0, 67.0], "value": 65.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 83.0, 84.0, 84.0, 89.0, 87.0, 84.0, 85.0, 91.0], "value": 85.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 146.0, 154.0, 157.0, 144.0, 144.0, 140.0, 140.0, 195.0, 140.0], "value": 150.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 177.0, 183.0, 181.0, 180.0, 177.0, 178.0, 178.0, 191.0, 179.0], "value": 180.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 68.0, 68.0, 68.0, 69.0, 70.0, 74.0, 69.0, 68.0, 68.0], "value": 69.2, "unit": "score"}], "name": "kraken", "value": 1574.5, "alertThreshold": 2.0}]} 15:03:49 INFO - Return code: 0 15:03:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:03:49 INFO - # TBPL SUCCESS # 15:03:49 INFO - Running post-action listener: _resource_record_post_action 15:03:49 INFO - [mozharness: 2016-12-11 23:03:49.248263Z] Finished run-tests step (success) 15:03:49 INFO - Running post-run listener: _resource_record_post_run 15:03:49 INFO - Total resource usage - Wall time: 1206s; CPU: 13.0%; Read bytes: 4096; Write bytes: 403120128; Read time: 4; Write time: 311076 15:03:49 INFO - TinderboxPrint: CPU usage
13.2% 15:03:49 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 4 15:03:49 INFO - TinderboxPrint: I/O write bytes / time
403,120,128 / 311,076 15:03:49 INFO - TinderboxPrint: CPU idle
8,351.4 (86.8%) 15:03:49 INFO - TinderboxPrint: CPU user
1,219.6 (12.7%) 15:03:49 INFO - TinderboxPrint: Swap in / out
0 / 0 15:03:49 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 110653440; Read time: 0; Write time: 70136 15:03:49 INFO - run-tests - Wall time: 1190s; CPU: 13.0%; Read bytes: 0; Write bytes: 292466688; Read time: 0; Write time: 240940 15:03:49 INFO - Running post-run listener: _upload_blobber_files 15:03:49 INFO - Blob upload gear active. 15:03:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:03:49 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 15:03:49 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', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:03:49 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 Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:03:50 INFO - (blobuploader) - INFO - Open directory for files ... 15:03:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:03:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:03:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:03:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:03:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:03:51 INFO - (blobuploader) - INFO - Done attempting. 15:03:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:03:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:03:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:03:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:03:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:03:51 INFO - (blobuploader) - INFO - Done attempting. 15:03:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:03:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:03:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:03:55 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:03:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:03:55 INFO - (blobuploader) - INFO - Done attempting. 15:03:55 INFO - (blobuploader) - INFO - Iteration through files over. 15:03:55 INFO - Return code: 0 15:03:55 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:03:55 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:03:55 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/54b47f25f2b21adb82bcc4796dcd21793720631edeb53d01494e7c4184265b615fb0d9792678dbed09b33c6f719b34bade5dd818b6afd3177c50028db8744922", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/24623ff0e4e6eb0ebf69f8b95abc9a0a2a606a2a14631668cd8ba557eb10fbff976e0b3bce14899681a1b188add56785af2760949ff8ddf026b772d1d3bb2f51", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a93ffb2531c57ac3d40694781f147ac4c6eb0b14d4c711152f6885437a252ec7343aacd9317e7a911b4fa3f2ddbafee2c8eae46662966b575d02ecb92892cdd8"} 15:03:55 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:03:55 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:03:55 INFO - Contents: 15:03:55 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/54b47f25f2b21adb82bcc4796dcd21793720631edeb53d01494e7c4184265b615fb0d9792678dbed09b33c6f719b34bade5dd818b6afd3177c50028db8744922", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/24623ff0e4e6eb0ebf69f8b95abc9a0a2a606a2a14631668cd8ba557eb10fbff976e0b3bce14899681a1b188add56785af2760949ff8ddf026b772d1d3bb2f51", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a93ffb2531c57ac3d40694781f147ac4c6eb0b14d4c711152f6885437a252ec7343aacd9317e7a911b4fa3f2ddbafee2c8eae46662966b575d02ecb92892cdd8"} 15:03:55 INFO - Running post-run listener: copy_logs_to_upload_dir 15:03:55 INFO - Copying logs to upload dir... 15:03:55 INFO - mkdir: /builds/slave/test/build/upload/logs 15:03:55 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1248.915835 ========= master_lag: 0.96 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2016-12-11 15:03:55.179328) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-11 15:03:55.187325) ========= 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=c6927cf182745ad25b1d38b9000001fe-1481484085.398759-241147463 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/54b47f25f2b21adb82bcc4796dcd21793720631edeb53d01494e7c4184265b615fb0d9792678dbed09b33c6f719b34bade5dd818b6afd3177c50028db8744922", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/24623ff0e4e6eb0ebf69f8b95abc9a0a2a606a2a14631668cd8ba557eb10fbff976e0b3bce14899681a1b188add56785af2760949ff8ddf026b772d1d3bb2f51", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a93ffb2531c57ac3d40694781f147ac4c6eb0b14d4c711152f6885437a252ec7343aacd9317e7a911b4fa3f2ddbafee2c8eae46662966b575d02ecb92892cdd8"} build_url:https://queue.taskcluster.net/v1/task/Mtp0Yv3oTcWszgWQTimMsA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010797 build_url: 'https://queue.taskcluster.net/v1/task/Mtp0Yv3oTcWszgWQTimMsA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/54b47f25f2b21adb82bcc4796dcd21793720631edeb53d01494e7c4184265b615fb0d9792678dbed09b33c6f719b34bade5dd818b6afd3177c50028db8744922", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/24623ff0e4e6eb0ebf69f8b95abc9a0a2a606a2a14631668cd8ba557eb10fbff976e0b3bce14899681a1b188add56785af2760949ff8ddf026b772d1d3bb2f51", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a93ffb2531c57ac3d40694781f147ac4c6eb0b14d4c711152f6885437a252ec7343aacd9317e7a911b4fa3f2ddbafee2c8eae46662966b575d02ecb92892cdd8"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-11 15:03:55.217351) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-11 15:03:55.217751) ========= 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=c6927cf182745ad25b1d38b9000001fe-1481484085.398759-241147463 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004682 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-11 15:03:55.267684) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-11 15:03:55.271036) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-11 15:03:55.271352) ========= ========= Total master_lag: 1.09 =========