builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-058 starttime: 1476916634.65 results: success (0) buildid: 20161019122722 builduid: bb8d8481d12a47029b05f21f2c3a8d9d revision: 49dfb63c28b9c093f13391b999de40b01f125613 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-19 15:37:14.648863) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-19 15:37:14.649401) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-19 15:37:14.649719) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-19 15:37:14.664484) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 15:37:14.664801) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=0e2a256762fed810e2f0f810000001b3-1476916634.109370-2000386204 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-19 15:37:14-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.6M=0s 2016-10-19 15:37:15 (46.6 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.098465 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 15:37:14.777545) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 15:37:14.777906) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=0e2a256762fed810e2f0f810000001b3-1476916634.109370-2000386204 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019095 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 15:37:14.828653) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-19 15:37:14.829082) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 49dfb63c28b9c093f13391b999de40b01f125613 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 49dfb63c28b9c093f13391b999de40b01f125613 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=0e2a256762fed810e2f0f810000001b3-1476916634.109370-2000386204 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-19 15:37:15,128 truncating revision to first 12 chars 2016-10-19 15:37:15,128 Setting DEBUG logging. 2016-10-19 15:37:15,128 attempt 1/10 2016-10-19 15:37:15,128 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/49dfb63c28b9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-19 15:37:15,918 unpacking tar archive at: mozilla-aurora-49dfb63c28b9/testing/mozharness/ program finished with exit code 0 elapsedTime=1.119306 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-19 15:37:15.962788) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-19 15:37:15.963179) ========= script_repo_revision: 49dfb63c28b9c093f13391b999de40b01f125613 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-19 15:37:15.963632) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-19 15:37:15.963982) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-19 15:37:15.973879) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2016-10-19 15:37:15.974204) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=0e2a256762fed810e2f0f810000001b3-1476916634.109370-2000386204 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:37:16 INFO - MultiFileLogger online at 20161019 15:37:16 in /builds/slave/test 15:37:16 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 15:37:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:37:16 INFO - {'append_to_log': False, 15:37:16 INFO - 'base_work_dir': '/builds/slave/test', 15:37:16 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 15:37:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:37:16 INFO - 'branch': 'Mozilla-Aurora', 15:37:16 INFO - 'buildbot_json_path': 'buildprops.json', 15:37:16 INFO - 'config_files': ('talos/linux_config.py',), 15:37:16 INFO - 'default_actions': ('clobber', 15:37:16 INFO - 'read-buildbot-config', 15:37:16 INFO - 'download-and-extract', 15:37:16 INFO - 'populate-webroot', 15:37:16 INFO - 'create-virtualenv', 15:37:16 INFO - 'install', 15:37:16 INFO - 'run-tests'), 15:37:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:37:16 INFO - 'download_minidump_stackwalk': True, 15:37:16 INFO - 'download_symbols': 'ondemand', 15:37:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:37:16 INFO - 'tooltool.py': '/tools/tooltool.py', 15:37:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:37:16 INFO - '/tools/misc-python/virtualenv.py')}, 15:37:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:37:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:37:16 INFO - 'installer_path': 'installer.exe', 15:37:16 INFO - 'log_level': 'info', 15:37:16 INFO - 'log_name': 'talos', 15:37:16 INFO - 'log_to_console': True, 15:37:16 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:37:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:37:16 INFO - 'opt_config_files': (), 15:37:16 INFO - 'pip_index': False, 15:37:16 INFO - 'sps_profile': False, 15:37:16 INFO - 'sps_profile_interval': 0, 15:37:16 INFO - 'suite': 'dromaeojs', 15:37:16 INFO - 'system_bits': '32', 15:37:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:37:16 INFO - 'title': 'talos-linux64-ix-058', 15:37:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:37:16 INFO - 'use_talos_json': True, 15:37:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:37:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:37:16 INFO - 'work_dir': 'build'} 15:37:16 INFO - [mozharness: 2016-10-19 22:37:16.377445Z] Running clobber step. 15:37:16 INFO - Running pre-action listener: _resource_record_pre_action 15:37:16 INFO - Running main action method: clobber 15:37:16 INFO - rmtree: /builds/slave/test/build 15:37:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:37:17 INFO - Running post-action listener: _resource_record_post_action 15:37:17 INFO - [mozharness: 2016-10-19 22:37:17.339676Z] Finished clobber step (success) 15:37:17 INFO - [mozharness: 2016-10-19 22:37:17.339776Z] Running read-buildbot-config step. 15:37:17 INFO - Running pre-action listener: _resource_record_pre_action 15:37:17 INFO - Running main action method: read_buildbot_config 15:37:17 INFO - Using buildbot properties: 15:37:17 INFO - { 15:37:17 INFO - "project": "", 15:37:17 INFO - "product": "firefox", 15:37:17 INFO - "script_repo_revision": "production", 15:37:17 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 15:37:17 INFO - "repository": "", 15:37:17 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 15:37:17 INFO - "buildid": "20161019122722", 15:37:17 INFO - "pgo_build": "True", 15:37:17 INFO - "basedir": "/builds/slave/test", 15:37:17 INFO - "buildnumber": 527, 15:37:17 INFO - "slavename": "talos-linux64-ix-058", 15:37:17 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 15:37:17 INFO - "platform": "ubuntu64_hw", 15:37:17 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 15:37:17 INFO - "branch": "mozilla-aurora", 15:37:17 INFO - "repo_path": "releases/mozilla-aurora", 15:37:17 INFO - "revision": "49dfb63c28b9c093f13391b999de40b01f125613", 15:37:17 INFO - "stage_platform": "linux64-pgo", 15:37:17 INFO - "builduid": "bb8d8481d12a47029b05f21f2c3a8d9d", 15:37:17 INFO - "slavebuilddir": "test" 15:37:17 INFO - } 15:37:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/W4NhmvbwRimCsx9P9pmVHg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2. 15:37:17 INFO - Running post-action listener: _resource_record_post_action 15:37:17 INFO - [mozharness: 2016-10-19 22:37:17.342213Z] Finished read-buildbot-config step (success) 15:37:17 INFO - [mozharness: 2016-10-19 22:37:17.342302Z] Running download-and-extract step. 15:37:17 INFO - Running pre-action listener: _resource_record_pre_action 15:37:17 INFO - Running main action method: download_and_extract 15:37:17 INFO - mkdir: /builds/slave/test/build/tests 15:37:17 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')]} 15:37:17 INFO - trying https://queue.taskcluster.net/v1/task/W4NhmvbwRimCsx9P9pmVHg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 15:37:17 INFO - Downloading https://queue.taskcluster.net/v1/task/W4NhmvbwRimCsx9P9pmVHg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 15:37:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/W4NhmvbwRimCsx9P9pmVHg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 15:37:17 INFO - Downloaded 1361 bytes. 15:37:17 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 15:37:17 INFO - Using the following test package requirements: 15:37:17 INFO - {u'common': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 15:37:17 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 15:37:17 INFO - u'firefox-51.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 15:37:17 INFO - u'gtest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 15:37:17 INFO - u'firefox-51.0a2.en-US.linux-x86_64.gtest.tests.zip'], 15:37:17 INFO - u'jittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 15:37:17 INFO - u'jsshell-linux-x86_64.zip'], 15:37:17 INFO - u'mochitest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 15:37:17 INFO - u'firefox-51.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 15:37:17 INFO - u'mozbase': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 15:37:17 INFO - u'reftest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 15:37:17 INFO - u'firefox-51.0a2.en-US.linux-x86_64.reftest.tests.zip'], 15:37:17 INFO - u'talos': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 15:37:17 INFO - u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'], 15:37:17 INFO - u'web-platform': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 15:37:17 INFO - u'firefox-51.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 15:37:17 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 15:37:17 INFO - u'firefox-51.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 15:37:17 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:37:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/W4NhmvbwRimCsx9P9pmVHg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 15:37:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/W4NhmvbwRimCsx9P9pmVHg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:37:17 INFO - Fetch https://queue.taskcluster.net/v1/task/W4NhmvbwRimCsx9P9pmVHg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 15:37:18 INFO - Expected file size: 21908458 15:37:22 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 15:37:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/W4NhmvbwRimCsx9P9pmVHg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 15:37:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/W4NhmvbwRimCsx9P9pmVHg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:37:22 INFO - Fetch https://queue.taskcluster.net/v1/task/W4NhmvbwRimCsx9P9pmVHg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 15:37:23 INFO - Expected file size: 21908458 15:37:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/W4NhmvbwRimCsx9P9pmVHg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip 15:37:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/W4NhmvbwRimCsx9P9pmVHg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 15:37:28 INFO - Fetch https://queue.taskcluster.net/v1/task/W4NhmvbwRimCsx9P9pmVHg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip into memory 15:37:28 INFO - Expected file size: 14414081 15:37:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:37:29 INFO - trying https://queue.taskcluster.net/v1/task/W4NhmvbwRimCsx9P9pmVHg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 15:37:29 INFO - Downloading https://queue.taskcluster.net/v1/task/W4NhmvbwRimCsx9P9pmVHg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 15:37:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/W4NhmvbwRimCsx9P9pmVHg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 15:37:30 INFO - Downloaded 63997358 bytes. 15:37:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/W4NhmvbwRimCsx9P9pmVHg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 15:37:30 INFO - mkdir: /builds/slave/test/properties 15:37:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:37:30 INFO - Writing to file /builds/slave/test/properties/build_url 15:37:30 INFO - Contents: 15:37:30 INFO - build_url:https://queue.taskcluster.net/v1/task/W4NhmvbwRimCsx9P9pmVHg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 15:37:31 INFO - Running post-action listener: _resource_record_post_action 15:37:31 INFO - Running post-action listener: set_extra_try_arguments 15:37:31 INFO - [mozharness: 2016-10-19 22:37:31.000754Z] Finished download-and-extract step (success) 15:37:31 INFO - [mozharness: 2016-10-19 22:37:31.000981Z] Running populate-webroot step. 15:37:31 INFO - Running pre-action listener: _resource_record_pre_action 15:37:31 INFO - Running main action method: populate_webroot 15:37:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:37:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:37:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:37:31 INFO - '--tppagecycles', 15:37:31 INFO - '7'], 15:37:31 INFO - 'tests': ['tsvgm']}}, 15:37:31 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:37:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:37:31 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:37:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:37:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:37:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:37:31 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:37:31 INFO - 'talos_options': ['--disable-e10s'], 15:37:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:37:31 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:37:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:37:31 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:37:31 INFO - 'talos_options': ['--disable-e10s'], 15:37:31 INFO - 'tests': ['damp', 'tps']}, 15:37:31 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:37:31 INFO - 'tests': ['damp', 'tps']}, 15:37:31 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:37:31 INFO - 'tests': ['dromaeo_dom']}, 15:37:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:37:31 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:37:31 INFO - 'tests': ['basic_compositor_video']}, 15:37:31 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:37:31 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:37:31 INFO - 'tests': ['a11yr', 15:37:31 INFO - 'ts_paint', 15:37:31 INFO - 'tpaint', 15:37:31 INFO - 'sessionrestore', 15:37:31 INFO - 'sessionrestore_no_auto_restore', 15:37:31 INFO - 'tabpaint']}, 15:37:31 INFO - 'other-e10s': {'tests': ['a11yr', 15:37:31 INFO - 'ts_paint', 15:37:31 INFO - 'tpaint', 15:37:31 INFO - 'sessionrestore', 15:37:31 INFO - 'sessionrestore_no_auto_restore', 15:37:31 INFO - 'tabpaint']}, 15:37:31 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:37:31 INFO - 'ts_paint', 15:37:31 INFO - 'tpaint', 15:37:31 INFO - 'sessionrestore', 15:37:31 INFO - 'sessionrestore_no_auto_restore', 15:37:31 INFO - 'tabpaint']}, 15:37:31 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:37:31 INFO - 'ts_paint', 15:37:31 INFO - 'tpaint', 15:37:31 INFO - 'sessionrestore', 15:37:31 INFO - 'sessionrestore_no_auto_restore', 15:37:31 INFO - 'tabpaint']}, 15:37:31 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:37:31 INFO - 'tests': ['a11yr', 15:37:31 INFO - 'ts_paint', 15:37:31 INFO - 'tpaint', 15:37:31 INFO - 'sessionrestore', 15:37:31 INFO - 'sessionrestore_no_auto_restore', 15:37:31 INFO - 'tabpaint']}, 15:37:31 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:37:31 INFO - 'tests': ['a11yr', 15:37:31 INFO - 'ts_paint', 15:37:31 INFO - 'tpaint', 15:37:31 INFO - 'sessionrestore', 15:37:31 INFO - 'sessionrestore_no_auto_restore', 15:37:31 INFO - 'tabpaint']}, 15:37:31 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:37:31 INFO - 'tests': ['tsvgx', 15:37:31 INFO - 'tsvgr_opacity', 15:37:31 INFO - 'tart', 15:37:31 INFO - 'tscrollx', 15:37:31 INFO - 'cart']}, 15:37:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:37:31 INFO - 'tsvgr_opacity', 15:37:31 INFO - 'tart', 15:37:31 INFO - 'tscrollx', 15:37:31 INFO - 'cart']}, 15:37:31 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:37:31 INFO - 'talos_options': ['--disable-e10s'], 15:37:31 INFO - 'tests': ['tp5o']}, 15:37:31 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:37:31 INFO - 'tests': ['tp5o']}, 15:37:31 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:37:31 INFO - 'talos_options': ['--disable-e10s', 15:37:31 INFO - '--xperf_path', 15:37:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:37:31 INFO - 'tests': ['tp5n']}, 15:37:31 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:37:31 INFO - 'talos_options': ['--xperf_path', 15:37:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:37:31 INFO - 'tests': ['tp5n']}}, 15:37:31 INFO - 'talos.zip': {'path': '', 15:37:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:37:31 INFO - Running post-action listener: _resource_record_post_action 15:37:31 INFO - [mozharness: 2016-10-19 22:37:31.023358Z] Finished populate-webroot step (success) 15:37:31 INFO - [mozharness: 2016-10-19 22:37:31.023452Z] Running create-virtualenv step. 15:37:31 INFO - Running pre-action listener: _resource_record_pre_action 15:37:31 INFO - Running main action method: create_virtualenv 15:37:31 INFO - Creating virtualenv /builds/slave/test/build/venv 15:37:31 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 15:37:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:37:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:37:31 INFO - Using real prefix '/usr' 15:37:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:37:31 INFO - Installing distribute.............................................................................................................................................................................................done. 15:37:33 INFO - Installing pip.................done. 15:37:33 INFO - Return code: 0 15:37:33 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:37:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:37:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:37:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:37:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:37:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:33 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 0x7f5cf5cf2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cfb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dc8ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc5f60>, '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 0x1dc6d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dc71d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1476916634.109370-2000386204', '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 15:37:33 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 15:37:33 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 15:37:33 INFO - Using env: {'DISPLAY': ':0', 15:37:33 INFO - 'HOME': '/home/cltbld', 15:37:33 INFO - 'LANG': 'en_US.UTF-8', 15:37:33 INFO - 'LANGUAGE': 'en_US:en', 15:37:33 INFO - 'LOGNAME': 'cltbld', 15:37:33 INFO - 'MAIL': '/var/mail/cltbld', 15:37:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:37:33 INFO - 'MOZ_NO_REMOTE': '1', 15:37:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:37:33 INFO - 'NO_EM_RESTART': '1', 15:37:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:37:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:37:33 INFO - 'PWD': '/builds/slave/test', 15:37:33 INFO - 'SHELL': '/bin/bash', 15:37:33 INFO - 'SHLVL': '1', 15:37:33 INFO - 'TERM': 'linux', 15:37:33 INFO - 'TMOUT': '86400', 15:37:33 INFO - 'USER': 'cltbld', 15:37:33 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1476916634.109370-2000386204', 15:37:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:37:33 INFO - '_': '/tools/buildbot/bin/python'} 15:37:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:37:33 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 15:37:33 INFO - Cleaning up... 15:37:33 INFO - Return code: 0 15:37:33 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:37:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:37:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:37:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:37:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:37:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:33 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 0x7f5cf5cf2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cfb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dc8ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc5f60>, '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 0x1dc6d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dc71d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1476916634.109370-2000386204', '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 15:37:33 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 15:37:33 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 15:37:33 INFO - Using env: {'DISPLAY': ':0', 15:37:33 INFO - 'HOME': '/home/cltbld', 15:37:33 INFO - 'LANG': 'en_US.UTF-8', 15:37:33 INFO - 'LANGUAGE': 'en_US:en', 15:37:33 INFO - 'LOGNAME': 'cltbld', 15:37:33 INFO - 'MAIL': '/var/mail/cltbld', 15:37:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:37:33 INFO - 'MOZ_NO_REMOTE': '1', 15:37:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:37:33 INFO - 'NO_EM_RESTART': '1', 15:37:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:37:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:37:33 INFO - 'PWD': '/builds/slave/test', 15:37:33 INFO - 'SHELL': '/bin/bash', 15:37:33 INFO - 'SHLVL': '1', 15:37:33 INFO - 'TERM': 'linux', 15:37:33 INFO - 'TMOUT': '86400', 15:37:33 INFO - 'USER': 'cltbld', 15:37:33 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1476916634.109370-2000386204', 15:37:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:37:33 INFO - '_': '/tools/buildbot/bin/python'} 15:37:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:37:34 INFO - Downloading/unpacking psutil>=3.1.1 15:37: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 15:37: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 15:37: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 15:37: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 15:37: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 15:37: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 15:37:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:37:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:37:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:37:36 INFO - Installing collected packages: psutil 15:37:36 INFO - Running setup.py install for psutil 15:37:36 INFO - building 'psutil._psutil_linux' extension 15:37:36 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 15:37:36 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 15:37:36 INFO - building 'psutil._psutil_posix' extension 15:37:36 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 15:37:36 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 15:37:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:37:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:37:36 INFO - Successfully installed psutil 15:37:36 INFO - Cleaning up... 15:37:36 INFO - Return code: 0 15:37:36 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:37:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:37:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:37:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:37:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:37:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f5cf5cf2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cfb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dc8ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc5f60>, '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 0x1dc6d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dc71d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1476916634.109370-2000386204', '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 15:37:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:37:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:37:36 INFO - Using env: {'DISPLAY': ':0', 15:37:36 INFO - 'HOME': '/home/cltbld', 15:37:36 INFO - 'LANG': 'en_US.UTF-8', 15:37:36 INFO - 'LANGUAGE': 'en_US:en', 15:37:36 INFO - 'LOGNAME': 'cltbld', 15:37:36 INFO - 'MAIL': '/var/mail/cltbld', 15:37:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:37:36 INFO - 'MOZ_NO_REMOTE': '1', 15:37:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:37:36 INFO - 'NO_EM_RESTART': '1', 15:37:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:37:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:37:36 INFO - 'PWD': '/builds/slave/test', 15:37:36 INFO - 'SHELL': '/bin/bash', 15:37:36 INFO - 'SHLVL': '1', 15:37:36 INFO - 'TERM': 'linux', 15:37:36 INFO - 'TMOUT': '86400', 15:37:36 INFO - 'USER': 'cltbld', 15:37:36 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1476916634.109370-2000386204', 15:37:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:37:36 INFO - '_': '/tools/buildbot/bin/python'} 15:37:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:37:36 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:37:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:37:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:37:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:37:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:37:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:37:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:37:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:37:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:37:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:37:38 INFO - Installing collected packages: mozsystemmonitor 15:37:38 INFO - Running setup.py install for mozsystemmonitor 15:37:38 INFO - Successfully installed mozsystemmonitor 15:37:38 INFO - Cleaning up... 15:37:38 INFO - Return code: 0 15:37:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:37: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')]} 15:37:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:37:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37: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')]} 15:37:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:37:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f5cf5cf2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cfb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dc8ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc5f60>, '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 0x1dc6d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dc71d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1476916634.109370-2000386204', '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 15:37:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:37:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:37:38 INFO - Using env: {'DISPLAY': ':0', 15:37:38 INFO - 'HOME': '/home/cltbld', 15:37:38 INFO - 'LANG': 'en_US.UTF-8', 15:37:38 INFO - 'LANGUAGE': 'en_US:en', 15:37:38 INFO - 'LOGNAME': 'cltbld', 15:37:38 INFO - 'MAIL': '/var/mail/cltbld', 15:37:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:37:38 INFO - 'MOZ_NO_REMOTE': '1', 15:37:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:37:38 INFO - 'NO_EM_RESTART': '1', 15:37:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:37:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:37:38 INFO - 'PWD': '/builds/slave/test', 15:37:38 INFO - 'SHELL': '/bin/bash', 15:37:38 INFO - 'SHLVL': '1', 15:37:38 INFO - 'TERM': 'linux', 15:37:38 INFO - 'TMOUT': '86400', 15:37:38 INFO - 'USER': 'cltbld', 15:37:38 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1476916634.109370-2000386204', 15:37:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:37:38 INFO - '_': '/tools/buildbot/bin/python'} 15:37:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:37:39 INFO - Downloading/unpacking jsonschema==2.5.1 15:37: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 15:37: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 15:37: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 15:37: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 15:37: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 15:37: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 15:37:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:37:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:37:41 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 15:37:41 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 15:37:41 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 15:37:41 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 15:37:41 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 15:37:41 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 15:37:41 INFO - Downloading functools32-3.2.3-2.tar.gz 15:37:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:37:41 INFO - warning: no files found matching '*.txt' 15:37:41 INFO - no previously-included directories found matching 'build' 15:37:41 INFO - no previously-included directories found matching 'dist' 15:37:41 INFO - no previously-included directories found matching '.git*' 15:37:41 INFO - Installing collected packages: jsonschema, functools32 15:37:41 INFO - Running setup.py install for functools32 15:37:41 INFO - warning: no files found matching '*.txt' 15:37:41 INFO - no previously-included directories found matching 'build' 15:37:41 INFO - no previously-included directories found matching 'dist' 15:37:41 INFO - no previously-included directories found matching '.git*' 15:37:41 INFO - Successfully installed jsonschema functools32 15:37:41 INFO - Cleaning up... 15:37:41 INFO - Return code: 0 15:37:41 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:37:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:37:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:37:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:37:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:37:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f5cf5cf2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cfb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dc8ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc5f60>, '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 0x1dc6d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dc71d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1476916634.109370-2000386204', '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 15:37:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:37:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 15:37:41 INFO - Using env: {'DISPLAY': ':0', 15:37:41 INFO - 'HOME': '/home/cltbld', 15:37:41 INFO - 'LANG': 'en_US.UTF-8', 15:37:41 INFO - 'LANGUAGE': 'en_US:en', 15:37:41 INFO - 'LOGNAME': 'cltbld', 15:37:41 INFO - 'MAIL': '/var/mail/cltbld', 15:37:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:37:41 INFO - 'MOZ_NO_REMOTE': '1', 15:37:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:37:41 INFO - 'NO_EM_RESTART': '1', 15:37:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:37:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:37:41 INFO - 'PWD': '/builds/slave/test', 15:37:41 INFO - 'SHELL': '/bin/bash', 15:37:41 INFO - 'SHLVL': '1', 15:37:41 INFO - 'TERM': 'linux', 15:37:41 INFO - 'TMOUT': '86400', 15:37:41 INFO - 'USER': 'cltbld', 15:37:41 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1476916634.109370-2000386204', 15:37:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:37:41 INFO - '_': '/tools/buildbot/bin/python'} 15:37:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:37:41 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:37:41 INFO - Cleaning up... 15:37:41 INFO - Return code: 0 15:37:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:37:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:37:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:37:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:37:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:37:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f5cf5cf2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cfb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dc8ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc5f60>, '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 0x1dc6d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dc71d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1476916634.109370-2000386204', '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 15:37:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:37:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:37:41 INFO - Using env: {'DISPLAY': ':0', 15:37:41 INFO - 'HOME': '/home/cltbld', 15:37:41 INFO - 'LANG': 'en_US.UTF-8', 15:37:41 INFO - 'LANGUAGE': 'en_US:en', 15:37:41 INFO - 'LOGNAME': 'cltbld', 15:37:41 INFO - 'MAIL': '/var/mail/cltbld', 15:37:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:37:41 INFO - 'MOZ_NO_REMOTE': '1', 15:37:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:37:41 INFO - 'NO_EM_RESTART': '1', 15:37:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:37:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:37:41 INFO - 'PWD': '/builds/slave/test', 15:37:41 INFO - 'SHELL': '/bin/bash', 15:37:41 INFO - 'SHLVL': '1', 15:37:41 INFO - 'TERM': 'linux', 15:37:41 INFO - 'TMOUT': '86400', 15:37:41 INFO - 'USER': 'cltbld', 15:37:41 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1476916634.109370-2000386204', 15:37:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:37:41 INFO - '_': '/tools/buildbot/bin/python'} 15:37:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:37:42 INFO - Downloading/unpacking blobuploader==1.2.4 15:37:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:37:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:37:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:37:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:37:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:37:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:37:43 INFO - Downloading blobuploader-1.2.4.tar.gz 15:37:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:37:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:37:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:37:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:37:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:37:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:37:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:37:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:37:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:37:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:37:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:37:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:37:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:37:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:37:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:37:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:37:44 INFO - Downloading docopt-0.6.1.tar.gz 15:37:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:37:44 INFO - Installing collected packages: blobuploader, requests, docopt 15:37:44 INFO - Running setup.py install for blobuploader 15:37:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:37:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:37:44 INFO - Running setup.py install for requests 15:37:45 INFO - Running setup.py install for docopt 15:37:45 INFO - Successfully installed blobuploader requests docopt 15:37:45 INFO - Cleaning up... 15:37:45 INFO - Return code: 0 15:37:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:37:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:37:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:37:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:37:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:37:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:45 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 0x7f5cf5cf2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cfb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dc8ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc5f60>, '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 0x1dc6d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dc71d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1476916634.109370-2000386204', '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 15:37:45 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 15:37:45 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 15:37:45 INFO - Using env: {'DISPLAY': ':0', 15:37:45 INFO - 'HOME': '/home/cltbld', 15:37:45 INFO - 'LANG': 'en_US.UTF-8', 15:37:45 INFO - 'LANGUAGE': 'en_US:en', 15:37:45 INFO - 'LOGNAME': 'cltbld', 15:37:45 INFO - 'MAIL': '/var/mail/cltbld', 15:37:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:37:45 INFO - 'MOZ_NO_REMOTE': '1', 15:37:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:37:45 INFO - 'NO_EM_RESTART': '1', 15:37:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:37:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:37:45 INFO - 'PWD': '/builds/slave/test', 15:37:45 INFO - 'SHELL': '/bin/bash', 15:37:45 INFO - 'SHLVL': '1', 15:37:45 INFO - 'TERM': 'linux', 15:37:45 INFO - 'TMOUT': '86400', 15:37:45 INFO - 'USER': 'cltbld', 15:37:45 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1476916634.109370-2000386204', 15:37:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:37:45 INFO - '_': '/tools/buildbot/bin/python'} 15:37:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:37:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:37:45 INFO - Running setup.py (path:/tmp/pip-hDc34L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:37:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:37:45 INFO - Running setup.py (path:/tmp/pip-6JaCAu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:37:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:37:45 INFO - Running setup.py (path:/tmp/pip-Ujf7Tb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:37:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:37:45 INFO - Running setup.py (path:/tmp/pip-wcMX24-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:37:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:37:45 INFO - Running setup.py (path:/tmp/pip-Ze04hc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:37:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:37:45 INFO - Running setup.py (path:/tmp/pip-20cdOJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:37:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:37:45 INFO - Running setup.py (path:/tmp/pip-O82goE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:37:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:37:46 INFO - Running setup.py (path:/tmp/pip-z2wiff-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:37:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:37:46 INFO - Running setup.py (path:/tmp/pip-1pdi7o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:37:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:37:46 INFO - Running setup.py (path:/tmp/pip-KN2d3I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:37:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:37:46 INFO - Running setup.py (path:/tmp/pip-L7AanZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:37:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:37:46 INFO - Running setup.py (path:/tmp/pip-1o3W89-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:37:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:37:46 INFO - Running setup.py (path:/tmp/pip-JEaMdP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:37:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:37:46 INFO - Running setup.py (path:/tmp/pip-Wf4od4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:37:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:37:46 INFO - Running setup.py (path:/tmp/pip-McUNTZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:37:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:37:46 INFO - Running setup.py (path:/tmp/pip-RoCpOJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:37:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:37:46 INFO - Running setup.py (path:/tmp/pip-yaXT5a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:37:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:37:46 INFO - Running setup.py install for manifestparser 15:37:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:37:46 INFO - Running setup.py install for mozcrash 15:37:46 INFO - Running setup.py install for mozdebug 15:37:47 INFO - Running setup.py install for mozdevice 15:37:47 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:37:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:37:47 INFO - Running setup.py install for mozfile 15:37:47 INFO - Running setup.py install for mozhttpd 15:37:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:37:47 INFO - Running setup.py install for mozinfo 15:37:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:37:47 INFO - Running setup.py install for mozInstall 15:37:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:37:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:37:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:37:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:37:47 INFO - Running setup.py install for mozleak 15:37:47 INFO - Running setup.py install for mozlog 15:37:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:37:47 INFO - Running setup.py install for moznetwork 15:37:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:37:47 INFO - Running setup.py install for mozprocess 15:37:47 INFO - Running setup.py install for mozprofile 15:37:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:37:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:37:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:37:48 INFO - Running setup.py install for mozrunner 15:37:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:37:48 INFO - Running setup.py install for mozscreenshot 15:37:48 INFO - Running setup.py install for moztest 15:37:48 INFO - Running setup.py install for mozversion 15:37:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:37:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:37:48 INFO - Cleaning up... 15:37:48 INFO - Return code: 0 15:37:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:37:48 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')]} 15:37:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:37:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:48 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')]} 15:37:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:37:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:48 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 0x7f5cf5cf2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cfb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dc8ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc5f60>, '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 0x1dc6d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dc71d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1476916634.109370-2000386204', '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 15:37:48 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 15:37:48 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 15:37:48 INFO - Using env: {'DISPLAY': ':0', 15:37:48 INFO - 'HOME': '/home/cltbld', 15:37:48 INFO - 'LANG': 'en_US.UTF-8', 15:37:48 INFO - 'LANGUAGE': 'en_US:en', 15:37:48 INFO - 'LOGNAME': 'cltbld', 15:37:48 INFO - 'MAIL': '/var/mail/cltbld', 15:37:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:37:48 INFO - 'MOZ_NO_REMOTE': '1', 15:37:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:37:48 INFO - 'NO_EM_RESTART': '1', 15:37:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:37:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:37:48 INFO - 'PWD': '/builds/slave/test', 15:37:48 INFO - 'SHELL': '/bin/bash', 15:37:48 INFO - 'SHLVL': '1', 15:37:48 INFO - 'TERM': 'linux', 15:37:48 INFO - 'TMOUT': '86400', 15:37:48 INFO - 'USER': 'cltbld', 15:37:48 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1476916634.109370-2000386204', 15:37:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:37:48 INFO - '_': '/tools/buildbot/bin/python'} 15:37:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:37:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:37:48 INFO - Running setup.py (path:/tmp/pip-hIXIIi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:37:48 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)) 15:37:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:37:48 INFO - Running setup.py (path:/tmp/pip-XKdU4y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:37:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:37:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:37:48 INFO - Running setup.py (path:/tmp/pip-jFvOc2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:37:49 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)) 15:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:37:49 INFO - Running setup.py (path:/tmp/pip-FNPTJo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:37:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:37:49 INFO - Running setup.py (path:/tmp/pip-k7UCD9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:37:49 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)) 15:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:37:49 INFO - Running setup.py (path:/tmp/pip-cKkAFA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:37:49 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)) 15:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:37:49 INFO - Running setup.py (path:/tmp/pip-8Cqnox-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:37:49 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)) 15:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:37:49 INFO - Running setup.py (path:/tmp/pip-jaK7oD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:37:49 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)) 15:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:37:49 INFO - Running setup.py (path:/tmp/pip-7FZQuu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:37:49 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)) 15:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:37:49 INFO - Running setup.py (path:/tmp/pip-oyTXEe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:37:49 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)) 15:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:37:49 INFO - Running setup.py (path:/tmp/pip-5Zm786-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:37:49 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)) 15:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:37:49 INFO - Running setup.py (path:/tmp/pip-HmpxDH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:37:49 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)) 15:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:37:49 INFO - Running setup.py (path:/tmp/pip-NP9EGl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:37:49 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)) 15:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:37:49 INFO - Running setup.py (path:/tmp/pip-J13x67-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:37:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 15:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:37:49 INFO - Running setup.py (path:/tmp/pip-ohr6il-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:37:49 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)) 15:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:37:49 INFO - Running setup.py (path:/tmp/pip-rULyvz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:37:49 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)) 15:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:37:49 INFO - Running setup.py (path:/tmp/pip-Pjb55M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:37:50 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)) 15:37:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:37:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:37:50 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)) 15:37:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:37:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:37:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:37:50 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 15:37:50 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 15:37:50 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 15:37:50 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 15:37:50 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 15:37:50 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 15:37:51 INFO - Downloading blessings-1.6.tar.gz 15:37:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:37:52 INFO - Installing collected packages: blessings 15:37:52 INFO - Running setup.py install for blessings 15:37:52 INFO - Successfully installed blessings 15:37:52 INFO - Cleaning up... 15:37:52 INFO - Return code: 0 15:37:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:37:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:37:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:37:52 INFO - Reading from file tmpfile_stdout 15:37:52 INFO - Current package versions: 15:37:52 INFO - argparse == 1.2.1 15:37:52 INFO - blessings == 1.6 15:37:52 INFO - blobuploader == 1.2.4 15:37:52 INFO - docopt == 0.6.1 15:37:52 INFO - functools32 == 3.2.3-2 15:37:52 INFO - jsonschema == 2.5.1 15:37:52 INFO - manifestparser == 1.1 15:37:52 INFO - mozInstall == 1.12 15:37:52 INFO - mozcrash == 0.17 15:37:52 INFO - mozdebug == 0.1 15:37:52 INFO - mozdevice == 0.48 15:37:52 INFO - mozfile == 1.2 15:37:52 INFO - mozhttpd == 0.7 15:37:52 INFO - mozinfo == 0.9 15:37:52 INFO - mozleak == 0.1 15:37:52 INFO - mozlog == 3.4 15:37:52 INFO - moznetwork == 0.27 15:37:52 INFO - mozprocess == 0.23 15:37:52 INFO - mozprofile == 0.28 15:37:52 INFO - mozrunner == 6.12 15:37:52 INFO - mozscreenshot == 0.1 15:37:52 INFO - mozsystemmonitor == 0.3 15:37:52 INFO - moztest == 0.7 15:37:52 INFO - mozversion == 1.4 15:37:52 INFO - psutil == 3.1.1 15:37:52 INFO - requests == 1.2.3 15:37:52 INFO - wsgiref == 0.1.2 15:37:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:37:52 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')]} 15:37:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:37:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:52 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')]} 15:37:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:37:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:52 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 0x7f5cf5cf2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cfb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dc8ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc5f60>, '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 0x1dc6d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dc71d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1476916634.109370-2000386204', '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 15:37:52 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 15:37:52 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 15:37:52 INFO - Using env: {'DISPLAY': ':0', 15:37:52 INFO - 'HOME': '/home/cltbld', 15:37:52 INFO - 'LANG': 'en_US.UTF-8', 15:37:52 INFO - 'LANGUAGE': 'en_US:en', 15:37:52 INFO - 'LOGNAME': 'cltbld', 15:37:52 INFO - 'MAIL': '/var/mail/cltbld', 15:37:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:37:52 INFO - 'MOZ_NO_REMOTE': '1', 15:37:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:37:52 INFO - 'NO_EM_RESTART': '1', 15:37:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:37:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:37:52 INFO - 'PWD': '/builds/slave/test', 15:37:52 INFO - 'SHELL': '/bin/bash', 15:37:52 INFO - 'SHLVL': '1', 15:37:52 INFO - 'TERM': 'linux', 15:37:52 INFO - 'TMOUT': '86400', 15:37:52 INFO - 'USER': 'cltbld', 15:37:52 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1476916634.109370-2000386204', 15:37:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:37:52 INFO - '_': '/tools/buildbot/bin/python'} 15:37:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:37:52 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)) 15:37:52 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)) 15:37:52 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)) 15:37:52 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)) 15:37:52 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)) 15:37:52 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)) 15:37:52 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)) 15:37:52 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)) 15:37:52 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)) 15:37:52 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)) 15:37:52 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)) 15:37:52 INFO - Cleaning up... 15:37:52 INFO - Return code: 0 15:37:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:37:52 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')]} 15:37:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:37:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:52 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')]} 15:37:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:37:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:52 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 0x7f5cf5cf2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cfb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dc8ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc5f60>, '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 0x1dc6d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dc71d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1476916634.109370-2000386204', '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 15:37:52 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 15:37:52 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 15:37:52 INFO - Using env: {'DISPLAY': ':0', 15:37:52 INFO - 'HOME': '/home/cltbld', 15:37:52 INFO - 'LANG': 'en_US.UTF-8', 15:37:52 INFO - 'LANGUAGE': 'en_US:en', 15:37:52 INFO - 'LOGNAME': 'cltbld', 15:37:52 INFO - 'MAIL': '/var/mail/cltbld', 15:37:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:37:52 INFO - 'MOZ_NO_REMOTE': '1', 15:37:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:37:52 INFO - 'NO_EM_RESTART': '1', 15:37:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:37:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:37:52 INFO - 'PWD': '/builds/slave/test', 15:37:52 INFO - 'SHELL': '/bin/bash', 15:37:52 INFO - 'SHLVL': '1', 15:37:52 INFO - 'TERM': 'linux', 15:37:52 INFO - 'TMOUT': '86400', 15:37:52 INFO - 'USER': 'cltbld', 15:37:52 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1476916634.109370-2000386204', 15:37:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:37:52 INFO - '_': '/tools/buildbot/bin/python'} 15:37:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:37:52 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:37:52 INFO - Cleaning up... 15:37:52 INFO - Return code: 0 15:37:52 INFO - Running post-action listener: _resource_record_post_action 15:37:52 INFO - Running post-action listener: _start_resource_monitoring 15:37:52 INFO - Starting resource monitoring. 15:37:52 INFO - [mozharness: 2016-10-19 22:37:52.883400Z] Finished create-virtualenv step (success) 15:37:52 INFO - [mozharness: 2016-10-19 22:37:52.884001Z] Running install step. 15:37:52 INFO - Running pre-action listener: _resource_record_pre_action 15:37:52 INFO - Running main action method: install 15:37:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:37:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:37:53 INFO - Reading from file tmpfile_stdout 15:37:53 INFO - Detecting whether we're running mozinstall >=1.0... 15:37:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:37:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:37:53 INFO - Reading from file tmpfile_stdout 15:37:53 INFO - Output received: 15:37:53 INFO - Usage: mozinstall [options] installer 15:37:53 INFO - Options: 15:37:53 INFO - -h, --help show this help message and exit 15:37:53 INFO - -d DEST, --destination=DEST 15:37:53 INFO - Directory to install application into. [default: 15:37:53 INFO - "/builds/slave/test"] 15:37:53 INFO - --app=APP Application being installed. [default: firefox] 15:37:53 INFO - mkdir: /builds/slave/test/build/application 15:37:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 15:37:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 15:38:10 INFO - Reading from file tmpfile_stdout 15:38:10 INFO - Output received: 15:38:10 INFO - /builds/slave/test/build/application/firefox/firefox 15:38:10 INFO - Running post-action listener: _resource_record_post_action 15:38:10 INFO - [mozharness: 2016-10-19 22:38:10.062611Z] Finished install step (success) 15:38:10 INFO - [mozharness: 2016-10-19 22:38:10.062857Z] Running run-tests step. 15:38:10 INFO - Running pre-action listener: _resource_record_pre_action 15:38:10 INFO - Running main action method: run_tests 15:38:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:38:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:38:10 INFO - Python 2.7.3 15:38:10 INFO - Return code: 0 15:38:10 INFO - grabbing minidump binary from tooltool 15:38:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:38:10 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 0x1dc5f60>, '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 0x1dc6d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dc71d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:38:10 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 15:38:10 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 15:38:10 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 15:38:10 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:38:10 INFO - Return code: 0 15:38:10 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:38:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:38:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:38:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:38:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:38:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/W4NhmvbwRimCsx9P9pmVHg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-058', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 15:38:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/W4NhmvbwRimCsx9P9pmVHg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-058 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 15:38:10 INFO - Using env: {'DISPLAY': ':0', 15:38:10 INFO - 'HOME': '/home/cltbld', 15:38:10 INFO - 'LANG': 'en_US.UTF-8', 15:38:10 INFO - 'LANGUAGE': 'en_US:en', 15:38:10 INFO - 'LOGNAME': 'cltbld', 15:38:10 INFO - 'MAIL': '/var/mail/cltbld', 15:38:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:38:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:38:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:38:10 INFO - 'MOZ_NO_REMOTE': '1', 15:38:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:38:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:38:10 INFO - 'NO_EM_RESTART': '1', 15:38:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:38:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:38:10 INFO - 'PWD': '/builds/slave/test', 15:38:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:38:10 INFO - 'SHELL': '/bin/bash', 15:38:10 INFO - 'SHLVL': '1', 15:38:10 INFO - 'TERM': 'linux', 15:38:10 INFO - 'TMOUT': '86400', 15:38:10 INFO - 'USER': 'cltbld', 15:38:10 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1476916634.109370-2000386204', 15:38:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:38:10 INFO - '_': '/tools/buildbot/bin/python'} 15:38:10 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/W4NhmvbwRimCsx9P9pmVHg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-058', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 15:38:10 INFO - mozversion application_buildid: 20161019122722 15:38:10 INFO - mozversion application_changeset: 49dfb63c28b9c093f13391b999de40b01f125613 15:38:10 INFO - mozversion application_display_name: FirefoxDeveloperEdition 15:38:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:38:10 INFO - mozversion application_name: Firefox 15:38:10 INFO - mozversion application_remotingname: firefox-dev 15:38:10 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:38:10 INFO - mozversion application_vendor: Mozilla 15:38:10 INFO - mozversion application_version: 51.0a2 15:38:10 INFO - mozversion platform_buildid: 20161019122722 15:38:10 INFO - mozversion platform_changeset: 49dfb63c28b9c093f13391b999de40b01f125613 15:38:10 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:38:10 INFO - mozversion platform_version: 51.0a2 15:38:10 INFO - using testdate: 1476916690 15:38:10 INFO - actual date: 1476916690 15:38:10 INFO - starting webserver on 'localhost:54212' 15:38:10 INFO - SUITE-START | Running 2 tests 15:38:10 INFO - TEST-START | dromaeo_css 15:38:10 INFO - operating with platform_type : linux_ 15:38:10 INFO - Initialising browser for dromaeo_css test... 15:38:10 INFO - TEST-INFO | started process 18440 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfiHGkV/profile http://localhost:54212/getInfo.html) 15:38:14 INFO - PROCESS | 18440 | __metrics Screen width/height:1600/1200 15:38:14 INFO - PROCESS | 18440 | colorDepth:24 15:38:14 INFO - PROCESS | 18440 | Browser inner width/height: 1024/697 15:38:14 INFO - PROCESS | 18440 | __metrics 15:38:15 INFO - PROCESS | 18440 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:38:15 INFO - PROCESS | 18440 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:38:15 INFO - PROCESS | 18440 | console.error: PushService: 15:38:15 INFO - PROCESS | 18440 | stateChangeProcessEnqueue: Error transitioning state 15:38:15 INFO - PROCESS | 18440 | UnknownError 15:38:16 INFO - TEST-INFO | 18440: exit 0 15:38:16 INFO - Browser initialized. 15:38:16 INFO - Running cycle 1/1 for dromaeo_css test... 15:38:16 INFO - TEST-INFO | started process 18533 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfiHGkV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:38:28 INFO - PROCESS | 18533 | RSS: Main: 145158144 15:38:28 INFO - PROCESS | 18533 | 15:39:19 INFO - PROCESS | 18533 | 1476916759227 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] 15:41:28 INFO - PROCESS | 18533 | Cycle 1(1): loaded http://localhost:54212/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54212/tests/dromaeo/cssquery-ext.html) 15:41:29 INFO - PROCESS | 18533 | RSS: Main: 238800896 15:41:29 INFO - PROCESS | 18533 | 15:44:31 INFO - PROCESS | 18533 | Cycle 1(1): loaded http://localhost:54212/tests/dromaeo/cssquery-ext.html (next: http://localhost:54212/tests/dromaeo/cssquery-jquery.html) 15:44:32 INFO - PROCESS | 18533 | RSS: Main: 232230912 15:44:32 INFO - PROCESS | 18533 | 15:47:34 INFO - PROCESS | 18533 | Cycle 1(1): loaded http://localhost:54212/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54212/tests/dromaeo/cssquery-mootools.html) 15:47:35 INFO - PROCESS | 18533 | RSS: Main: 176160768 15:47:35 INFO - PROCESS | 18533 | 15:50:36 INFO - PROCESS | 18533 | Cycle 1(1): loaded http://localhost:54212/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54212/tests/dromaeo/cssquery-prototype.html) 15:50:37 INFO - PROCESS | 18533 | RSS: Main: 218972160 15:50:37 INFO - PROCESS | 18533 | 15:53:40 INFO - PROCESS | 18533 | Cycle 1(1): loaded http://localhost:54212/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54212/tests/dromaeo/cssquery-yui.html) 15:53:40 INFO - PROCESS | 18533 | RSS: Main: 177512448 15:53:40 INFO - PROCESS | 18533 | 15:56:43 INFO - PROCESS | 18533 | Cycle 1(1): loaded http://localhost:54212/tests/dromaeo/cssquery-yui.html (next: http://localhost:54212/tests/dromaeo/cssquery-dojo.html) 15:56:43 INFO - PROCESS | 18533 | RSS: Main: 263835648 15:56:43 INFO - PROCESS | 18533 | 15:56:43 INFO - PROCESS | 18533 | __start_tp_report 15:56:43 INFO - PROCESS | 18533 | _x_x_mozilla_page_load 15:56:43 INFO - PROCESS | 18533 | _x_x_mozilla_page_load_details 15:56:43 INFO - PROCESS | 18533 | |i|pagename|runs| 15:56:43 INFO - PROCESS | 18533 | |0;dojo.html;3137.8117312961226;3172.825494597797;3176.364727054588;3203.0390882735173;3213.9035828925125;5572.832815015546;5644.604877658566;5654.773809047643;5656;5669.376368599451;5706.28671416073;5754.395788442205;5755.654660720349;5760.279965004374;5795.739191736371;5161.690298582078;5192.688438693672;5212.635115541913;5228.032813929435;5260.710660913648;5022.598192144638;5263.3683957925105;5280.762365693534;5282.947170528303;5313.1764576490705;5022.4224214215465;5138.306328645623;5160.793568257265;5166.509181627739;5195.896082078356;5178;5207.505286997767;5213.061648903177;5286.418493965661;5292.100342941709;5139.997700448414;5190.325257716473;5256.710880901549;5264.289320941701;5288.048151332759;5429.4299098594865;5480.04099282624;5554.944450555483;5634.661920284796;5678.517326027251;5945.31628862682;5982.790602328898;5990.071538911476;6032.638041717511;6044.2746870375395;6066.514678825683;6090;6090.604110732833;6105.206323177958;6110.450059494667;5466.01611709892;5521.282233309684;5551.111822108443;5603.719814009283;5608.46719561641;6177.320494745574;6178.289496707846;6192;6198.907016394758;6204.844878878064;5947.434993675681;5950.047992321294;5963.612365196248;5968.4926781224085;6006;5850.707464626752;5860.09168578871;5873.735681894325;5918.319393269829;6041.154238406627;5951.732172052268;5971.402859713994;5978;5986.760529578855;5987.6108052976415;5926.61477004002;5934.821955341346;5969.940300597025;5989.2812862456785;6011.158437818709;4526.977365113219;4576.244919588231;4603.815847366068;4611.815527378867;4612.3542704021465;4137.379393091061;4149.875503734893;4162.4796900387455;4200.663946884242;4211.115665710236;5782.942170578241;5788.652680839163;5791.276090488689;5794.768209271666;5809.970950145223;5933.436323549256;5994.100884867305;6152.107944348045;6163.599366041193;6168.0439531872635;5682.715864206773;5701.372848986608;5713.05734553794;5752.395998420189;5794.739236734357;5276.445973172838;5305.761240744176;5319.308489896289;5319.9202011969855;5325;5965.940340596712;6295.181626388632;6340.714667839959;6360.0777887206095;6369.71336289868;5811.041178205549;6007.068904319837;6077.058056001327;6107.297660769068;6164.167837341902;6335.809925702236;6340.270868850142;6341.55609107358;6354.52341074412;6375.043743438521;6023.186869772525;6032.125341825498;6038.818835434944;6039.939600604113;6046.7278972445465;5810.128480727924;5817.883642327045;5949.0481522956115;6005.279366476051;6022.578419510767;5930.11048342752;5933.614315069679;5937.584369094295;5940.940590594038;5948.881022379442;5940.079287710412;5942.019566771434;5942.61373010753;5948.672822994777;5955.196048533393;5557.527610153183;5672.829815105553;5898.4101589840675;5924.348321684697;5963.254593175853;5977.671228082497;6006.669633170218;6015.639061656315;6031.969840150771;6057.848553786191;5604.215409842707;5819.0689489681445;5864.618799778001;5872.618279811799;5877.265341832271 15:56:43 INFO - PROCESS | 18533 | |1;ext.html;560.9570061695615;573.6558065160903;611.7797592866567;634.8095571328603;653.3597074866634;46230.537694623046;47111.52888471119;47427;50665.74667126669;50820.49179508204;48312.27531587029;48803.51196488034;48957;48968.51031489684;48987;8717.346199035066;8926.687565935194;9405.999999999984;9568.952155239214;9570.952145239264;11019;11340.03609693177;11342.71643208918;11745.647630571097;11774.352410617412;11564.942175289112;11644.534218631245;11650.475728592233;11652.766944661102;11663.183577149603;11385.203035787497;11464.598739044177;11467.827982580267;11485.253458525169;11490;11202.215844890861;11247.718807029807;11251.54993800247;11272.267302625303;11283.605073822377;45736.54263457356;45923;46073.30890036652;46139.53860461386;46164.53835461669;3702.7593206441766;3756.981215093934;3793.563740169888;3989.341758609855;4092.877213683594;34416;34844.303113937836;34912;34960.82519587411;35052.474212886824;4638.721676699409;4657.441107067174;4702.482726900038;4731.195696731564;4781.569658730731;393695;394069.02965485275;395164;396406;397464;64481;64778.352216478175;65035.34964650293;65046.34953650403;65089;40241.396379054335;41448.58551414447;41513.1697366051;43926.78036609796;67837.32162678409;18743.156557954924;18821.341253056078;18829.058547072866;18868.33960811365;18878.905605471886;13845.446182152802;14152.787708184385;14770.63073423173;15023.474178403922;15088.773668394982;20266.392008239778;21054.78945210559;21166.259180928595;21172.894135529223;21245;7684.3084122429245;7700.691972321157;7741.690332386642;7824.93103586132;7890.053193616688;1013.4172850610928;1058.523664351045;1068.7007637861411;1177.3053898200103;1203.5366383942246;34428.48357274643;34698.653013470044;35065.123371915906;35362.39115790493;35378.11554711153;1171.4260012593768;1183.0240051957169;1201.5073819733868;1235.4008305971597;1237.0351126121639;18236.817631823513;18296.817031829778;18370.540736481697;18464.35374761877;18633.440996770118;582488;584963;586064;588138;588468;63334;64019.67990160113;64026.35973640297;64044;64074;1311.7966715159166;1363.4409891944256;1399.4472183487464;1426.6504706346805;1456.5775924981638;31431.371372572878;36198.8190059048;36261.09347266339;36403.63596364002;36835.815820920725;54989;55480;55569.444305556426;55579.722101389234;55666.72166639141;406200.7519699297;407152;407410;407481;408278;31113;31676.841615791775;32071.198220044687;32161.839190803898;33339.66660333463;48339.75830120827;52044.47955520548;52107.73946130245;52133.7393313031;52230.21654675107;43280.56719432891;43923;43964.560354396046;44119.55880441283;44979.77510112429;44579;45189.322160166965;45951.3107303384;46480;46632.533674662816;45169.32246016247;46003.76998115122;46036.53963460457;46068.769656151504;46325.07349853052;36980.260394792494;39908.80045599753;40275.1944961105;40392;40719.59280407158;29704.257393565334;29867.850660746557;30768.692313076583;30865.69134308628;30989.380212395175 15:56:43 INFO - PROCESS | 18533 | |2;jquery.html;391.2043719328283;429.5742333564392;432.4032834688131;436.23440861288447;446.3728461511577;16846.15769211539;19901.900490497545;21515.246966356182;21683;21972.230971915986;12973.286469244187;16354.100524471149;19138.94735789531;19725.40823775289;19783.406497805052;5553.7778488860395;5773.826785196451;5782.508486778629;5783.074708046714;5787.971060144693;5713.342965558952;5899.88200235995;6164.198654174951;6187.381261873822;6276.780312689057;6046.12331211974;6225.875482490347;6245.531585131111;6250.406211409909;6254.593451425665;6185.350538193492;6224.470919971808;6242.625442473443;6250.6874656267455;6252.5935814171935;5939.4357536034;6072.514198864124;6089.751600921801;6135.846603834895;6173.166622505949;13885.791713124312;13997;14160.858391416057;14303.49937752184;14311.998160128795;2104.705341252226;2427.368884090132;2556.9042875101095;2602.9978458293504;3145.889893853704;11077.778444431066;11171.66485005451;11342.659720208407;11532.250403723816;11737.413129343498;1926.3161577639876;2063.719064076579;2120.1731324783345;2144.871307721526;2183.759786423492;82197;83211;85065.57467212687;86265.65917677445;88173.55913220393;10720.303180293256;11854.407279635983;11934.28394296345;12347.567835125728;12482.875171248354;15504.379824807107;16643.251053702614;16724.498265051825;16740.497785066225;17356.47930562085;8856.734297971072;8982;9002.684906028258;9009;9198.954005230065;8559.315254779603;8585.828283434297;8617.741467755977;8847.557622118868;9075.636974521078;5146.228065790161;5234.7644356004075;5246.7638956247065;5271.578273738091;5317.282166907496;2183.344996501043;2188.868667879932;2193.561287742458;2215.268961242786;2234.664800279971;945.4232917920064;978.8580655804868;981.1513041219293;1039.4698703661188;1085.2132204152085;20885.79114208869;20999.8950005249;21022.78977210208;21089.367318980454;21392.1326372006;1077.9514921828536;1089.2810744908322;1091.1380009792176;1094.6497120921229;1098.2312381332938;11161.162912781574;11675.75275925069;11816.88183118175;11846.170768046153;11972.760544789055;483534;516856;526320;541519;542773;95880.52059739656;97505.5124724386;98381;100238;100520;1033.0280073118845;1068.2041878800344;1108.667399780079;1129.7401597632427;1140.236041851961;18328.358507452176;18373.724394134366;18638.906805466428;18939.57072424798;19072.713909291648;44153;44530.55469445264;44764.32853507265;45127;45877.54122458733;58936.70531647486;59026;60032.39967600268;65576;66475;8033.6786528538205;8171.264586187272;8250.711225107332;8482.109378515168;9158.58786354629;7819.960900195462;11757.588484403004;12319.644839067516;12626;13222.471101155847;11525.020373268366;11527.135464840003;11554.826677599674;11626.53493860236;11727.179097463437;11365.488553015299;11535.5962541314;11589;11713.238639488405;11998.520059197883;11793;11795.233309834834;11891.821622675494;12019.75960480803;12295.139340246096;7882.487638303492;8088.393370497354;8540.957295213484;8691.869621955802;8762.912370876209;9144.177024067865;11555.4800033997;11825.231359961575;11926.463309151282;11936 15:56:43 INFO - PROCESS | 18533 | |3;mootools.html;1409.7532931736944;1526.2902750221144;1559.4619856149627;1705.1729910993174;1853.694140466824;8206.876896846545;9061.411008284465;9383.296252781034;9399.95300023499;9750.902490975088;9493.335466517347;9590.616375344978;9594.616215351394;9597.328187026893;9604.711858644234;2588.9093881714143;2613.751693589106;2618.9738102619017;2619.2011436511857;2619.554675705125;2559.3217797283733;2593.0276146445085;2606;2616.3851494898777;2618.0705849423466;2528.2415275417393;2543.669322988009;2567.114345550782;2569.511792759379;2572.6655534780452;2498.612715029173;2500.8999640014376;2508.435601989556;2533;2542.1610868413377;2500.4749002709455;2520.218732193026;2523.028633975914;2567.2169988153605;2575.1244576843774;9303;9303.395279306824;9511.476868772215;9513.429194248298;9548.283878709055;5484.61607687462;5614.29821272341;5633.380328163898;5634.774609015634;5637.689927054011;3764.416515440111;3806.505154329947;3873.845046198149;3873.845046198149;3898.4737060497037;1311.3050083455785;1362.8773410393112;1369.760291948905;1407.8521755215656;1412.894032947533;13169.144005639602;13211.207327560282;13261.138026028277;13294.534691285759;13300.135491193041;4922.8769280768265;4991.201407774739;5095.414027386824;5120.436751957282;5139.948600514022;10626.628068017582;10662.093722033715;10667.14662826972;10685.252032357661;10689.30519516229;5684.8010319638615;5707.172459993278;5713.085906254979;5718.714064296769;5730;4499.122671079108;4713.080949214938;4883.706977581356;5290.867728306823;5370.087085195526;4243.469566304212;4244.405783190356;4299.892502687458;4337.7397356158735;4338.6312163466455;1305.5691621764795;1319.3271431569776;1328.6811165320446;1330.6872884872048;1339.6316013096282;967.4727273635885;984.7636567223958;1013.898610138995;1014.7361685961703;1025.6153942271649;5991.490723288569;6154.3845615438995;6175.567710260329;6177.752889884444;6460.418562329413;495.87355224418263;505.26736232463077;509.23360342684026;509.8368522072901;528.9550388217044;4130.876073717794;4239.236937351307;4351.369051487517;4418.1163767246035;4509.1658043262105;11500.942495287638;11734.767849375863;11767.23512971654;11823.408829558488;11976;11633.302001879914;11690.47392867323;11768.235064720766;11876.524939002344;11927.403629818475;8778.341624378198;8960.059193784558;8969.910300896907;8991.865122023175;8992.8651070234;6479.8056058318325;6496.577722448026;6576.736930522917;6676.732930682719;6756.155480564929;10790.73023174427;10904.56381744753;10991.120710342993;10991.890081099087;11015.173861960546;11026.779464410507;11028.834567481656;11110.944445277722;11176.776464470502;11222.382768947786;8396.706115285935;8453.873191902003;8528;8543.145685431407;8607.569621518775;8996.775080623036;9052.637894484147;9063.41087829289;9073.45559266446;9087.863682044643;7972.760817175494;8167.224113709188;8235.094139644754;8263.421560490708;8521.659133634585;7960;8005.879911801444;8095.5142691438705;8166.673333066611;8238.629261683358;7369.263073692802;7458.254174582498;7529.774106776806;7595.734149304969;7677.001989741298;5112.565431938327;5192.273081768631;5470.288862447856;5567.109262517978;5640.858978525405;5067.771950262173;5171.534561889298;5298.28473156119;5308.681479111266;5331.920021199608 15:56:43 INFO - PROCESS | 18533 | |4;prototype.html;346.791924845093;355.8772223582863;359.7391890879113;370.18928546483215;370.98701545445914;5561.110222364423;5606.887862242757;5612.69130197839;5615.157726341045;5631.521320687746;5259.184826351912;5292.412276317099;5380.21986811912;5440.646357986738;5575.665460072399;4347.652187824982;4349.108432771274;4349.259966221276;4363.694541382097;4368.366586844905;4376.781160941956;4378.584034516716;4385.188740083079;4385.956140438602;4425.557444255581;4156.5635608261;4313.590208930146;4337;4362.236608593499;4389.780510974439;4260.829566817323;4281.529031806499;4326.264535029052;4368.584984426474;4370.978145109286;3816.912180028706;3827.7320587558884;3851.768893866349;3855.730098893079;3876.9224615507533;5179.8446046618665;5275.551578115865;5431.266778984826;5461.5357694596005;5479.808206712746;4967.7019378837385;4972;4973;4973.055119527278;4978.6763860348965;5725;5735.139729040636;5738.4835364816945;5766.7981620643495;5777.248957635523;4179.310413781753;4198.895027624303;4236.88155922039;4265.573442655741;4294.527601963781;5949.583529153005;5958.463738263577;5963.850903727442;5978.83412734517;5991.951408503494;4338.414314067623;4348.565143485689;4383.473983122046;4406.449193850769;4408;4297.495876443282;4347.847825326098;4368.628666563378;4386.451693538308;4452.933206001912;4885.534339698076;4916.06594746997;4929.704217746947;4937.284093806378;4938.913439043405;5787.524181333733;5803.245578074823;5834.329052159056;5845.181674565564;5853;1398.4406237504877;1436.561848636155;1447.2980604406855;1459.408939379553;1471.8160229971254;1015.0712098429854;1031.5461197073264;1046.6493724602344;1047.643801107627;1050.9369437833755;5551.583631227674;5555.555555555546;5577.498025177672;5589.441055894378;5618.297712785902;5692.459216374438;5909.202257695242;5991.880162396815;5996.910046349309;6013.729382177812;5507.421720719267;5527.170924361298;5528.55771538276;5551.306086739158;5560.527355174855;3090.8145511269395;3101.410731960965;3114.9532757008665;3131.7964332318784;3144.7421031587546;5893.233879595711;5985.683149707057;6166.383361663888;6190.535709821781;6238.781642642486;6104.840080384713;6112.2970858351855;6122.816315510453;6125.724342404603;6139.785107521305;5872.647641141545;6162.672157035151;6180.134781130645;6238.220222472191;6256.874862502725;5976.701164941823;5990;5990.191324171181;6013.338532761481;6015.608985415934;5953.970230148821;5977.163197152489;5981.850453738692;5982.88034239304;6001.549883758648;5887.0580707086665;6121.877562448815;6134.631922084511;6191.9473689472;6197.442230199304;4301.956980430281;4344.652427805831;4356.5405589127995;4375.890602734957;4394.978025109854;5764;5978.432048955342;6029.75880964774;6041.728122234422;6047.516198704006;5840.678762668094;5845.1232315153065;5851.824445266649;5862.560307977004;5863.091220860773;5873.73568189424;5879.588428810113;5885.234919460443;5907.350191478848;5938.287405511366;5610.28886189716;5808.970955145197;5811.4479124483105;5815.738291776965;5837.190470954018;5840.123981402823;5858.9121163181735;5870.530357571299;5870.882582348244;5879.853003674943;5452.26394436746;5662.8301150965535;5772.451617096369;5772.797952071657;5777.24895763548 15:56:43 INFO - PROCESS | 18533 | |5;yui.html;805.6777289084365;809.1786836361091;857.6655104509239;861.487414988082;927.0636656976454;16772.748408773863;18199.36302229419;18301.542461438454;18306.633867322646;18317.542061448454;16654.750178747297;16882.577935551588;16891.915540422313;17950;17956.102194890267;685.9074025006622;707.6214225389417;870.7083127152412;874.9343799215053;893.0355216366308;883.3992884838308;883.9292856571491;885.0972008551274;885.4023534114447;886.5124181700071;779.123486078162;938.8967213606497;939.5865819039601;939.8402271613842;941.256407438123;788.5820515126964;908.4912449028532;935.4621092871558;941.0495399646406;944.4050248343568;680.0275605883585;681.2778454837887;681.9829504262384;682.3756263019332;682.743971010871;16163.353465861352;16223.594410139842;16264.43074492399;16292.674146517003;16346.34614615426;15753.291101900442;15898.60253493672;16088.75866861998;16404.67190656192;16644.916775416168;13197.736045279136;13271.402786874614;13469.461221551126;13699.931500342434;13994.230317332542;4211.684123690705;4228.281192197333;4303.526612072701;4319.481662200525;4324.481062272547;91048;91335.54332228296;91445;93441.06558934391;94553;13138.4087716053;13178.275194864184;13376.732465350638;13514;13606.455741770218;25633.48733025329;25875.223743287355;26244.212673619822;26298.6055209172;26325;9514.666986655433;9534;9642.987486313976;9785.07041831025;10022.749431264278;5209.322788037607;5412.377576578665;5417.160340147283;5439.5376393006245;5589.469000444951;3977.2642061218767;4106.507219133723;4109.054917368962;4112.115895082572;4113.382992551141;712.4620911212002;737.0271241960668;738.6011553761016;739.6930273936364;748.636911098117;678.0067201549754;704.8872180451103;715.8675645005691;782.6634547144782;795.129333379948;10521.84217236742;10881.673549793519;11152.10783137347;11259.605913793142;11342.773144537063;660.3066779881106;684.226823689228;696.7839969609336;727.7380143148463;738.3650060947581;5238.73806309683;5311.867203319871;5325.3609566851455;5358.571314294847;5421.376541697677;133069;133722.33138834243;133923.3303833494;134593.53125031103;138823;113958.43020784843;115805.8419415812;116529.41735291269;116937.4153129229;117136;94663;95974.52012740032;96918;98359.50820245995;98617.50691246497;10262.281640285108;10320.750600043544;10389.324693894872;10490.475476226311;10518.368897866152;42041;49303.50696492989;52892;56215.15677264763;57962;51102.48897510977;77890;78774;80614.19385806067;83535;14371.568852934428;14648.633784155056;14706.264686765406;14894.743149317137;14967;15844.287007084478;15882.60293492672;16102.838971610134;16263.593410164367;16992.150392480573;14677.853221468076;14891.925540372229;14949.925250373679;15092.094474331576;15257.237138142826;13688.315584220949;14154.221517816208;14277;14637.853621464075;15416.306266218271;14311.212883291519;14439.566812995627;14680.41278348854;14701;14997.100173989596;11974.521019159136;12297.508099675913;12307.323097229739;12349.382530873241;12657.87342126567;13179.67050823737;13209.207447553177;13236.933815330862;13388.799168012682;13422.530211442552 15:56:43 INFO - PROCESS | 18533 | __end_tp_report 15:56:43 INFO - PROCESS | 18533 | __start_cc_report 15:56:43 INFO - PROCESS | 18533 | _x_x_mozilla_cycle_collect,821 15:56:43 INFO - PROCESS | 18533 | __end_cc_report 15:56:43 INFO - PROCESS | 18533 | __startTimestamp1476917803606__endTimestamp 15:56:43 INFO - PROCESS | 18533 | 15:56:43 INFO - PROCESS | 18533 | ------- Summary: start ------- 15:56:43 INFO - PROCESS | 18533 | Number of tests: 6 15:56:43 INFO - PROCESS | 18533 | 15:56:43 INFO - PROCESS | 18533 | [#0] dojo.html Cycles:165 Average:5624.69 Median:5850.71 stddev:640.29 (10.9%) stddev-sans-first:611.81 15:56:43 INFO - PROCESS | 18533 | Values: 3137.8 3172.8 3176.4 3203.0 3213.9 5572.8 5644.6 5654.8 5656.0 5669.4 5706.3 5754.4 5755.7 5760.3 5795.7 5161.7 5192.7 5212.6 5228.0 5260.7 5022.6 5263.4 5280.8 5282.9 5313.2 5022.4 5138.3 5160.8 5166.5 5195.9 5178.0 5207.5 5213.1 5286.4 5292.1 5140.0 5190.3 5256.7 5264.3 5288.0 5429.4 5480.0 5554.9 5634.7 5678.5 5945.3 5982.8 5990.1 6032.6 6044.3 6066.5 6090.0 6090.6 6105.2 6110.5 5466.0 5521.3 5551.1 5603.7 5608.5 6177.3 6178.3 6192.0 6198.9 6204.8 5947.4 5950.0 5963.6 5968.5 6006.0 5850.7 5860.1 5873.7 5918.3 6041.2 5951.7 5971.4 5978.0 5986.8 5987.6 5926.6 5934.8 5969.9 5989.3 6011.2 4527.0 4576.2 4603.8 4611.8 4612.4 4137.4 4149.9 4162.5 4200.7 4211.1 5782.9 5788.7 5791.3 5794.8 5810.0 5933.4 5994.1 6152.1 6163.6 6168.0 5682.7 5701.4 5713.1 5752.4 5794.7 5276.4 5305.8 5319.3 5319.9 5325.0 5965.9 6295.2 6340.7 6360.1 6369.7 5811.0 6007.1 6077.1 6107.3 6164.2 6335.8 6340.3 6341.6 6354.5 6375.0 6023.2 6032.1 6038.8 6039.9 6046.7 5810.1 5817.9 5949.0 6005.3 6022.6 5930.1 5933.6 5937.6 5940.9 5948.9 5940.1 5942.0 5942.6 5948.7 5955.2 5557.5 5672.8 5898.4 5924.3 5963.3 5977.7 6006.7 6015.6 6032.0 6057.8 5604.2 5819.1 5864.6 5872.6 5877.3 15:56:43 INFO - PROCESS | 18533 | 15:56:43 INFO - PROCESS | 18533 | [#1] ext.html Cycles:180 Average:64060.46 Median:40075.10 stddev:124679.74 (311.1%) stddev-sans-first:124938.35 15:56:43 INFO - PROCESS | 18533 | Values: 561.0 573.7 611.8 634.8 653.4 46230.5 47111.5 47427.0 50665.7 50820.5 48312.3 48803.5 48957.0 48968.5 48987.0 8717.3 8926.7 9406.0 9569.0 9571.0 11019.0 11340.0 11342.7 11745.6 11774.4 11564.9 11644.5 11650.5 11652.8 11663.2 11385.2 11464.6 11467.8 11485.3 11490.0 11202.2 11247.7 11251.5 11272.3 11283.6 45736.5 45923.0 46073.3 46139.5 46164.5 3702.8 3757.0 3793.6 3989.3 4092.9 34416.0 34844.3 34912.0 34960.8 35052.5 4638.7 4657.4 4702.5 4731.2 4781.6 393695.0 394069.0 395164.0 396406.0 397464.0 64481.0 64778.4 65035.3 65046.3 65089.0 40241.4 41448.6 41513.2 43926.8 67837.3 18743.2 18821.3 18829.1 18868.3 18878.9 13845.4 14152.8 14770.6 15023.5 15088.8 20266.4 21054.8 21166.3 21172.9 21245.0 7684.3 7700.7 7741.7 7824.9 7890.1 1013.4 1058.5 1068.7 1177.3 1203.5 34428.5 34698.7 35065.1 35362.4 35378.1 1171.4 1183.0 1201.5 1235.4 1237.0 18236.8 18296.8 18370.5 18464.4 18633.4 582488.0 584963.0 586064.0 588138.0 588468.0 63334.0 64019.7 64026.4 64044.0 64074.0 1311.8 1363.4 1399.4 1426.7 1456.6 31431.4 36198.8 36261.1 36403.6 36835.8 54989.0 55480.0 55569.4 55579.7 55666.7 406200.8 407152.0 407410.0 407481.0 408278.0 31113.0 31676.8 32071.2 32161.8 33339.7 48339.8 52044.5 52107.7 52133.7 52230.2 43280.6 43923.0 43964.6 44119.6 44979.8 44579.0 45189.3 45951.3 46480.0 46632.5 45169.3 46003.8 46036.5 46068.8 46325.1 36980.3 39908.8 40275.2 40392.0 40719.6 29704.3 29867.9 30768.7 30865.7 30989.4 15:56:43 INFO - PROCESS | 18533 | 15:56:43 INFO - PROCESS | 18533 | [#2] jquery.html Cycles:180 Average:30404.91 Median:12093.75 stddev:86247.76 (713.2%) stddev-sans-first:86460.27 15:56:43 INFO - PROCESS | 18533 | Values: 391.2 429.6 432.4 436.2 446.4 16846.2 19901.9 21515.2 21683.0 21972.2 12973.3 16354.1 19138.9 19725.4 19783.4 5553.8 5773.8 5782.5 5783.1 5788.0 5713.3 5899.9 6164.2 6187.4 6276.8 6046.1 6225.9 6245.5 6250.4 6254.6 6185.4 6224.5 6242.6 6250.7 6252.6 5939.4 6072.5 6089.8 6135.8 6173.2 13885.8 13997.0 14160.9 14303.5 14312.0 2104.7 2427.4 2556.9 2603.0 3145.9 11077.8 11171.7 11342.7 11532.3 11737.4 1926.3 2063.7 2120.2 2144.9 2183.8 82197.0 83211.0 85065.6 86265.7 88173.6 10720.3 11854.4 11934.3 12347.6 12482.9 15504.4 16643.3 16724.5 16740.5 17356.5 8856.7 8982.0 9002.7 9009.0 9199.0 8559.3 8585.8 8617.7 8847.6 9075.6 5146.2 5234.8 5246.8 5271.6 5317.3 2183.3 2188.9 2193.6 2215.3 2234.7 945.4 978.9 981.2 1039.5 1085.2 20885.8 20999.9 21022.8 21089.4 21392.1 1078.0 1089.3 1091.1 1094.6 1098.2 11161.2 11675.8 11816.9 11846.2 11972.8 483534.0 516856.0 526320.0 541519.0 542773.0 95880.5 97505.5 98381.0 100238.0 100520.0 1033.0 1068.2 1108.7 1129.7 1140.2 18328.4 18373.7 18638.9 18939.6 19072.7 44153.0 44530.6 44764.3 45127.0 45877.5 58936.7 59026.0 60032.4 65576.0 66475.0 8033.7 8171.3 8250.7 8482.1 9158.6 7820.0 11757.6 12319.6 12626.0 13222.5 11525.0 11527.1 11554.8 11626.5 11727.2 11365.5 11535.6 11589.0 11713.2 11998.5 11793.0 11795.2 11891.8 12019.8 12295.1 7882.5 8088.4 8541.0 8691.9 8762.9 9144.2 11555.5 11825.2 11926.5 11936.0 15:56:43 INFO - PROCESS | 18533 | 15:56:43 INFO - PROCESS | 18533 | [#3] mootools.html Cycles:180 Average:6218.69 Median:4903.29 stddev:3551.32 (72.4%) stddev-sans-first:3542.89 15:56:43 INFO - PROCESS | 18533 | Values: 1409.8 1526.3 1559.5 1705.2 1853.7 8206.9 9061.4 9383.3 9400.0 9750.9 9493.3 9590.6 9594.6 9597.3 9604.7 2588.9 2613.8 2619.0 2619.2 2619.6 2559.3 2593.0 2606.0 2616.4 2618.1 2528.2 2543.7 2567.1 2569.5 2572.7 2498.6 2500.9 2508.4 2533.0 2542.2 2500.5 2520.2 2523.0 2567.2 2575.1 9303.0 9303.4 9511.5 9513.4 9548.3 5484.6 5614.3 5633.4 5634.8 5637.7 3764.4 3806.5 3873.8 3873.8 3898.5 1311.3 1362.9 1369.8 1407.9 1412.9 13169.1 13211.2 13261.1 13294.5 13300.1 4922.9 4991.2 5095.4 5120.4 5139.9 10626.6 10662.1 10667.1 10685.3 10689.3 5684.8 5707.2 5713.1 5718.7 5730.0 4499.1 4713.1 4883.7 5290.9 5370.1 4243.5 4244.4 4299.9 4337.7 4338.6 1305.6 1319.3 1328.7 1330.7 1339.6 967.5 984.8 1013.9 1014.7 1025.6 5991.5 6154.4 6175.6 6177.8 6460.4 495.9 505.3 509.2 509.8 529.0 4130.9 4239.2 4351.4 4418.1 4509.2 11500.9 11734.8 11767.2 11823.4 11976.0 11633.3 11690.5 11768.2 11876.5 11927.4 8778.3 8960.1 8969.9 8991.9 8992.9 6479.8 6496.6 6576.7 6676.7 6756.2 10790.7 10904.6 10991.1 10991.9 11015.2 11026.8 11028.8 11110.9 11176.8 11222.4 8396.7 8453.9 8528.0 8543.1 8607.6 8996.8 9052.6 9063.4 9073.5 9087.9 7972.8 8167.2 8235.1 8263.4 8521.7 7960.0 8005.9 8095.5 8166.7 8238.6 7369.3 7458.3 7529.8 7595.7 7677.0 5112.6 5192.3 5470.3 5567.1 5640.9 5067.8 5171.5 5298.3 5308.7 5331.9 15:56:43 INFO - PROCESS | 18533 | 15:56:43 INFO - PROCESS | 18533 | [#4] prototype.html Cycles:180 Average:4912.33 Median:5558.04 stddev:1434.52 (25.8%) stddev-sans-first:1397.01 15:56:43 INFO - PROCESS | 18533 | Values: 346.8 355.9 359.7 370.2 371.0 5561.1 5606.9 5612.7 5615.2 5631.5 5259.2 5292.4 5380.2 5440.6 5575.7 4347.7 4349.1 4349.3 4363.7 4368.4 4376.8 4378.6 4385.2 4386.0 4425.6 4156.6 4313.6 4337.0 4362.2 4389.8 4260.8 4281.5 4326.3 4368.6 4371.0 3816.9 3827.7 3851.8 3855.7 3876.9 5179.8 5275.6 5431.3 5461.5 5479.8 4967.7 4972.0 4973.0 4973.1 4978.7 5725.0 5735.1 5738.5 5766.8 5777.2 4179.3 4198.9 4236.9 4265.6 4294.5 5949.6 5958.5 5963.9 5978.8 5992.0 4338.4 4348.6 4383.5 4406.4 4408.0 4297.5 4347.8 4368.6 4386.5 4452.9 4885.5 4916.1 4929.7 4937.3 4938.9 5787.5 5803.2 5834.3 5845.2 5853.0 1398.4 1436.6 1447.3 1459.4 1471.8 1015.1 1031.5 1046.6 1047.6 1050.9 5551.6 5555.6 5577.5 5589.4 5618.3 5692.5 5909.2 5991.9 5996.9 6013.7 5507.4 5527.2 5528.6 5551.3 5560.5 3090.8 3101.4 3115.0 3131.8 3144.7 5893.2 5985.7 6166.4 6190.5 6238.8 6104.8 6112.3 6122.8 6125.7 6139.8 5872.6 6162.7 6180.1 6238.2 6256.9 5976.7 5990.0 5990.2 6013.3 6015.6 5954.0 5977.2 5981.9 5982.9 6001.5 5887.1 6121.9 6134.6 6191.9 6197.4 4302.0 4344.7 4356.5 4375.9 4395.0 5764.0 5978.4 6029.8 6041.7 6047.5 5840.7 5845.1 5851.8 5862.6 5863.1 5873.7 5879.6 5885.2 5907.4 5938.3 5610.3 5809.0 5811.4 5815.7 5837.2 5840.1 5858.9 5870.5 5870.9 5879.9 5452.3 5662.8 5772.5 5772.8 5777.2 15:56:43 INFO - PROCESS | 18533 | 15:56:43 INFO - PROCESS | 18533 | [#5] yui.html Cycles:180 Average:23468.89 Median:15151.13 stddev:34331.85 (226.6%) stddev-sans-first:34385.99 15:56:43 INFO - PROCESS | 18533 | Values: 805.7 809.2 857.7 861.5 927.1 16772.7 18199.4 18301.5 18306.6 18317.5 16654.8 16882.6 16891.9 17950.0 17956.1 685.9 707.6 870.7 874.9 893.0 883.4 883.9 885.1 885.4 886.5 779.1 938.9 939.6 939.8 941.3 788.6 908.5 935.5 941.0 944.4 680.0 681.3 682.0 682.4 682.7 16163.4 16223.6 16264.4 16292.7 16346.3 15753.3 15898.6 16088.8 16404.7 16644.9 13197.7 13271.4 13469.5 13699.9 13994.2 4211.7 4228.3 4303.5 4319.5 4324.5 91048.0 91335.5 91445.0 93441.1 94553.0 13138.4 13178.3 13376.7 13514.0 13606.5 25633.5 25875.2 26244.2 26298.6 26325.0 9514.7 9534.0 9643.0 9785.1 10022.7 5209.3 5412.4 5417.2 5439.5 5589.5 3977.3 4106.5 4109.1 4112.1 4113.4 712.5 737.0 738.6 739.7 748.6 678.0 704.9 715.9 782.7 795.1 10521.8 10881.7 11152.1 11259.6 11342.8 660.3 684.2 696.8 727.7 738.4 5238.7 5311.9 5325.4 5358.6 5421.4 133069.0 133722.3 133923.3 134593.5 138823.0 113958.4 115805.8 116529.4 116937.4 117136.0 94663.0 95974.5 96918.0 98359.5 98617.5 10262.3 10320.8 10389.3 10490.5 10518.4 42041.0 49303.5 52892.0 56215.2 57962.0 51102.5 77890.0 78774.0 80614.2 83535.0 14371.6 14648.6 14706.3 14894.7 14967.0 15844.3 15882.6 16102.8 16263.6 16992.2 14677.9 14891.9 14949.9 15092.1 15257.2 13688.3 14154.2 14277.0 14637.9 15416.3 14311.2 14439.6 14680.4 14701.0 14997.1 11974.5 12297.5 12307.3 12349.4 12657.9 13179.7 13209.2 13236.9 13388.8 13422.5 15:56:43 INFO - PROCESS | 18533 | -------- Summary: end -------- 15:56:43 INFO - PROCESS | 18533 | 15:56:44 INFO - TEST-INFO | 18533: exit 0 15:56:44 INFO - TEST-OK | dromaeo_css | took 1114014ms 15:56:44 INFO - TEST-START | kraken 15:56:44 INFO - operating with platform_type : linux_ 15:56:44 INFO - Initialising browser for kraken test... 15:56:44 INFO - TEST-INFO | started process 18852 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprykDk1/profile http://localhost:54212/getInfo.html) 15:56:48 INFO - PROCESS | 18852 | __metrics Screen width/height:1600/1200 15:56:48 INFO - PROCESS | 18852 | colorDepth:24 15:56:48 INFO - PROCESS | 18852 | Browser inner width/height: 1024/697 15:56:48 INFO - PROCESS | 18852 | __metrics 15:56:48 INFO - PROCESS | 18852 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:56:48 INFO - PROCESS | 18852 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:56:49 INFO - PROCESS | 18852 | console.error: PushService: 15:56:49 INFO - PROCESS | 18852 | stateChangeProcessEnqueue: Error transitioning state 15:56:49 INFO - PROCESS | 18852 | UnknownError 15:56:49 INFO - TEST-INFO | 18852: exit 0 15:56:49 INFO - Browser initialized. 15:56:49 INFO - Running cycle 1/1 for kraken test... 15:56:50 INFO - TEST-INFO | started process 18944 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprykDk1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:57:01 INFO - PROCESS | 18944 | RSS: Main: 145031168 15:57:01 INFO - PROCESS | 18944 | 15:57:04 INFO - PROCESS | 18944 | Cycle 1(1): loaded http://localhost:54212/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54212/tests/kraken/driver.html?testName=audio-beat-detection) 15:57:05 INFO - PROCESS | 18944 | RSS: Main: 186372096 15:57:05 INFO - PROCESS | 18944 | 15:57:07 INFO - PROCESS | 18944 | Cycle 1(1): loaded http://localhost:54212/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54212/tests/kraken/driver.html?testName=audio-dft) 15:57:08 INFO - PROCESS | 18944 | RSS: Main: 176619520 15:57:08 INFO - PROCESS | 18944 | 15:57:11 INFO - PROCESS | 18944 | Cycle 1(1): loaded http://localhost:54212/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54212/tests/kraken/driver.html?testName=audio-fft) 15:57:11 INFO - PROCESS | 18944 | RSS: Main: 266391552 15:57:11 INFO - PROCESS | 18944 | 15:57:13 INFO - PROCESS | 18944 | Cycle 1(1): loaded http://localhost:54212/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54212/tests/kraken/driver.html?testName=audio-oscillator) 15:57:14 INFO - PROCESS | 18944 | RSS: Main: 173699072 15:57:14 INFO - PROCESS | 18944 | 15:57:16 INFO - PROCESS | 18944 | Cycle 1(1): loaded http://localhost:54212/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54212/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:57:17 INFO - PROCESS | 18944 | RSS: Main: 174993408 15:57:17 INFO - PROCESS | 18944 | 15:57:20 INFO - PROCESS | 18944 | Cycle 1(1): loaded http://localhost:54212/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54212/tests/kraken/driver.html?testName=imaging-darkroom) 15:57:20 INFO - PROCESS | 18944 | RSS: Main: 210817024 15:57:20 INFO - PROCESS | 18944 | 15:57:23 INFO - PROCESS | 18944 | Cycle 1(1): loaded http://localhost:54212/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54212/tests/kraken/driver.html?testName=imaging-desaturate) 15:57:24 INFO - PROCESS | 18944 | RSS: Main: 210001920 15:57:24 INFO - PROCESS | 18944 | 15:57:27 INFO - PROCESS | 18944 | Cycle 1(1): loaded http://localhost:54212/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54212/tests/kraken/driver.html?testName=json-parse-financial) 15:57:27 INFO - PROCESS | 18944 | RSS: Main: 209674240 15:57:27 INFO - PROCESS | 18944 | 15:57:29 INFO - PROCESS | 18944 | Cycle 1(1): loaded http://localhost:54212/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54212/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:57:29 INFO - PROCESS | 18944 | RSS: Main: 171491328 15:57:29 INFO - PROCESS | 18944 | 15:57:31 INFO - PROCESS | 18944 | Cycle 1(1): loaded http://localhost:54212/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54212/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:57:32 INFO - PROCESS | 18944 | RSS: Main: 178692096 15:57:32 INFO - PROCESS | 18944 | 15:57:34 INFO - PROCESS | 18944 | Cycle 1(1): loaded http://localhost:54212/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54212/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:57:34 INFO - PROCESS | 18944 | RSS: Main: 188612608 15:57:34 INFO - PROCESS | 18944 | 15:57:37 INFO - PROCESS | 18944 | Cycle 1(1): loaded http://localhost:54212/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54212/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:57:38 INFO - PROCESS | 18944 | RSS: Main: 197459968 15:57:38 INFO - PROCESS | 18944 | 15:57:40 INFO - PROCESS | 18944 | Cycle 1(1): loaded http://localhost:54212/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54212/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:57:41 INFO - PROCESS | 18944 | RSS: Main: 193658880 15:57:41 INFO - PROCESS | 18944 | 15:57:43 INFO - PROCESS | 18944 | Cycle 1(1): loaded http://localhost:54212/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54212/tests/kraken/driver.html?testName=ai-astar) 15:57:43 INFO - PROCESS | 18944 | RSS: Main: 245067776 15:57:43 INFO - PROCESS | 18944 | 15:57:43 INFO - PROCESS | 18944 | __start_tp_report 15:57:43 INFO - PROCESS | 18944 | _x_x_mozilla_page_load 15:57:43 INFO - PROCESS | 18944 | _x_x_mozilla_page_load_details 15:57:43 INFO - PROCESS | 18944 | |i|pagename|runs| 15:57:43 INFO - PROCESS | 18944 | |0;ai-astar;109;111;112;111;112;111;111;111;114;111 15:57:43 INFO - PROCESS | 18944 | |1;audio-beat-detection;148;121;117;157;118;120;117;118;150;123 15:57:43 INFO - PROCESS | 18944 | |2;audio-dft;163;162;161;162;160;161;160;160;161;161 15:57:43 INFO - PROCESS | 18944 | |3;audio-fft;75;76;76;106;76;76;76;76;98;75 15:57:43 INFO - PROCESS | 18944 | |4;audio-oscillator;83;82;122;82;81;83;101;82;82;111 15:57:43 INFO - PROCESS | 18944 | |5;imaging-gaussian-blur;117;117;116;117;117;116;117;117;117;117 15:57:43 INFO - PROCESS | 18944 | |6;imaging-darkroom;146;148;146;147;147;147;146;147;145;146 15:57:43 INFO - PROCESS | 18944 | |7;imaging-desaturate;94;94;95;96;95;95;95;96;95;96 15:57:43 INFO - PROCESS | 18944 | |8;json-parse-financial;51;50;50;50;50;51;51;51;49;53 15:57:43 INFO - PROCESS | 18944 | |9;json-stringify-tinderbox;59;60;60;60;61;60;59;59;59;60 15:57:43 INFO - PROCESS | 18944 | |10;stanford-crypto-aes;74;74;82;74;75;75;79;73;82;74 15:57:43 INFO - PROCESS | 18944 | |11;stanford-crypto-ccm;130;126;120;128;119;122;125;116;159;127 15:57:43 INFO - PROCESS | 18944 | |12;stanford-crypto-pbkdf2;153;158;154;154;154;150;159;152;155;152 15:57:43 INFO - PROCESS | 18944 | |13;stanford-crypto-sha256-iterative;63;63;62;63;63;61;62;61;61;65 15:57:43 INFO - PROCESS | 18944 | __end_tp_report 15:57:43 INFO - PROCESS | 18944 | __start_cc_report 15:57:43 INFO - PROCESS | 18944 | _x_x_mozilla_cycle_collect,1595 15:57:43 INFO - PROCESS | 18944 | __end_cc_report 15:57:43 INFO - PROCESS | 18944 | __startTimestamp1476917863344__endTimestamp 15:57:43 INFO - PROCESS | 18944 | 15:57:43 INFO - PROCESS | 18944 | ------- Summary: start ------- 15:57:43 INFO - PROCESS | 18944 | Number of tests: 14 15:57:43 INFO - PROCESS | 18944 | 15:57:43 INFO - PROCESS | 18944 | [#0] ai-astar Cycles:10 Average:111.30 Median:111.00 stddev:1.25 (1.1%) stddev-sans-first:1.01 15:57:43 INFO - PROCESS | 18944 | Values: 109.0 111.0 112.0 111.0 112.0 111.0 111.0 111.0 114.0 111.0 15:57:43 INFO - PROCESS | 18944 | 15:57:43 INFO - PROCESS | 18944 | [#1] audio-beat-detection Cycles:10 Average:128.90 Median:122.00 stddev:15.98 (13.1%) stddev-sans-first:15.38 15:57:43 INFO - PROCESS | 18944 | Values: 148.0 121.0 117.0 157.0 118.0 120.0 117.0 118.0 150.0 123.0 15:57:43 INFO - PROCESS | 18944 | 15:57:43 INFO - PROCESS | 18944 | [#2] audio-dft Cycles:10 Average:161.10 Median:161.00 stddev:0.99 (0.6%) stddev-sans-first:0.78 15:57:43 INFO - PROCESS | 18944 | Values: 163.0 162.0 161.0 162.0 160.0 161.0 160.0 160.0 161.0 161.0 15:57:43 INFO - PROCESS | 18944 | 15:57:43 INFO - PROCESS | 18944 | [#3] audio-fft Cycles:10 Average:81.00 Median:76.00 stddev:11.23 (14.8%) stddev-sans-first:11.70 15:57:43 INFO - PROCESS | 18944 | Values: 75.0 76.0 76.0 106.0 76.0 76.0 76.0 76.0 98.0 75.0 15:57:43 INFO - PROCESS | 18944 | 15:57:43 INFO - PROCESS | 18944 | [#4] audio-oscillator Cycles:10 Average:90.90 Median:82.00 stddev:14.96 (18.2%) stddev-sans-first:15.59 15:57:43 INFO - PROCESS | 18944 | Values: 83.0 82.0 122.0 82.0 81.0 83.0 101.0 82.0 82.0 111.0 15:57:43 INFO - PROCESS | 18944 | 15:57:43 INFO - PROCESS | 18944 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 15:57:43 INFO - PROCESS | 18944 | Values: 117.0 117.0 116.0 117.0 117.0 116.0 117.0 117.0 117.0 117.0 15:57:43 INFO - PROCESS | 18944 | 15:57:43 INFO - PROCESS | 18944 | [#6] imaging-darkroom Cycles:10 Average:146.50 Median:147.00 stddev:0.85 (0.6%) stddev-sans-first:0.88 15:57:43 INFO - PROCESS | 18944 | Values: 146.0 148.0 146.0 147.0 147.0 147.0 146.0 147.0 145.0 146.0 15:57:43 INFO - PROCESS | 18944 | 15:57:43 INFO - PROCESS | 18944 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.74 (0.8%) stddev-sans-first:0.67 15:57:43 INFO - PROCESS | 18944 | Values: 94.0 94.0 95.0 96.0 95.0 95.0 95.0 96.0 95.0 96.0 15:57:43 INFO - PROCESS | 18944 | 15:57:43 INFO - PROCESS | 18944 | [#8] json-parse-financial Cycles:10 Average:50.60 Median:51.00 stddev:1.07 (2.1%) stddev-sans-first:1.13 15:57:43 INFO - PROCESS | 18944 | Values: 51.0 50.0 50.0 50.0 50.0 51.0 51.0 51.0 49.0 53.0 15:57:43 INFO - PROCESS | 18944 | 15:57:43 INFO - PROCESS | 18944 | [#9] json-stringify-tinderbox Cycles:10 Average:59.70 Median:60.00 stddev:0.67 (1.1%) stddev-sans-first:0.67 15:57:43 INFO - PROCESS | 18944 | Values: 59.0 60.0 60.0 60.0 61.0 60.0 59.0 59.0 59.0 60.0 15:57:43 INFO - PROCESS | 18944 | 15:57:43 INFO - PROCESS | 18944 | [#10] stanford-crypto-aes Cycles:10 Average:76.20 Median:75.00 stddev:3.46 (4.6%) stddev-sans-first:3.57 15:57:43 INFO - PROCESS | 18944 | Values: 74.0 74.0 82.0 74.0 75.0 75.0 79.0 73.0 82.0 74.0 15:57:43 INFO - PROCESS | 18944 | 15:57:43 INFO - PROCESS | 18944 | [#11] stanford-crypto-ccm Cycles:10 Average:127.20 Median:126.50 stddev:12.01 (9.5%) stddev-sans-first:12.69 15:57:43 INFO - PROCESS | 18944 | Values: 130.0 126.0 120.0 128.0 119.0 122.0 125.0 116.0 159.0 127.0 15:57:43 INFO - PROCESS | 18944 | 15:57:43 INFO - PROCESS | 18944 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.10 Median:154.00 stddev:2.73 (1.8%) stddev-sans-first:2.86 15:57:43 INFO - PROCESS | 18944 | Values: 153.0 158.0 154.0 154.0 154.0 150.0 159.0 152.0 155.0 152.0 15:57:43 INFO - PROCESS | 18944 | 15:57:43 INFO - PROCESS | 18944 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.40 Median:63.00 stddev:1.26 (2.0%) stddev-sans-first:1.32 15:57:43 INFO - PROCESS | 18944 | Values: 63.0 63.0 62.0 63.0 63.0 61.0 62.0 61.0 61.0 65.0 15:57:43 INFO - PROCESS | 18944 | -------- Summary: end -------- 15:57:43 INFO - PROCESS | 18944 | 15:57:44 INFO - TEST-INFO | 18944: exit 0 15:57:44 INFO - TEST-OK | kraken | took 60007ms 15:57:44 INFO - SUITE-END | took 1174s 15:57:44 INFO - Completed test suite (00:19:34) 15:57:44 INFO - javascript benchmark 15:57:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3137.8117312961226, 3172.825494597797, 3176.364727054588, 3203.0390882735173, 3213.9035828925125, 5572.832815015546, 5644.604877658566, 5654.773809047643, 5656.0, 5669.376368599451, 5706.28671416073, 5754.395788442205, 5755.654660720349, 5760.279965004374, 5795.739191736371, 5161.690298582078, 5192.688438693672, 5212.635115541913, 5228.032813929435, 5260.710660913648, 5022.598192144638, 5263.3683957925105, 5280.762365693534, 5282.947170528303, 5313.1764576490705, 5022.4224214215465, 5138.306328645623, 5160.793568257265, 5166.509181627739, 5195.896082078356, 5178.0, 5207.505286997767, 5213.061648903177, 5286.418493965661, 5292.100342941709, 5139.997700448414, 5190.325257716473, 5256.710880901549, 5264.289320941701, 5288.048151332759, 5429.4299098594865, 5480.04099282624, 5554.944450555483, 5634.661920284796, 5678.517326027251, 5945.31628862682, 5982.790602328898, 5990.071538911476, 6032.638041717511, 6044.2746870375395, 6066.514678825683, 6090.0, 6090.604110732833, 6105.206323177958, 6110.450059494667, 5466.01611709892, 5521.282233309684, 5551.111822108443, 5603.719814009283, 5608.46719561641, 6177.320494745574, 6178.289496707846, 6192.0, 6198.907016394758, 6204.844878878064, 5947.434993675681, 5950.047992321294, 5963.612365196248, 5968.4926781224085, 6006.0, 5850.707464626752, 5860.09168578871, 5873.735681894325, 5918.319393269829, 6041.154238406627, 5951.732172052268, 5971.402859713994, 5978.0, 5986.760529578855, 5987.6108052976415, 5926.61477004002, 5934.821955341346, 5969.940300597025, 5989.2812862456785, 6011.158437818709, 4526.977365113219, 4576.244919588231, 4603.815847366068, 4611.815527378867, 4612.3542704021465, 4137.379393091061, 4149.875503734893, 4162.4796900387455, 4200.663946884242, 4211.115665710236, 5782.942170578241, 5788.652680839163, 5791.276090488689, 5794.768209271666, 5809.970950145223, 5933.436323549256, 5994.100884867305, 6152.107944348045, 6163.599366041193, 6168.0439531872635, 5682.715864206773, 5701.372848986608, 5713.05734553794, 5752.395998420189, 5794.739236734357, 5276.445973172838, 5305.761240744176, 5319.308489896289, 5319.9202011969855, 5325.0, 5965.940340596712, 6295.181626388632, 6340.714667839959, 6360.0777887206095, 6369.71336289868, 5811.041178205549, 6007.068904319837, 6077.058056001327, 6107.297660769068, 6164.167837341902, 6335.809925702236, 6340.270868850142, 6341.55609107358, 6354.52341074412, 6375.043743438521, 6023.186869772525, 6032.125341825498, 6038.818835434944, 6039.939600604113, 6046.7278972445465, 5810.128480727924, 5817.883642327045, 5949.0481522956115, 6005.279366476051, 6022.578419510767, 5930.11048342752, 5933.614315069679, 5937.584369094295, 5940.940590594038, 5948.881022379442, 5940.079287710412, 5942.019566771434, 5942.61373010753, 5948.672822994777, 5955.196048533393, 5557.527610153183, 5672.829815105553, 5898.4101589840675, 5924.348321684697, 5963.254593175853, 5977.671228082497, 6006.669633170218, 6015.639061656315, 6031.969840150771, 6057.848553786191, 5604.215409842707, 5819.0689489681445, 5864.618799778001, 5872.618279811799, 5877.265341832271], "value": 5580.705623026501, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [560.9570061695615, 573.6558065160903, 611.7797592866567, 634.8095571328603, 653.3597074866634, 46230.537694623046, 47111.52888471119, 47427.0, 50665.74667126669, 50820.49179508204, 48312.27531587029, 48803.51196488034, 48957.0, 48968.51031489684, 48987.0, 8717.346199035066, 8926.687565935194, 9405.999999999984, 9568.952155239214, 9570.952145239264, 11019.0, 11340.03609693177, 11342.71643208918, 11745.647630571097, 11774.352410617412, 11564.942175289112, 11644.534218631245, 11650.475728592233, 11652.766944661102, 11663.183577149603, 11385.203035787497, 11464.598739044177, 11467.827982580267, 11485.253458525169, 11490.0, 11202.215844890861, 11247.718807029807, 11251.54993800247, 11272.267302625303, 11283.605073822377, 45736.54263457356, 45923.0, 46073.30890036652, 46139.53860461386, 46164.53835461669, 3702.7593206441766, 3756.981215093934, 3793.563740169888, 3989.341758609855, 4092.877213683594, 34416.0, 34844.303113937836, 34912.0, 34960.82519587411, 35052.474212886824, 4638.721676699409, 4657.441107067174, 4702.482726900038, 4731.195696731564, 4781.569658730731, 393695.0, 394069.02965485275, 395164.0, 396406.0, 397464.0, 64481.0, 64778.352216478175, 65035.34964650293, 65046.34953650403, 65089.0, 40241.396379054335, 41448.58551414447, 41513.1697366051, 43926.78036609796, 67837.32162678409, 18743.156557954924, 18821.341253056078, 18829.058547072866, 18868.33960811365, 18878.905605471886, 13845.446182152802, 14152.787708184385, 14770.63073423173, 15023.474178403922, 15088.773668394982, 20266.392008239778, 21054.78945210559, 21166.259180928595, 21172.894135529223, 21245.0, 7684.3084122429245, 7700.691972321157, 7741.690332386642, 7824.93103586132, 7890.053193616688, 1013.4172850610928, 1058.523664351045, 1068.7007637861411, 1177.3053898200103, 1203.5366383942246, 34428.48357274643, 34698.653013470044, 35065.123371915906, 35362.39115790493, 35378.11554711153, 1171.4260012593768, 1183.0240051957169, 1201.5073819733868, 1235.4008305971597, 1237.0351126121639, 18236.817631823513, 18296.817031829778, 18370.540736481697, 18464.35374761877, 18633.440996770118, 582488.0, 584963.0, 586064.0, 588138.0, 588468.0, 63334.0, 64019.67990160113, 64026.35973640297, 64044.0, 64074.0, 1311.7966715159166, 1363.4409891944256, 1399.4472183487464, 1426.6504706346805, 1456.5775924981638, 31431.371372572878, 36198.8190059048, 36261.09347266339, 36403.63596364002, 36835.815820920725, 54989.0, 55480.0, 55569.444305556426, 55579.722101389234, 55666.72166639141, 406200.7519699297, 407152.0, 407410.0, 407481.0, 408278.0, 31113.0, 31676.841615791775, 32071.198220044687, 32161.839190803898, 33339.66660333463, 48339.75830120827, 52044.47955520548, 52107.73946130245, 52133.7393313031, 52230.21654675107, 43280.56719432891, 43923.0, 43964.560354396046, 44119.55880441283, 44979.77510112429, 44579.0, 45189.322160166965, 45951.3107303384, 46480.0, 46632.533674662816, 45169.32246016247, 46003.76998115122, 46036.53963460457, 46068.769656151504, 46325.07349853052, 36980.260394792494, 39908.80045599753, 40275.1944961105, 40392.0, 40719.59280407158, 29704.257393565334, 29867.850660746557, 30768.692313076583, 30865.69134308628, 30989.380212395175], "value": 22065.400382246014, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [391.2043719328283, 429.5742333564392, 432.4032834688131, 436.23440861288447, 446.3728461511577, 16846.15769211539, 19901.900490497545, 21515.246966356182, 21683.0, 21972.230971915986, 12973.286469244187, 16354.100524471149, 19138.94735789531, 19725.40823775289, 19783.406497805052, 5553.7778488860395, 5773.826785196451, 5782.508486778629, 5783.074708046714, 5787.971060144693, 5713.342965558952, 5899.88200235995, 6164.198654174951, 6187.381261873822, 6276.780312689057, 6046.12331211974, 6225.875482490347, 6245.531585131111, 6250.406211409909, 6254.593451425665, 6185.350538193492, 6224.470919971808, 6242.625442473443, 6250.6874656267455, 6252.5935814171935, 5939.4357536034, 6072.514198864124, 6089.751600921801, 6135.846603834895, 6173.166622505949, 13885.791713124312, 13997.0, 14160.858391416057, 14303.49937752184, 14311.998160128795, 2104.705341252226, 2427.368884090132, 2556.9042875101095, 2602.9978458293504, 3145.889893853704, 11077.778444431066, 11171.66485005451, 11342.659720208407, 11532.250403723816, 11737.413129343498, 1926.3161577639876, 2063.719064076579, 2120.1731324783345, 2144.871307721526, 2183.759786423492, 82197.0, 83211.0, 85065.57467212687, 86265.65917677445, 88173.55913220393, 10720.303180293256, 11854.407279635983, 11934.28394296345, 12347.567835125728, 12482.875171248354, 15504.379824807107, 16643.251053702614, 16724.498265051825, 16740.497785066225, 17356.47930562085, 8856.734297971072, 8982.0, 9002.684906028258, 9009.0, 9198.954005230065, 8559.315254779603, 8585.828283434297, 8617.741467755977, 8847.557622118868, 9075.636974521078, 5146.228065790161, 5234.7644356004075, 5246.7638956247065, 5271.578273738091, 5317.282166907496, 2183.344996501043, 2188.868667879932, 2193.561287742458, 2215.268961242786, 2234.664800279971, 945.4232917920064, 978.8580655804868, 981.1513041219293, 1039.4698703661188, 1085.2132204152085, 20885.79114208869, 20999.8950005249, 21022.78977210208, 21089.367318980454, 21392.1326372006, 1077.9514921828536, 1089.2810744908322, 1091.1380009792176, 1094.6497120921229, 1098.2312381332938, 11161.162912781574, 11675.75275925069, 11816.88183118175, 11846.170768046153, 11972.760544789055, 483534.0, 516856.0, 526320.0, 541519.0, 542773.0, 95880.52059739656, 97505.5124724386, 98381.0, 100238.0, 100520.0, 1033.0280073118845, 1068.2041878800344, 1108.667399780079, 1129.7401597632427, 1140.236041851961, 18328.358507452176, 18373.724394134366, 18638.906805466428, 18939.57072424798, 19072.713909291648, 44153.0, 44530.55469445264, 44764.32853507265, 45127.0, 45877.54122458733, 58936.70531647486, 59026.0, 60032.39967600268, 65576.0, 66475.0, 8033.6786528538205, 8171.264586187272, 8250.711225107332, 8482.109378515168, 9158.58786354629, 7819.960900195462, 11757.588484403004, 12319.644839067516, 12626.0, 13222.471101155847, 11525.020373268366, 11527.135464840003, 11554.826677599674, 11626.53493860236, 11727.179097463437, 11365.488553015299, 11535.5962541314, 11589.0, 11713.238639488405, 11998.520059197883, 11793.0, 11795.233309834834, 11891.821622675494, 12019.75960480803, 12295.139340246096, 7882.487638303492, 8088.393370497354, 8540.957295213484, 8691.869621955802, 8762.912370876209, 9144.177024067865, 11555.4800033997, 11825.231359961575, 11926.463309151282, 11936.0], "value": 9554.447639946256, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1409.7532931736944, 1526.2902750221144, 1559.4619856149627, 1705.1729910993174, 1853.694140466824, 8206.876896846545, 9061.411008284465, 9383.296252781034, 9399.95300023499, 9750.902490975088, 9493.335466517347, 9590.616375344978, 9594.616215351394, 9597.328187026893, 9604.711858644234, 2588.9093881714143, 2613.751693589106, 2618.9738102619017, 2619.2011436511857, 2619.554675705125, 2559.3217797283733, 2593.0276146445085, 2606.0, 2616.3851494898777, 2618.0705849423466, 2528.2415275417393, 2543.669322988009, 2567.114345550782, 2569.511792759379, 2572.6655534780452, 2498.612715029173, 2500.8999640014376, 2508.435601989556, 2533.0, 2542.1610868413377, 2500.4749002709455, 2520.218732193026, 2523.028633975914, 2567.2169988153605, 2575.1244576843774, 9303.0, 9303.395279306824, 9511.476868772215, 9513.429194248298, 9548.283878709055, 5484.61607687462, 5614.29821272341, 5633.380328163898, 5634.774609015634, 5637.689927054011, 3764.416515440111, 3806.505154329947, 3873.845046198149, 3873.845046198149, 3898.4737060497037, 1311.3050083455785, 1362.8773410393112, 1369.760291948905, 1407.8521755215656, 1412.894032947533, 13169.144005639602, 13211.207327560282, 13261.138026028277, 13294.534691285759, 13300.135491193041, 4922.8769280768265, 4991.201407774739, 5095.414027386824, 5120.436751957282, 5139.948600514022, 10626.628068017582, 10662.093722033715, 10667.14662826972, 10685.252032357661, 10689.30519516229, 5684.8010319638615, 5707.172459993278, 5713.085906254979, 5718.714064296769, 5730.0, 4499.122671079108, 4713.080949214938, 4883.706977581356, 5290.867728306823, 5370.087085195526, 4243.469566304212, 4244.405783190356, 4299.892502687458, 4337.7397356158735, 4338.6312163466455, 1305.5691621764795, 1319.3271431569776, 1328.6811165320446, 1330.6872884872048, 1339.6316013096282, 967.4727273635885, 984.7636567223958, 1013.898610138995, 1014.7361685961703, 1025.6153942271649, 5991.490723288569, 6154.3845615438995, 6175.567710260329, 6177.752889884444, 6460.418562329413, 495.87355224418263, 505.26736232463077, 509.23360342684026, 509.8368522072901, 528.9550388217044, 4130.876073717794, 4239.236937351307, 4351.369051487517, 4418.1163767246035, 4509.1658043262105, 11500.942495287638, 11734.767849375863, 11767.23512971654, 11823.408829558488, 11976.0, 11633.302001879914, 11690.47392867323, 11768.235064720766, 11876.524939002344, 11927.403629818475, 8778.341624378198, 8960.059193784558, 8969.910300896907, 8991.865122023175, 8992.8651070234, 6479.8056058318325, 6496.577722448026, 6576.736930522917, 6676.732930682719, 6756.155480564929, 10790.73023174427, 10904.56381744753, 10991.120710342993, 10991.890081099087, 11015.173861960546, 11026.779464410507, 11028.834567481656, 11110.944445277722, 11176.776464470502, 11222.382768947786, 8396.706115285935, 8453.873191902003, 8528.0, 8543.145685431407, 8607.569621518775, 8996.775080623036, 9052.637894484147, 9063.41087829289, 9073.45559266446, 9087.863682044643, 7972.760817175494, 8167.224113709188, 8235.094139644754, 8263.421560490708, 8521.659133634585, 7960.0, 8005.879911801444, 8095.5142691438705, 8166.673333066611, 8238.629261683358, 7369.263073692802, 7458.254174582498, 7529.774106776806, 7595.734149304969, 7677.001989741298, 5112.565431938327, 5192.273081768631, 5470.288862447856, 5567.109262517978, 5640.858978525405, 5067.771950262173, 5171.534561889298, 5298.28473156119, 5308.681479111266, 5331.920021199608], "value": 4908.064010754204, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [346.791924845093, 355.8772223582863, 359.7391890879113, 370.18928546483215, 370.98701545445914, 5561.110222364423, 5606.887862242757, 5612.69130197839, 5615.157726341045, 5631.521320687746, 5259.184826351912, 5292.412276317099, 5380.21986811912, 5440.646357986738, 5575.665460072399, 4347.652187824982, 4349.108432771274, 4349.259966221276, 4363.694541382097, 4368.366586844905, 4376.781160941956, 4378.584034516716, 4385.188740083079, 4385.956140438602, 4425.557444255581, 4156.5635608261, 4313.590208930146, 4337.0, 4362.236608593499, 4389.780510974439, 4260.829566817323, 4281.529031806499, 4326.264535029052, 4368.584984426474, 4370.978145109286, 3816.912180028706, 3827.7320587558884, 3851.768893866349, 3855.730098893079, 3876.9224615507533, 5179.8446046618665, 5275.551578115865, 5431.266778984826, 5461.5357694596005, 5479.808206712746, 4967.7019378837385, 4972.0, 4973.0, 4973.055119527278, 4978.6763860348965, 5725.0, 5735.139729040636, 5738.4835364816945, 5766.7981620643495, 5777.248957635523, 4179.310413781753, 4198.895027624303, 4236.88155922039, 4265.573442655741, 4294.527601963781, 5949.583529153005, 5958.463738263577, 5963.850903727442, 5978.83412734517, 5991.951408503494, 4338.414314067623, 4348.565143485689, 4383.473983122046, 4406.449193850769, 4408.0, 4297.495876443282, 4347.847825326098, 4368.628666563378, 4386.451693538308, 4452.933206001912, 4885.534339698076, 4916.06594746997, 4929.704217746947, 4937.284093806378, 4938.913439043405, 5787.524181333733, 5803.245578074823, 5834.329052159056, 5845.181674565564, 5853.0, 1398.4406237504877, 1436.561848636155, 1447.2980604406855, 1459.408939379553, 1471.8160229971254, 1015.0712098429854, 1031.5461197073264, 1046.6493724602344, 1047.643801107627, 1050.9369437833755, 5551.583631227674, 5555.555555555546, 5577.498025177672, 5589.441055894378, 5618.297712785902, 5692.459216374438, 5909.202257695242, 5991.880162396815, 5996.910046349309, 6013.729382177812, 5507.421720719267, 5527.170924361298, 5528.55771538276, 5551.306086739158, 5560.527355174855, 3090.8145511269395, 3101.410731960965, 3114.9532757008665, 3131.7964332318784, 3144.7421031587546, 5893.233879595711, 5985.683149707057, 6166.383361663888, 6190.535709821781, 6238.781642642486, 6104.840080384713, 6112.2970858351855, 6122.816315510453, 6125.724342404603, 6139.785107521305, 5872.647641141545, 6162.672157035151, 6180.134781130645, 6238.220222472191, 6256.874862502725, 5976.701164941823, 5990.0, 5990.191324171181, 6013.338532761481, 6015.608985415934, 5953.970230148821, 5977.163197152489, 5981.850453738692, 5982.88034239304, 6001.549883758648, 5887.0580707086665, 6121.877562448815, 6134.631922084511, 6191.9473689472, 6197.442230199304, 4301.956980430281, 4344.652427805831, 4356.5405589127995, 4375.890602734957, 4394.978025109854, 5764.0, 5978.432048955342, 6029.75880964774, 6041.728122234422, 6047.516198704006, 5840.678762668094, 5845.1232315153065, 5851.824445266649, 5862.560307977004, 5863.091220860773, 5873.73568189424, 5879.588428810113, 5885.234919460443, 5907.350191478848, 5938.287405511366, 5610.28886189716, 5808.970955145197, 5811.4479124483105, 5815.738291776965, 5837.190470954018, 5840.123981402823, 5858.9121163181735, 5870.530357571299, 5870.882582348244, 5879.853003674943, 5452.26394436746, 5662.8301150965535, 5772.451617096369, 5772.797952071657, 5777.24895763548], "value": 4459.030487955923, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [805.6777289084365, 809.1786836361091, 857.6655104509239, 861.487414988082, 927.0636656976454, 16772.748408773863, 18199.36302229419, 18301.542461438454, 18306.633867322646, 18317.542061448454, 16654.750178747297, 16882.577935551588, 16891.915540422313, 17950.0, 17956.102194890267, 685.9074025006622, 707.6214225389417, 870.7083127152412, 874.9343799215053, 893.0355216366308, 883.3992884838308, 883.9292856571491, 885.0972008551274, 885.4023534114447, 886.5124181700071, 779.123486078162, 938.8967213606497, 939.5865819039601, 939.8402271613842, 941.256407438123, 788.5820515126964, 908.4912449028532, 935.4621092871558, 941.0495399646406, 944.4050248343568, 680.0275605883585, 681.2778454837887, 681.9829504262384, 682.3756263019332, 682.743971010871, 16163.353465861352, 16223.594410139842, 16264.43074492399, 16292.674146517003, 16346.34614615426, 15753.291101900442, 15898.60253493672, 16088.75866861998, 16404.67190656192, 16644.916775416168, 13197.736045279136, 13271.402786874614, 13469.461221551126, 13699.931500342434, 13994.230317332542, 4211.684123690705, 4228.281192197333, 4303.526612072701, 4319.481662200525, 4324.481062272547, 91048.0, 91335.54332228296, 91445.0, 93441.06558934391, 94553.0, 13138.4087716053, 13178.275194864184, 13376.732465350638, 13514.0, 13606.455741770218, 25633.48733025329, 25875.223743287355, 26244.212673619822, 26298.6055209172, 26325.0, 9514.666986655433, 9534.0, 9642.987486313976, 9785.07041831025, 10022.749431264278, 5209.322788037607, 5412.377576578665, 5417.160340147283, 5439.5376393006245, 5589.469000444951, 3977.2642061218767, 4106.507219133723, 4109.054917368962, 4112.115895082572, 4113.382992551141, 712.4620911212002, 737.0271241960668, 738.6011553761016, 739.6930273936364, 748.636911098117, 678.0067201549754, 704.8872180451103, 715.8675645005691, 782.6634547144782, 795.129333379948, 10521.84217236742, 10881.673549793519, 11152.10783137347, 11259.605913793142, 11342.773144537063, 660.3066779881106, 684.226823689228, 696.7839969609336, 727.7380143148463, 738.3650060947581, 5238.73806309683, 5311.867203319871, 5325.3609566851455, 5358.571314294847, 5421.376541697677, 133069.0, 133722.33138834243, 133923.3303833494, 134593.53125031103, 138823.0, 113958.43020784843, 115805.8419415812, 116529.41735291269, 116937.4153129229, 117136.0, 94663.0, 95974.52012740032, 96918.0, 98359.50820245995, 98617.50691246497, 10262.281640285108, 10320.750600043544, 10389.324693894872, 10490.475476226311, 10518.368897866152, 42041.0, 49303.50696492989, 52892.0, 56215.15677264763, 57962.0, 51102.48897510977, 77890.0, 78774.0, 80614.19385806067, 83535.0, 14371.568852934428, 14648.633784155056, 14706.264686765406, 14894.743149317137, 14967.0, 15844.287007084478, 15882.60293492672, 16102.838971610134, 16263.593410164367, 16992.150392480573, 14677.853221468076, 14891.925540372229, 14949.925250373679, 15092.094474331576, 15257.237138142826, 13688.315584220949, 14154.221517816208, 14277.0, 14637.853621464075, 15416.306266218271, 14311.212883291519, 14439.566812995627, 14680.41278348854, 14701.0, 14997.100173989596, 11974.521019159136, 12297.508099675913, 12307.323097229739, 12349.382530873241, 12657.87342126567, 13179.67050823737, 13209.207447553177, 13236.933815330862, 13388.799168012682, 13422.530211442552], "value": 8398.289817374376, "unit": "score"}], "name": "dromaeo_css", "value": 7747.567573379175, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 111.0, 112.0, 111.0, 112.0, 111.0, 111.0, 111.0, 114.0, 111.0], "value": 111.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 121.0, 117.0, 157.0, 118.0, 120.0, 117.0, 118.0, 150.0, 123.0], "value": 128.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 161.0, 162.0, 160.0, 161.0, 160.0, 160.0, 161.0, 161.0], "value": 161.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 106.0, 76.0, 76.0, 76.0, 76.0, 98.0, 75.0], "value": 81.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 122.0, 82.0, 81.0, 83.0, 101.0, 82.0, 82.0, 111.0], "value": 90.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 148.0, 146.0, 147.0, 147.0, 147.0, 146.0, 147.0, 145.0, 146.0], "value": 146.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 96.0, 95.0, 95.0, 95.0, 96.0, 95.0, 96.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 50.0, 50.0, 50.0, 51.0, 51.0, 51.0, 49.0, 53.0], "value": 50.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 60.0, 60.0, 61.0, 60.0, 59.0, 59.0, 59.0, 60.0], "value": 59.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 82.0, 74.0, 75.0, 75.0, 79.0, 73.0, 82.0, 74.0], "value": 76.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 126.0, 120.0, 128.0, 119.0, 122.0, 125.0, 116.0, 159.0, 127.0], "value": 127.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 158.0, 154.0, 154.0, 154.0, 150.0, 159.0, 152.0, 155.0, 152.0], "value": 154.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 62.0, 63.0, 63.0, 61.0, 62.0, 61.0, 61.0, 65.0], "value": 62.4, "unit": "score"}], "name": "kraken", "value": 1461.8, "alertThreshold": 2.0}]} 15:57:44 INFO - Return code: 0 15:57:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:57:44 INFO - # TBPL SUCCESS # 15:57:44 INFO - Running post-action listener: _resource_record_post_action 15:57:44 INFO - [mozharness: 2016-10-19 22:57:44.664609Z] Finished run-tests step (success) 15:57:44 INFO - Running post-run listener: _resource_record_post_run 15:57:44 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 266240; Write bytes: 361963520; Read time: 28; Write time: 412088 15:57:44 INFO - TinderboxPrint: CPU usage
12.9% 15:57:44 INFO - TinderboxPrint: I/O read bytes / time
266,240 / 28 15:57:44 INFO - TinderboxPrint: I/O write bytes / time
361,963,520 / 412,088 15:57:44 INFO - TinderboxPrint: CPU idle
8,276.0 (87.1%) 15:57:44 INFO - TinderboxPrint: CPU user
1,183.2 (12.4%) 15:57:44 INFO - TinderboxPrint: Swap in / out
0 / 0 15:57:44 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 115908608; Read time: 0; Write time: 139752 15:57:44 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 262144; Write bytes: 245219328; Read time: 16; Write time: 272280 15:57:45 INFO - Running post-run listener: _upload_blobber_files 15:57:45 INFO - Blob upload gear active. 15:57:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:57:45 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:57:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:57:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:57:45 INFO - (blobuploader) - INFO - Open directory for files ... 15:57:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:57:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:57:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:57:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:57:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:57:46 INFO - (blobuploader) - INFO - Done attempting. 15:57:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:57:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:57:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:57:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:57:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:57:47 INFO - (blobuploader) - INFO - Done attempting. 15:57:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:57:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:57:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:57:50 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:57:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:57:50 INFO - (blobuploader) - INFO - Done attempting. 15:57:50 INFO - (blobuploader) - INFO - Iteration through files over. 15:57:50 INFO - Return code: 0 15:57:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:57:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:57:50 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b31e021d223eb7e2eec651dfdd2ed8824642bbfd1ec4b78ef7a3dd27352bfa6a4fc7f0f5374d57b3e38876250a21d07432d7d54c7dd1369657e54b1370c84349", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/677b74a5a6fcd034c88c3412775f5d55c73e5d94fb8c9158e05a7a020c76d7489a604d1570f39c68ce27278d3196a3b1f1352c34bf5cd4ebf88fabfcdd039914", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fb37920dc5459066857585bcf7e1249d8831efb15e6c0a0a3fc99f8042e771f55c9b443bba16a2d96909c8e74609611c2e14eb257646db40014e565859df7e8d"} 15:57:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:57:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:57:50 INFO - Contents: 15:57:50 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b31e021d223eb7e2eec651dfdd2ed8824642bbfd1ec4b78ef7a3dd27352bfa6a4fc7f0f5374d57b3e38876250a21d07432d7d54c7dd1369657e54b1370c84349", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/677b74a5a6fcd034c88c3412775f5d55c73e5d94fb8c9158e05a7a020c76d7489a604d1570f39c68ce27278d3196a3b1f1352c34bf5cd4ebf88fabfcdd039914", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fb37920dc5459066857585bcf7e1249d8831efb15e6c0a0a3fc99f8042e771f55c9b443bba16a2d96909c8e74609611c2e14eb257646db40014e565859df7e8d"} 15:57:50 INFO - Running post-run listener: copy_logs_to_upload_dir 15:57:50 INFO - Copying logs to upload dir... 15:57:50 INFO - mkdir: /builds/slave/test/build/upload/logs 15:57:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1234.177575 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2016-10-19 15:57:50.165274) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-19 15:57:50.168729) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=0e2a256762fed810e2f0f810000001b3-1476916634.109370-2000386204 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b31e021d223eb7e2eec651dfdd2ed8824642bbfd1ec4b78ef7a3dd27352bfa6a4fc7f0f5374d57b3e38876250a21d07432d7d54c7dd1369657e54b1370c84349", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/677b74a5a6fcd034c88c3412775f5d55c73e5d94fb8c9158e05a7a020c76d7489a604d1570f39c68ce27278d3196a3b1f1352c34bf5cd4ebf88fabfcdd039914", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fb37920dc5459066857585bcf7e1249d8831efb15e6c0a0a3fc99f8042e771f55c9b443bba16a2d96909c8e74609611c2e14eb257646db40014e565859df7e8d"} build_url:https://queue.taskcluster.net/v1/task/W4NhmvbwRimCsx9P9pmVHg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011573 build_url: 'https://queue.taskcluster.net/v1/task/W4NhmvbwRimCsx9P9pmVHg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b31e021d223eb7e2eec651dfdd2ed8824642bbfd1ec4b78ef7a3dd27352bfa6a4fc7f0f5374d57b3e38876250a21d07432d7d54c7dd1369657e54b1370c84349", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/677b74a5a6fcd034c88c3412775f5d55c73e5d94fb8c9158e05a7a020c76d7489a604d1570f39c68ce27278d3196a3b1f1352c34bf5cd4ebf88fabfcdd039914", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fb37920dc5459066857585bcf7e1249d8831efb15e6c0a0a3fc99f8042e771f55c9b443bba16a2d96909c8e74609611c2e14eb257646db40014e565859df7e8d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-19 15:57:50.195898) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 15:57:50.196307) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=0e2a256762fed810e2f0f810000001b3-1476916634.109370-2000386204 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004565 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 15:57:50.278772) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-19 15:57:50.279154) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-19 15:57:50.279521) ========= ========= Total master_lag: 0.17 =========