builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-001 starttime: 1474597989.67 results: success (0) buildid: 20160922165659 builduid: 8490fcee4df24f71a33684dbcf3162e5 revision: bc17447fefc995c71a6a97a5a5a0f19783df5ad1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-22 19:33:09.668253) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-22 19:33:09.668793) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-22 19:33:09.669245) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-22 19:33:09.726451) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-22 19:33:09.727085) ========= 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=8887e36bb8e381bfd44124ac000001a8-1474595299.956446-1244825490 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-22 19:33:09-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 73.9K=0.2s 2016-09-22 19:33:10 (73.9 KB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.291101 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-22 19:33:10.043782) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-22 19:33:10.044116) ========= 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=8887e36bb8e381bfd44124ac000001a8-1474595299.956446-1244825490 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016881 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-22 19:33:10.094045) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-22 19:33:10.094463) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev bc17447fefc995c71a6a97a5a5a0f19783df5ad1 --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 bc17447fefc995c71a6a97a5a5a0f19783df5ad1 --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=8887e36bb8e381bfd44124ac000001a8-1474595299.956446-1244825490 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-22 19:33:10,132 truncating revision to first 12 chars 2016-09-22 19:33:10,132 Setting DEBUG logging. 2016-09-22 19:33:10,132 attempt 1/10 2016-09-22 19:33:10,132 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/bc17447fefc9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-22 19:33:10,438 unpacking tar archive at: mozilla-aurora-bc17447fefc9/testing/mozharness/ program finished with exit code 0 elapsedTime=0.640257 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-22 19:33:10.746596) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-22 19:33:10.746935) ========= script_repo_revision: bc17447fefc995c71a6a97a5a5a0f19783df5ad1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-22 19:33:10.747415) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-22 19:33:10.747750) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-22 19:33:10.756859) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-09-22 19:33:10.757295) ========= /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=8887e36bb8e381bfd44124ac000001a8-1474595299.956446-1244825490 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:33:10 INFO - MultiFileLogger online at 20160922 19:33:10 in /builds/slave/test 19:33:10 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 19:33:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:33:10 INFO - {'append_to_log': False, 19:33:10 INFO - 'base_work_dir': '/builds/slave/test', 19:33:10 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 19:33:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:33:10 INFO - 'branch': 'Mozilla-Aurora', 19:33:10 INFO - 'buildbot_json_path': 'buildprops.json', 19:33:10 INFO - 'config_files': ('talos/linux_config.py',), 19:33:10 INFO - 'default_actions': ('clobber', 19:33:10 INFO - 'read-buildbot-config', 19:33:10 INFO - 'download-and-extract', 19:33:10 INFO - 'populate-webroot', 19:33:10 INFO - 'create-virtualenv', 19:33:10 INFO - 'install', 19:33:10 INFO - 'run-tests'), 19:33:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:33:10 INFO - 'download_minidump_stackwalk': True, 19:33:10 INFO - 'download_symbols': 'ondemand', 19:33:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:33:10 INFO - 'tooltool.py': '/tools/tooltool.py', 19:33:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:33:10 INFO - '/tools/misc-python/virtualenv.py')}, 19:33:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:33:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:33:10 INFO - 'installer_path': 'installer.exe', 19:33:10 INFO - 'log_level': 'info', 19:33:10 INFO - 'log_name': 'talos', 19:33:10 INFO - 'log_to_console': True, 19:33:10 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:33:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:33:10 INFO - 'opt_config_files': (), 19:33:10 INFO - 'pip_index': False, 19:33:10 INFO - 'sps_profile': False, 19:33:10 INFO - 'sps_profile_interval': 0, 19:33:10 INFO - 'suite': 'dromaeojs', 19:33:10 INFO - 'system_bits': '32', 19:33:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:33:10 INFO - 'title': 'talos-linux64-ix-001', 19:33:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:33:10 INFO - 'use_talos_json': True, 19:33:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:33:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:33:10 INFO - 'work_dir': 'build'} 19:33:10 INFO - [mozharness: 2016-09-23 02:33:10.980064Z] Running clobber step. 19:33:10 INFO - Running pre-action listener: _resource_record_pre_action 19:33:10 INFO - Running main action method: clobber 19:33:10 INFO - rmtree: /builds/slave/test/build 19:33:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:33:11 INFO - Running post-action listener: _resource_record_post_action 19:33:11 INFO - [mozharness: 2016-09-23 02:33:11.409013Z] Finished clobber step (success) 19:33:11 INFO - [mozharness: 2016-09-23 02:33:11.409114Z] Running read-buildbot-config step. 19:33:11 INFO - Running pre-action listener: _resource_record_pre_action 19:33:11 INFO - Running main action method: read_buildbot_config 19:33:11 INFO - Using buildbot properties: 19:33:11 INFO - { 19:33:11 INFO - "project": "", 19:33:11 INFO - "product": "firefox", 19:33:11 INFO - "script_repo_revision": "production", 19:33:11 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 19:33:11 INFO - "repository": "", 19:33:11 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 19:33:11 INFO - "buildid": "20160922165659", 19:33:11 INFO - "pgo_build": "True", 19:33:11 INFO - "basedir": "/builds/slave/test", 19:33:11 INFO - "buildnumber": 783, 19:33:11 INFO - "slavename": "talos-linux64-ix-001", 19:33:11 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 19:33:11 INFO - "platform": "ubuntu64_hw", 19:33:11 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 19:33:11 INFO - "branch": "mozilla-aurora", 19:33:11 INFO - "repo_path": "releases/mozilla-aurora", 19:33:11 INFO - "revision": "bc17447fefc995c71a6a97a5a5a0f19783df5ad1", 19:33:11 INFO - "stage_platform": "linux64-pgo", 19:33:11 INFO - "builduid": "8490fcee4df24f71a33684dbcf3162e5", 19:33:11 INFO - "slavebuilddir": "test" 19:33:11 INFO - } 19:33:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/AqTHr-gGTA2MCgBce9s_sw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2. 19:33:11 INFO - Running post-action listener: _resource_record_post_action 19:33:11 INFO - [mozharness: 2016-09-23 02:33:11.411585Z] Finished read-buildbot-config step (success) 19:33:11 INFO - [mozharness: 2016-09-23 02:33:11.411674Z] Running download-and-extract step. 19:33:11 INFO - Running pre-action listener: _resource_record_pre_action 19:33:11 INFO - Running main action method: download_and_extract 19:33:11 INFO - mkdir: /builds/slave/test/build/tests 19:33:11 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')]} 19:33:11 INFO - trying https://queue.taskcluster.net/v1/task/AqTHr-gGTA2MCgBce9s_sw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 19:33:11 INFO - Downloading https://queue.taskcluster.net/v1/task/AqTHr-gGTA2MCgBce9s_sw/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 19:33:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AqTHr-gGTA2MCgBce9s_sw/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 19:33:11 INFO - Downloaded 1361 bytes. 19:33:11 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 19:33:11 INFO - Using the following test package requirements: 19:33:11 INFO - {u'common': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 19:33:11 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 19:33:11 INFO - u'firefox-51.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 19:33:11 INFO - u'gtest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 19:33:11 INFO - u'firefox-51.0a2.en-US.linux-x86_64.gtest.tests.zip'], 19:33:11 INFO - u'jittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 19:33:11 INFO - u'jsshell-linux-x86_64.zip'], 19:33:11 INFO - u'mochitest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 19:33:11 INFO - u'firefox-51.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 19:33:11 INFO - u'mozbase': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 19:33:11 INFO - u'reftest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 19:33:11 INFO - u'firefox-51.0a2.en-US.linux-x86_64.reftest.tests.zip'], 19:33:11 INFO - u'talos': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 19:33:11 INFO - u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'], 19:33:11 INFO - u'web-platform': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 19:33:11 INFO - u'firefox-51.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 19:33:11 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 19:33:11 INFO - u'firefox-51.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 19:33:11 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 19:33:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AqTHr-gGTA2MCgBce9s_sw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 19:33:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AqTHr-gGTA2MCgBce9s_sw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 19:33:11 INFO - Fetch https://queue.taskcluster.net/v1/task/AqTHr-gGTA2MCgBce9s_sw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 19:33:12 INFO - Expected file size: 21894570 19:33:17 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 19:33:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AqTHr-gGTA2MCgBce9s_sw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 19:33:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AqTHr-gGTA2MCgBce9s_sw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 19:33:17 INFO - Fetch https://queue.taskcluster.net/v1/task/AqTHr-gGTA2MCgBce9s_sw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 19:33:17 INFO - Expected file size: 21894570 19:33:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AqTHr-gGTA2MCgBce9s_sw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip 19:33:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AqTHr-gGTA2MCgBce9s_sw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 19:33:22 INFO - Fetch https://queue.taskcluster.net/v1/task/AqTHr-gGTA2MCgBce9s_sw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip into memory 19:33:23 INFO - Expected file size: 14414059 19:33:24 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')]} 19:33:24 INFO - trying https://queue.taskcluster.net/v1/task/AqTHr-gGTA2MCgBce9s_sw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 19:33:24 INFO - Downloading https://queue.taskcluster.net/v1/task/AqTHr-gGTA2MCgBce9s_sw/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 19:33:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AqTHr-gGTA2MCgBce9s_sw/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 19:33:26 INFO - Downloaded 63965688 bytes. 19:33:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AqTHr-gGTA2MCgBce9s_sw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 19:33:26 INFO - mkdir: /builds/slave/test/properties 19:33:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:33:26 INFO - Writing to file /builds/slave/test/properties/build_url 19:33:26 INFO - Contents: 19:33:26 INFO - build_url:https://queue.taskcluster.net/v1/task/AqTHr-gGTA2MCgBce9s_sw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 19:33:27 INFO - Running post-action listener: _resource_record_post_action 19:33:27 INFO - Running post-action listener: set_extra_try_arguments 19:33:27 INFO - [mozharness: 2016-09-23 02:33:27.599483Z] Finished download-and-extract step (success) 19:33:27 INFO - [mozharness: 2016-09-23 02:33:27.599713Z] Running populate-webroot step. 19:33:27 INFO - Running pre-action listener: _resource_record_pre_action 19:33:27 INFO - Running main action method: populate_webroot 19:33:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:33:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:33:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:33:27 INFO - '--tppagecycles', 19:33:27 INFO - '7'], 19:33:27 INFO - 'tests': ['tsvgm']}}, 19:33:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:33:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:33:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:33:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:33:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:33:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:33:27 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:33:27 INFO - 'talos_options': ['--disable-e10s'], 19:33:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:33:27 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:33:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:33:27 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:33:27 INFO - 'talos_options': ['--disable-e10s'], 19:33:27 INFO - 'tests': ['damp', 'tps']}, 19:33:27 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:33:27 INFO - 'tests': ['damp', 'tps']}, 19:33:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:33:27 INFO - 'tests': ['dromaeo_dom']}, 19:33:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:33:27 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:33:27 INFO - 'tests': ['basic_compositor_video']}, 19:33:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:33:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:33:27 INFO - 'tests': ['a11yr', 19:33:27 INFO - 'ts_paint', 19:33:27 INFO - 'tpaint', 19:33:27 INFO - 'sessionrestore', 19:33:27 INFO - 'sessionrestore_no_auto_restore', 19:33:27 INFO - 'tabpaint']}, 19:33:27 INFO - 'other-e10s': {'tests': ['a11yr', 19:33:27 INFO - 'ts_paint', 19:33:27 INFO - 'tpaint', 19:33:27 INFO - 'sessionrestore', 19:33:27 INFO - 'sessionrestore_no_auto_restore', 19:33:27 INFO - 'tabpaint']}, 19:33:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:33:27 INFO - 'ts_paint', 19:33:27 INFO - 'tpaint', 19:33:27 INFO - 'sessionrestore', 19:33:27 INFO - 'sessionrestore_no_auto_restore', 19:33:27 INFO - 'tabpaint']}, 19:33:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:33:27 INFO - 'ts_paint', 19:33:27 INFO - 'tpaint', 19:33:27 INFO - 'sessionrestore', 19:33:27 INFO - 'sessionrestore_no_auto_restore', 19:33:27 INFO - 'tabpaint']}, 19:33:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:33:27 INFO - 'tests': ['a11yr', 19:33:27 INFO - 'ts_paint', 19:33:27 INFO - 'tpaint', 19:33:27 INFO - 'sessionrestore', 19:33:27 INFO - 'sessionrestore_no_auto_restore', 19:33:27 INFO - 'tabpaint']}, 19:33:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:33:27 INFO - 'tests': ['a11yr', 19:33:27 INFO - 'ts_paint', 19:33:27 INFO - 'tpaint', 19:33:27 INFO - 'sessionrestore', 19:33:27 INFO - 'sessionrestore_no_auto_restore', 19:33:27 INFO - 'tabpaint']}, 19:33:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:33:27 INFO - 'tests': ['tsvgx', 19:33:27 INFO - 'tsvgr_opacity', 19:33:27 INFO - 'tart', 19:33:27 INFO - 'tscrollx', 19:33:27 INFO - 'cart']}, 19:33:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:33:27 INFO - 'tsvgr_opacity', 19:33:27 INFO - 'tart', 19:33:27 INFO - 'tscrollx', 19:33:27 INFO - 'cart']}, 19:33:27 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:33:27 INFO - 'talos_options': ['--disable-e10s'], 19:33:27 INFO - 'tests': ['tp5o']}, 19:33:27 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:33:27 INFO - 'tests': ['tp5o']}, 19:33:27 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:33:27 INFO - 'talos_options': ['--disable-e10s', 19:33:27 INFO - '--xperf_path', 19:33:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:33:27 INFO - 'tests': ['tp5n']}, 19:33:27 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:33:27 INFO - 'talos_options': ['--xperf_path', 19:33:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:33:27 INFO - 'tests': ['tp5n']}}, 19:33:27 INFO - 'talos.zip': {'path': '', 19:33:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:33:27 INFO - Running post-action listener: _resource_record_post_action 19:33:27 INFO - [mozharness: 2016-09-23 02:33:27.619276Z] Finished populate-webroot step (success) 19:33:27 INFO - [mozharness: 2016-09-23 02:33:27.619364Z] Running create-virtualenv step. 19:33:27 INFO - Running pre-action listener: _resource_record_pre_action 19:33:27 INFO - Running main action method: create_virtualenv 19:33:27 INFO - Creating virtualenv /builds/slave/test/build/venv 19:33:27 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 19:33:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:33:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:33:27 INFO - Using real prefix '/usr' 19:33:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:33:28 INFO - Installing distribute.............................................................................................................................................................................................done. 19:33:31 INFO - Installing pip.................done. 19:33:31 INFO - Return code: 0 19:33:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:33:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:33:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:33:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:33:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:33:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:33:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:33:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2965a2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dbe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e8bca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e88f60>, '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 0x1e89d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e8a1d0>, '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': '8887e36bb8e381bfd44124ac000001a8-1474595299.956446-1244825490', '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 19:33:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 19:33:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 19:33:31 INFO - Using env: {'DISPLAY': ':0', 19:33:31 INFO - 'HOME': '/home/cltbld', 19:33:31 INFO - 'LANG': 'en_US.UTF-8', 19:33:31 INFO - 'LANGUAGE': 'en_US:en', 19:33:31 INFO - 'LOGNAME': 'cltbld', 19:33:31 INFO - 'MAIL': '/var/mail/cltbld', 19:33:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:33:31 INFO - 'MOZ_NO_REMOTE': '1', 19:33:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:33:31 INFO - 'NO_EM_RESTART': '1', 19:33:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:33:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:33:31 INFO - 'PWD': '/builds/slave/test', 19:33:31 INFO - 'SHELL': '/bin/bash', 19:33:31 INFO - 'SHLVL': '1', 19:33:31 INFO - 'TERM': 'linux', 19:33:31 INFO - 'TMOUT': '86400', 19:33:31 INFO - 'USER': 'cltbld', 19:33:31 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1474595299.956446-1244825490', 19:33:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:33:31 INFO - '_': '/tools/buildbot/bin/python'} 19:33:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:33:31 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 19:33:31 INFO - Cleaning up... 19:33:31 INFO - Return code: 0 19:33:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:33:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:33:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:33:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:33:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:33:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:33:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:33:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fa2965a2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dbe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e8bca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e88f60>, '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 0x1e89d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e8a1d0>, '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': '8887e36bb8e381bfd44124ac000001a8-1474595299.956446-1244825490', '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 19:33:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 19:33:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 19:33:31 INFO - Using env: {'DISPLAY': ':0', 19:33:31 INFO - 'HOME': '/home/cltbld', 19:33:31 INFO - 'LANG': 'en_US.UTF-8', 19:33:31 INFO - 'LANGUAGE': 'en_US:en', 19:33:31 INFO - 'LOGNAME': 'cltbld', 19:33:31 INFO - 'MAIL': '/var/mail/cltbld', 19:33:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:33:31 INFO - 'MOZ_NO_REMOTE': '1', 19:33:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:33:31 INFO - 'NO_EM_RESTART': '1', 19:33:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:33:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:33:31 INFO - 'PWD': '/builds/slave/test', 19:33:31 INFO - 'SHELL': '/bin/bash', 19:33:31 INFO - 'SHLVL': '1', 19:33:31 INFO - 'TERM': 'linux', 19:33:31 INFO - 'TMOUT': '86400', 19:33:31 INFO - 'USER': 'cltbld', 19:33:31 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1474595299.956446-1244825490', 19:33:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:33:31 INFO - '_': '/tools/buildbot/bin/python'} 19:33:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:33:31 INFO - Downloading/unpacking psutil>=3.1.1 19:33:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:33:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:33:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:33:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:33:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:33:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:33:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:33:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:33:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:33:33 INFO - Installing collected packages: psutil 19:33:33 INFO - Running setup.py install for psutil 19:33:33 INFO - building 'psutil._psutil_linux' extension 19:33:33 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 19:33:34 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 19:33:34 INFO - building 'psutil._psutil_posix' extension 19:33:34 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 19:33:34 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 19:33:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:33:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:33:35 INFO - Successfully installed psutil 19:33:35 INFO - Cleaning up... 19:33:35 INFO - Return code: 0 19:33:35 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:33:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:33:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:33:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:33:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:33:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:33:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:33:35 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 0x7fa2965a2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dbe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e8bca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e88f60>, '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 0x1e89d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e8a1d0>, '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': '8887e36bb8e381bfd44124ac000001a8-1474595299.956446-1244825490', '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 19:33:35 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 19:33:35 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 19:33:35 INFO - Using env: {'DISPLAY': ':0', 19:33:35 INFO - 'HOME': '/home/cltbld', 19:33:35 INFO - 'LANG': 'en_US.UTF-8', 19:33:35 INFO - 'LANGUAGE': 'en_US:en', 19:33:35 INFO - 'LOGNAME': 'cltbld', 19:33:35 INFO - 'MAIL': '/var/mail/cltbld', 19:33:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:33:35 INFO - 'MOZ_NO_REMOTE': '1', 19:33:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:33:35 INFO - 'NO_EM_RESTART': '1', 19:33:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:33:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:33:35 INFO - 'PWD': '/builds/slave/test', 19:33:35 INFO - 'SHELL': '/bin/bash', 19:33:35 INFO - 'SHLVL': '1', 19:33:35 INFO - 'TERM': 'linux', 19:33:35 INFO - 'TMOUT': '86400', 19:33:35 INFO - 'USER': 'cltbld', 19:33:35 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1474595299.956446-1244825490', 19:33:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:33:35 INFO - '_': '/tools/buildbot/bin/python'} 19:33:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:33:35 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:33:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:33:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:33:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:33:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:33:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:33:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:33:37 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:33:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:33:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:33:37 INFO - Installing collected packages: mozsystemmonitor 19:33:37 INFO - Running setup.py install for mozsystemmonitor 19:33:37 INFO - Successfully installed mozsystemmonitor 19:33:37 INFO - Cleaning up... 19:33:37 INFO - Return code: 0 19:33:37 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:33:37 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')]} 19:33:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:33:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:33:37 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')]} 19:33:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:33:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:33:37 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 0x7fa2965a2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dbe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e8bca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e88f60>, '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 0x1e89d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e8a1d0>, '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': '8887e36bb8e381bfd44124ac000001a8-1474595299.956446-1244825490', '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 19:33:37 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 19:33:37 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 19:33:37 INFO - Using env: {'DISPLAY': ':0', 19:33:37 INFO - 'HOME': '/home/cltbld', 19:33:37 INFO - 'LANG': 'en_US.UTF-8', 19:33:37 INFO - 'LANGUAGE': 'en_US:en', 19:33:37 INFO - 'LOGNAME': 'cltbld', 19:33:37 INFO - 'MAIL': '/var/mail/cltbld', 19:33:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:33:37 INFO - 'MOZ_NO_REMOTE': '1', 19:33:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:33:37 INFO - 'NO_EM_RESTART': '1', 19:33:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:33:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:33:37 INFO - 'PWD': '/builds/slave/test', 19:33:37 INFO - 'SHELL': '/bin/bash', 19:33:37 INFO - 'SHLVL': '1', 19:33:37 INFO - 'TERM': 'linux', 19:33:37 INFO - 'TMOUT': '86400', 19:33:37 INFO - 'USER': 'cltbld', 19:33:37 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1474595299.956446-1244825490', 19:33:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:33:37 INFO - '_': '/tools/buildbot/bin/python'} 19:33:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:33:37 INFO - Downloading/unpacking jsonschema==2.5.1 19:33:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:33:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:33:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:33:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:33:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:33:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:33:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:33:39 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:33: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 19:33: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 19:33: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 19:33: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 19:33: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 19:33: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 19:33:39 INFO - Downloading functools32-3.2.3-2.tar.gz 19:33:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:33:40 INFO - warning: no files found matching '*.txt' 19:33:40 INFO - no previously-included directories found matching 'build' 19:33:40 INFO - no previously-included directories found matching 'dist' 19:33:40 INFO - no previously-included directories found matching '.git*' 19:33:40 INFO - Installing collected packages: jsonschema, functools32 19:33:40 INFO - Running setup.py install for functools32 19:33:40 INFO - warning: no files found matching '*.txt' 19:33:40 INFO - no previously-included directories found matching 'build' 19:33:40 INFO - no previously-included directories found matching 'dist' 19:33:40 INFO - no previously-included directories found matching '.git*' 19:33:40 INFO - Successfully installed jsonschema functools32 19:33:40 INFO - Cleaning up... 19:33:40 INFO - Return code: 0 19:33:40 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:33:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:33:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:33:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:33:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:33:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:33:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:33:40 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 0x7fa2965a2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dbe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e8bca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e88f60>, '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 0x1e89d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e8a1d0>, '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': '8887e36bb8e381bfd44124ac000001a8-1474595299.956446-1244825490', '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 19:33:40 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 19:33:40 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 19:33:40 INFO - Using env: {'DISPLAY': ':0', 19:33:40 INFO - 'HOME': '/home/cltbld', 19:33:40 INFO - 'LANG': 'en_US.UTF-8', 19:33:40 INFO - 'LANGUAGE': 'en_US:en', 19:33:40 INFO - 'LOGNAME': 'cltbld', 19:33:40 INFO - 'MAIL': '/var/mail/cltbld', 19:33:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:33:40 INFO - 'MOZ_NO_REMOTE': '1', 19:33:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:33:40 INFO - 'NO_EM_RESTART': '1', 19:33:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:33:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:33:40 INFO - 'PWD': '/builds/slave/test', 19:33:40 INFO - 'SHELL': '/bin/bash', 19:33:40 INFO - 'SHLVL': '1', 19:33:40 INFO - 'TERM': 'linux', 19:33:40 INFO - 'TMOUT': '86400', 19:33:40 INFO - 'USER': 'cltbld', 19:33:40 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1474595299.956446-1244825490', 19:33:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:33:40 INFO - '_': '/tools/buildbot/bin/python'} 19:33:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:33:40 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:33:40 INFO - Cleaning up... 19:33:40 INFO - Return code: 0 19:33:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:33:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:33:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:33:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:33:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:33:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:33:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:33:40 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 0x7fa2965a2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dbe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e8bca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e88f60>, '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 0x1e89d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e8a1d0>, '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': '8887e36bb8e381bfd44124ac000001a8-1474595299.956446-1244825490', '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 19:33:40 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 19:33:40 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 19:33:40 INFO - Using env: {'DISPLAY': ':0', 19:33:40 INFO - 'HOME': '/home/cltbld', 19:33:40 INFO - 'LANG': 'en_US.UTF-8', 19:33:40 INFO - 'LANGUAGE': 'en_US:en', 19:33:40 INFO - 'LOGNAME': 'cltbld', 19:33:40 INFO - 'MAIL': '/var/mail/cltbld', 19:33:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:33:40 INFO - 'MOZ_NO_REMOTE': '1', 19:33:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:33:40 INFO - 'NO_EM_RESTART': '1', 19:33:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:33:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:33:40 INFO - 'PWD': '/builds/slave/test', 19:33:40 INFO - 'SHELL': '/bin/bash', 19:33:40 INFO - 'SHLVL': '1', 19:33:40 INFO - 'TERM': 'linux', 19:33:40 INFO - 'TMOUT': '86400', 19:33:40 INFO - 'USER': 'cltbld', 19:33:40 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1474595299.956446-1244825490', 19:33:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:33:40 INFO - '_': '/tools/buildbot/bin/python'} 19:33:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:33:40 INFO - Downloading/unpacking blobuploader==1.2.4 19:33:40 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 19:33:40 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 19:33:40 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 19:33:40 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 19:33:40 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 19:33:40 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 19:33:42 INFO - Downloading blobuploader-1.2.4.tar.gz 19:33:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:33:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:33: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 19:33: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 19:33: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 19:33: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 19:33: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 19:33: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 19:33:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:33:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:33:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:33:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:33:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:33:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:33:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:33:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:33:43 INFO - Downloading docopt-0.6.1.tar.gz 19:33:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:33:43 INFO - Installing collected packages: blobuploader, requests, docopt 19:33:43 INFO - Running setup.py install for blobuploader 19:33:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:33:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:33:43 INFO - Running setup.py install for requests 19:33:43 INFO - Running setup.py install for docopt 19:33:43 INFO - Successfully installed blobuploader requests docopt 19:33:43 INFO - Cleaning up... 19:33:43 INFO - Return code: 0 19:33:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:33:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:33:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:33:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:33:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:33:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:33:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:33:43 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 0x7fa2965a2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dbe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e8bca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e88f60>, '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 0x1e89d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e8a1d0>, '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': '8887e36bb8e381bfd44124ac000001a8-1474595299.956446-1244825490', '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 19:33:43 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 19:33:43 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 19:33:43 INFO - Using env: {'DISPLAY': ':0', 19:33:43 INFO - 'HOME': '/home/cltbld', 19:33:43 INFO - 'LANG': 'en_US.UTF-8', 19:33:43 INFO - 'LANGUAGE': 'en_US:en', 19:33:43 INFO - 'LOGNAME': 'cltbld', 19:33:43 INFO - 'MAIL': '/var/mail/cltbld', 19:33:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:33:43 INFO - 'MOZ_NO_REMOTE': '1', 19:33:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:33:43 INFO - 'NO_EM_RESTART': '1', 19:33:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:33:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:33:43 INFO - 'PWD': '/builds/slave/test', 19:33:43 INFO - 'SHELL': '/bin/bash', 19:33:43 INFO - 'SHLVL': '1', 19:33:43 INFO - 'TERM': 'linux', 19:33:43 INFO - 'TMOUT': '86400', 19:33:43 INFO - 'USER': 'cltbld', 19:33:43 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1474595299.956446-1244825490', 19:33:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:33:43 INFO - '_': '/tools/buildbot/bin/python'} 19:33:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:33:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:33:44 INFO - Running setup.py (path:/tmp/pip-oiowVl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:33:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:33:44 INFO - Running setup.py (path:/tmp/pip-MXf3EQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:33:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:33:44 INFO - Running setup.py (path:/tmp/pip-I9tWn6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:33:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:33:44 INFO - Running setup.py (path:/tmp/pip-wcfBdG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:33:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:33:44 INFO - Running setup.py (path:/tmp/pip-m1tCyM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:33:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:33:44 INFO - Running setup.py (path:/tmp/pip-6RuR4i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:33:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:33:44 INFO - Running setup.py (path:/tmp/pip-GeT5JG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:33:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:33:44 INFO - Running setup.py (path:/tmp/pip-UtXdhO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:33:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:33:44 INFO - Running setup.py (path:/tmp/pip-Vq7IBL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:33:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:33:44 INFO - Running setup.py (path:/tmp/pip-yKxftn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:33:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:33:44 INFO - Running setup.py (path:/tmp/pip-MfiuIq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:33:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:33:44 INFO - Running setup.py (path:/tmp/pip-lVYQfj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:33:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:33:45 INFO - Running setup.py (path:/tmp/pip-1NTbdR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:33:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:33:45 INFO - Running setup.py (path:/tmp/pip-PMqyf2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:33:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:33:45 INFO - Running setup.py (path:/tmp/pip-EcvlOO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:33:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:33:45 INFO - Running setup.py (path:/tmp/pip-WkBhzB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:33:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:33:45 INFO - Running setup.py (path:/tmp/pip-gyv8tr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:33:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:33:45 INFO - Running setup.py install for manifestparser 19:33:45 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:33:45 INFO - Running setup.py install for mozcrash 19:33:45 INFO - Running setup.py install for mozdebug 19:33:45 INFO - Running setup.py install for mozdevice 19:33:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:33:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:33:45 INFO - Running setup.py install for mozfile 19:33:45 INFO - Running setup.py install for mozhttpd 19:33:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:33:45 INFO - Running setup.py install for mozinfo 19:33:46 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:33:46 INFO - Running setup.py install for mozInstall 19:33:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:33:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:33:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:33:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:33:46 INFO - Running setup.py install for mozleak 19:33:46 INFO - Running setup.py install for mozlog 19:33:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:33:46 INFO - Running setup.py install for moznetwork 19:33:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:33:46 INFO - Running setup.py install for mozprocess 19:33:46 INFO - Running setup.py install for mozprofile 19:33:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:33:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:33:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:33:46 INFO - Running setup.py install for mozrunner 19:33:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:33:46 INFO - Running setup.py install for mozscreenshot 19:33:46 INFO - Running setup.py install for moztest 19:33:47 INFO - Running setup.py install for mozversion 19:33:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:33:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:33:47 INFO - Cleaning up... 19:33:47 INFO - Return code: 0 19:33:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:33:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:33:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:33:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:33:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:33:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:33:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:33:47 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 0x7fa2965a2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dbe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e8bca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e88f60>, '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 0x1e89d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e8a1d0>, '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': '8887e36bb8e381bfd44124ac000001a8-1474595299.956446-1244825490', '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 19:33:47 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 19:33:47 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 19:33:47 INFO - Using env: {'DISPLAY': ':0', 19:33:47 INFO - 'HOME': '/home/cltbld', 19:33:47 INFO - 'LANG': 'en_US.UTF-8', 19:33:47 INFO - 'LANGUAGE': 'en_US:en', 19:33:47 INFO - 'LOGNAME': 'cltbld', 19:33:47 INFO - 'MAIL': '/var/mail/cltbld', 19:33:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:33:47 INFO - 'MOZ_NO_REMOTE': '1', 19:33:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:33:47 INFO - 'NO_EM_RESTART': '1', 19:33:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:33:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:33:47 INFO - 'PWD': '/builds/slave/test', 19:33:47 INFO - 'SHELL': '/bin/bash', 19:33:47 INFO - 'SHLVL': '1', 19:33:47 INFO - 'TERM': 'linux', 19:33:47 INFO - 'TMOUT': '86400', 19:33:47 INFO - 'USER': 'cltbld', 19:33:47 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1474595299.956446-1244825490', 19:33:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:33:47 INFO - '_': '/tools/buildbot/bin/python'} 19:33:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:33:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:33:47 INFO - Running setup.py (path:/tmp/pip-gMPRep-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:33:47 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)) 19:33:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:33:47 INFO - Running setup.py (path:/tmp/pip-OLWGjP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:33:47 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)) 19:33:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:33:47 INFO - Running setup.py (path:/tmp/pip-3ePY6s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:33:47 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)) 19:33:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:33:47 INFO - Running setup.py (path:/tmp/pip-ehrCNF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:33:47 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)) 19:33:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:33:47 INFO - Running setup.py (path:/tmp/pip-rlWj4t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:33:47 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)) 19:33:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:33:47 INFO - Running setup.py (path:/tmp/pip-oHvaqm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:33:47 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)) 19:33:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:33:47 INFO - Running setup.py (path:/tmp/pip-QL8xOG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:33:47 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)) 19:33:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:33:47 INFO - Running setup.py (path:/tmp/pip-M5Z6pQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:33:47 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)) 19:33:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:33:47 INFO - Running setup.py (path:/tmp/pip-vPeSlC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:33:48 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)) 19:33:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:33:48 INFO - Running setup.py (path:/tmp/pip-P1j0Vf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:33:48 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)) 19:33:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:33:48 INFO - Running setup.py (path:/tmp/pip-8u1AD5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:33:48 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)) 19:33:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:33:48 INFO - Running setup.py (path:/tmp/pip-Cm06yb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:33:48 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)) 19:33:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:33:48 INFO - Running setup.py (path:/tmp/pip-gIyxPf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:33:48 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)) 19:33:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:33:48 INFO - Running setup.py (path:/tmp/pip-8qJXaY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:33:48 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)) 19:33:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:33:48 INFO - Running setup.py (path:/tmp/pip-aFJHef-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:33:48 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)) 19:33:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:33:48 INFO - Running setup.py (path:/tmp/pip-cPUhM9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:33:48 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)) 19:33:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:33:48 INFO - Running setup.py (path:/tmp/pip-95i9p3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:33:48 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)) 19:33:48 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)) 19:33:48 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)) 19:33:48 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)) 19:33:48 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)) 19:33:48 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)) 19:33:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:33:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:33:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:33:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:33:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:33:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:33:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:33:50 INFO - Downloading blessings-1.6.tar.gz 19:33:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:33:50 INFO - Installing collected packages: blessings 19:33:50 INFO - Running setup.py install for blessings 19:33:50 INFO - Successfully installed blessings 19:33:50 INFO - Cleaning up... 19:33:50 INFO - Return code: 0 19:33:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:33:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:33:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:33:51 INFO - Reading from file tmpfile_stdout 19:33:51 INFO - Current package versions: 19:33:51 INFO - argparse == 1.2.1 19:33:51 INFO - blessings == 1.6 19:33:51 INFO - blobuploader == 1.2.4 19:33:51 INFO - docopt == 0.6.1 19:33:51 INFO - functools32 == 3.2.3-2 19:33:51 INFO - jsonschema == 2.5.1 19:33:51 INFO - manifestparser == 1.1 19:33:51 INFO - mozInstall == 1.12 19:33:51 INFO - mozcrash == 0.17 19:33:51 INFO - mozdebug == 0.1 19:33:51 INFO - mozdevice == 0.48 19:33:51 INFO - mozfile == 1.2 19:33:51 INFO - mozhttpd == 0.7 19:33:51 INFO - mozinfo == 0.9 19:33:51 INFO - mozleak == 0.1 19:33:51 INFO - mozlog == 3.4 19:33:51 INFO - moznetwork == 0.27 19:33:51 INFO - mozprocess == 0.23 19:33:51 INFO - mozprofile == 0.28 19:33:51 INFO - mozrunner == 6.12 19:33:51 INFO - mozscreenshot == 0.1 19:33:51 INFO - mozsystemmonitor == 0.3 19:33:51 INFO - moztest == 0.7 19:33:51 INFO - mozversion == 1.4 19:33:51 INFO - psutil == 3.1.1 19:33:51 INFO - requests == 1.2.3 19:33:51 INFO - wsgiref == 0.1.2 19:33:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:33:51 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')]} 19:33:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:33:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:33:51 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')]} 19:33:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:33:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:33:51 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 0x7fa2965a2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dbe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e8bca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e88f60>, '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 0x1e89d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e8a1d0>, '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': '8887e36bb8e381bfd44124ac000001a8-1474595299.956446-1244825490', '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 19:33:51 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 19:33:51 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 19:33:51 INFO - Using env: {'DISPLAY': ':0', 19:33:51 INFO - 'HOME': '/home/cltbld', 19:33:51 INFO - 'LANG': 'en_US.UTF-8', 19:33:51 INFO - 'LANGUAGE': 'en_US:en', 19:33:51 INFO - 'LOGNAME': 'cltbld', 19:33:51 INFO - 'MAIL': '/var/mail/cltbld', 19:33:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:33:51 INFO - 'MOZ_NO_REMOTE': '1', 19:33:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:33:51 INFO - 'NO_EM_RESTART': '1', 19:33:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:33:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:33:51 INFO - 'PWD': '/builds/slave/test', 19:33:51 INFO - 'SHELL': '/bin/bash', 19:33:51 INFO - 'SHLVL': '1', 19:33:51 INFO - 'TERM': 'linux', 19:33:51 INFO - 'TMOUT': '86400', 19:33:51 INFO - 'USER': 'cltbld', 19:33:51 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1474595299.956446-1244825490', 19:33:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:33:51 INFO - '_': '/tools/buildbot/bin/python'} 19:33:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:33:51 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)) 19:33:51 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)) 19:33:51 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)) 19:33:51 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)) 19:33:51 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)) 19:33:51 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)) 19:33:51 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)) 19:33:51 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)) 19:33:51 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)) 19:33:51 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)) 19:33:51 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)) 19:33:51 INFO - Cleaning up... 19:33:51 INFO - Return code: 0 19:33:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:33:51 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')]} 19:33:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:33:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:33:51 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')]} 19:33:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:33:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:33:51 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 0x7fa2965a2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dbe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e8bca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e88f60>, '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 0x1e89d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e8a1d0>, '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': '8887e36bb8e381bfd44124ac000001a8-1474595299.956446-1244825490', '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 19:33:51 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 19:33:51 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 19:33:51 INFO - Using env: {'DISPLAY': ':0', 19:33:51 INFO - 'HOME': '/home/cltbld', 19:33:51 INFO - 'LANG': 'en_US.UTF-8', 19:33:51 INFO - 'LANGUAGE': 'en_US:en', 19:33:51 INFO - 'LOGNAME': 'cltbld', 19:33:51 INFO - 'MAIL': '/var/mail/cltbld', 19:33:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:33:51 INFO - 'MOZ_NO_REMOTE': '1', 19:33:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:33:51 INFO - 'NO_EM_RESTART': '1', 19:33:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:33:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:33:51 INFO - 'PWD': '/builds/slave/test', 19:33:51 INFO - 'SHELL': '/bin/bash', 19:33:51 INFO - 'SHLVL': '1', 19:33:51 INFO - 'TERM': 'linux', 19:33:51 INFO - 'TMOUT': '86400', 19:33:51 INFO - 'USER': 'cltbld', 19:33:51 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1474595299.956446-1244825490', 19:33:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:33:51 INFO - '_': '/tools/buildbot/bin/python'} 19:33:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:33:51 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:33:51 INFO - Cleaning up... 19:33:51 INFO - Return code: 0 19:33:51 INFO - Running post-action listener: _resource_record_post_action 19:33:51 INFO - Running post-action listener: _start_resource_monitoring 19:33:51 INFO - Starting resource monitoring. 19:33:51 INFO - [mozharness: 2016-09-23 02:33:51.508797Z] Finished create-virtualenv step (success) 19:33:51 INFO - [mozharness: 2016-09-23 02:33:51.509293Z] Running install step. 19:33:51 INFO - Running pre-action listener: _resource_record_pre_action 19:33:51 INFO - Running main action method: install 19:33:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:33:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:33:51 INFO - Reading from file tmpfile_stdout 19:33:51 INFO - Detecting whether we're running mozinstall >=1.0... 19:33:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:33:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:33:51 INFO - Reading from file tmpfile_stdout 19:33:51 INFO - Output received: 19:33:51 INFO - Usage: mozinstall [options] installer 19:33:51 INFO - Options: 19:33:51 INFO - -h, --help show this help message and exit 19:33:51 INFO - -d DEST, --destination=DEST 19:33:51 INFO - Directory to install application into. [default: 19:33:51 INFO - "/builds/slave/test"] 19:33:51 INFO - --app=APP Application being installed. [default: firefox] 19:33:51 INFO - mkdir: /builds/slave/test/build/application 19:33:51 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'] 19:33:51 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 19:34:07 INFO - Reading from file tmpfile_stdout 19:34:07 INFO - Output received: 19:34:07 INFO - /builds/slave/test/build/application/firefox/firefox 19:34:07 INFO - Running post-action listener: _resource_record_post_action 19:34:07 INFO - [mozharness: 2016-09-23 02:34:07.740896Z] Finished install step (success) 19:34:07 INFO - [mozharness: 2016-09-23 02:34:07.741144Z] Running run-tests step. 19:34:07 INFO - Running pre-action listener: _resource_record_pre_action 19:34:07 INFO - Running main action method: run_tests 19:34:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:34:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:34:07 INFO - Python 2.7.3 19:34:07 INFO - Return code: 0 19:34:07 INFO - grabbing minidump binary from tooltool 19:34:07 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')]} 19:34:07 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 0x1e88f60>, '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 0x1e89d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e8a1d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:34:07 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 19:34:07 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 19:34:07 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 19:34:07 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:34:07 INFO - Return code: 0 19:34:07 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:34:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:34:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:34:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:34:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:34:07 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/AqTHr-gGTA2MCgBce9s_sw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-001', '--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 19:34:07 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/AqTHr-gGTA2MCgBce9s_sw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-001 --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 19:34:07 INFO - Using env: {'DISPLAY': ':0', 19:34:07 INFO - 'HOME': '/home/cltbld', 19:34:07 INFO - 'LANG': 'en_US.UTF-8', 19:34:07 INFO - 'LANGUAGE': 'en_US:en', 19:34:07 INFO - 'LOGNAME': 'cltbld', 19:34:07 INFO - 'MAIL': '/var/mail/cltbld', 19:34:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:34:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:34:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:34:07 INFO - 'MOZ_NO_REMOTE': '1', 19:34:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:34:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:34:07 INFO - 'NO_EM_RESTART': '1', 19:34:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:34:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:34:07 INFO - 'PWD': '/builds/slave/test', 19:34:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:34:07 INFO - 'SHELL': '/bin/bash', 19:34:07 INFO - 'SHLVL': '1', 19:34:07 INFO - 'TERM': 'linux', 19:34:07 INFO - 'TMOUT': '86400', 19:34:07 INFO - 'USER': 'cltbld', 19:34:07 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1474595299.956446-1244825490', 19:34:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:34:07 INFO - '_': '/tools/buildbot/bin/python'} 19:34:07 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/AqTHr-gGTA2MCgBce9s_sw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-001', '--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 19:34:08 INFO - mozversion application_buildid: 20160922165659 19:34:08 INFO - mozversion application_changeset: bc17447fefc995c71a6a97a5a5a0f19783df5ad1 19:34:08 INFO - mozversion application_display_name: FirefoxDeveloperEdition 19:34:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:34:08 INFO - mozversion application_name: Firefox 19:34:08 INFO - mozversion application_remotingname: firefox-dev 19:34:08 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 19:34:08 INFO - mozversion application_vendor: Mozilla 19:34:08 INFO - mozversion application_version: 51.0a2 19:34:08 INFO - mozversion platform_buildid: 20160922165659 19:34:08 INFO - mozversion platform_changeset: bc17447fefc995c71a6a97a5a5a0f19783df5ad1 19:34:08 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 19:34:08 INFO - mozversion platform_version: 51.0a2 19:34:08 INFO - using testdate: 1474598048 19:34:08 INFO - actual date: 1474598048 19:34:08 INFO - starting webserver on 'localhost:48291' 19:34:08 INFO - SUITE-START | Running 2 tests 19:34:08 INFO - TEST-START | dromaeo_css 19:34:08 INFO - operating with platform_type : linux_ 19:34:08 INFO - Initialising browser for dromaeo_css test... 19:34:08 INFO - TEST-INFO | started process 3343 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyNHvkj/profile http://localhost:48291/getInfo.html) 19:34:13 INFO - PROCESS | 3343 | __metrics Screen width/height:1600/1200 19:34:13 INFO - PROCESS | 3343 | colorDepth:24 19:34:13 INFO - PROCESS | 3343 | Browser inner width/height: 1024/697 19:34:13 INFO - PROCESS | 3343 | __metrics 19:34:13 INFO - PROCESS | 3343 | 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" 19:34:13 INFO - PROCESS | 3343 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:34:14 INFO - PROCESS | 3343 | console.error: PushService: 19:34:14 INFO - PROCESS | 3343 | stateChangeProcessEnqueue: Error transitioning state 19:34:14 INFO - PROCESS | 3343 | UnknownError 19:34:14 INFO - TEST-INFO | 3343: exit 0 19:34:14 INFO - Browser initialized. 19:34:14 INFO - Running cycle 1/1 for dromaeo_css test... 19:34:14 INFO - TEST-INFO | started process 3435 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyNHvkj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:34:26 INFO - PROCESS | 3435 | RSS: Main: 144433152 19:34:26 INFO - PROCESS | 3435 | 19:35:17 INFO - PROCESS | 3435 | 1474598117681 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] 19:37:26 INFO - PROCESS | 3435 | Cycle 1(1): loaded http://localhost:48291/tests/dromaeo/cssquery-dojo.html (next: http://localhost:48291/tests/dromaeo/cssquery-ext.html) 19:37:28 INFO - PROCESS | 3435 | RSS: Main: 289804288 19:37:28 INFO - PROCESS | 3435 | 19:40:30 INFO - PROCESS | 3435 | Cycle 1(1): loaded http://localhost:48291/tests/dromaeo/cssquery-ext.html (next: http://localhost:48291/tests/dromaeo/cssquery-jquery.html) 19:40:31 INFO - PROCESS | 3435 | RSS: Main: 234004480 19:40:31 INFO - PROCESS | 3435 | 19:43:32 INFO - PROCESS | 3435 | Cycle 1(1): loaded http://localhost:48291/tests/dromaeo/cssquery-jquery.html (next: http://localhost:48291/tests/dromaeo/cssquery-mootools.html) 19:43:33 INFO - PROCESS | 3435 | RSS: Main: 176082944 19:43:33 INFO - PROCESS | 3435 | 19:46:35 INFO - PROCESS | 3435 | Cycle 1(1): loaded http://localhost:48291/tests/dromaeo/cssquery-mootools.html (next: http://localhost:48291/tests/dromaeo/cssquery-prototype.html) 19:46:36 INFO - PROCESS | 3435 | RSS: Main: 202604544 19:46:36 INFO - PROCESS | 3435 | 19:49:38 INFO - PROCESS | 3435 | Cycle 1(1): loaded http://localhost:48291/tests/dromaeo/cssquery-prototype.html (next: http://localhost:48291/tests/dromaeo/cssquery-yui.html) 19:49:39 INFO - PROCESS | 3435 | RSS: Main: 177795072 19:49:39 INFO - PROCESS | 3435 | 19:52:41 INFO - PROCESS | 3435 | Cycle 1(1): loaded http://localhost:48291/tests/dromaeo/cssquery-yui.html (next: http://localhost:48291/tests/dromaeo/cssquery-dojo.html) 19:52:42 INFO - PROCESS | 3435 | RSS: Main: 265687040 19:52:42 INFO - PROCESS | 3435 | 19:52:42 INFO - PROCESS | 3435 | __start_tp_report 19:52:42 INFO - PROCESS | 3435 | _x_x_mozilla_page_load 19:52:42 INFO - PROCESS | 3435 | _x_x_mozilla_page_load_details 19:52:42 INFO - PROCESS | 3435 | |i|pagename|runs| 19:52:42 INFO - PROCESS | 3435 | |0;dojo.html;3105.2702614885507;3137.3254750228703;3145.496720524715;3166.8416579171035;3183.952240716388;5559.110542313226;5650.463205995429;5665.660060396379;5678.119891416826;5716.685582292972;5704;5734.79928202513;5747.885042299152;5763.510101641355;5767.048437007888;5179.741012949356;5204.479552044784;5207.192885102815;5237.266782650432;5273.604479664022;5012.924806127911;5040.798368065273;5277.8416647500635;5297.205419187134;5329.467053294659;5122.129238029544;5132.204508301219;5165.974170129144;5171.534561889429;5214.400343960437;5220.660657057279;5229.843104706846;5299.337582802111;5301.893962120775;5320.680759154463;4939.678920870132;5122.743862806845;5205.791768329263;5252.658577192508;5265.4997775211295;5484.314460692413;5569.777208911679;5583.916241256385;5611.607187496877;5683.033884239648;5972.761089556412;6014.3985601439945;6110.08348747687;6111.786087486961;6116.663583502906;5921.378255283176;6139.079138129272;6147.969260153671;6153.1693221415435;6154.26148862135;5557.972210138923;5611.438856114397;5624.91562626561;5650.632708873951;5687.9431205687415;6245.500359971283;6251.12484252205;6254.499640028786;6256.655883926428;6260.906086408708;5822.388649191858;5999.100134979788;6004.39956004396;6004.909926351108;6012.488938440194;5940.6435613863305;5954.791582294599;6011.699415029231;6011.909821352683;6021.217241758631;5975.970120149372;6007.459328660441;6017.939820601738;6022.608530445507;6101.816229757492;6116.728650446344;6122.816315510542;6123.923976812287;6125.327021303102;6142.324344322121;4653.441587009513;4655.138799322136;4662.114198302312;4671.346011558385;4714.669973101919;4022.939655905164;4033;4088.6320231178743;4093.6725061995567;4140.979295103565;5855.677937713382;5855.795047173328;5859.2090067841145;5874.618149820248;5888.852778680482;6109.389061093855;6159.784407545714;6168.074788781719;6173.290071641729;6174.753009879644;5501.477359650827;5548.9722551386985;5754.913676294859;5810.622309549865;5843.853903652443;5351.036813373552;5355.598330125178;5357.223202635596;5371.677699338053;5372.113601255827;6402.743890244431;6407.295197528269;6407.423331900151;6407.807765767035;6417.74329026843;5821.534277257724;5851.034579294539;6188.81433556994;6197.721102550487;6203.47270481996;6185.84535386619;6238.15784869055;6247.843803904939;6343.746250149942;6352.237731472068;5996.580239383201;6002.769432266287;6010.849728756816;6013.30846952606;6017.2779266488305;5951.553633477593;6062.848428789139;6100.54245931548;6111.633302001894;6128.63228206309;5854;5911.7930872420975;5918.3489816121055;5958.433948774859;6047.244094488189;6004.909926351021;6006.279246490449;6009.399060093956;6016.488598469179;6021.397860213943;5960.225170727951;5963.463288304073;5967.552433567586;5970.820875373745;5970.940290597038;5765.4522820332;5863.94136058651;5876.6180198286975;5947.197128387612;5982.88034239304;5875.441833025856;5882.617629854046;5898.498627616701;5904.557158213237;5907.202527658883 19:52:42 INFO - PROCESS | 3435 | |1;ext.html;580.1848402993795;623.9469645080168;642.7975187815839;657.1457105762507;671.2414971082676;33771.15572110698;34457.82771086144;36707;49292.75353623236;51213;48962;49400.25899611509;49430;49479.25781113286;49531;9188.954055229731;9334.766630834249;9341.205997490222;9345.345825792198;9348.626054957793;8529.488230706178;8705.303575713928;9077.954610226972;10794.298370605924;11159.609413670523;11243.83134252987;11294.09647228224;11310.660680179608;11310.886891131107;11314.660560183207;11165.274257173298;11221.102311815035;11232.831507527393;11242.213045086888;11254.831177532344;8445.239928406412;8657.091005444401;10650;10968.396738179397;10989.61536346224;44834.32748508775;45190.32214516752;45218.54781452209;45222.77388613036;45233.321500177524;3883.436901649245;3883.6310550497656;3903.4730311408166;3991.960080399217;4010.2380547695843;35364.646353536395;35510;35566;35754;35775.82112089449;4512.255477846152;4526.502084770673;4594.150082234763;4599.241125214336;4694.29585562165;376060;388338;397188;399171;548312.999999996;66780.66609667017;67248.66375668188;67253.3274667247;67296.66351668308;67311.3268867305;71000.64499677572;71038;71303;71423.28576714166;71631.28368716246;19333.419997400102;19630.116644751022;19666.70499942502;19669.803301967084;19923.302684405975;12272.693182670326;15368.923155384375;15460.768088478682;15495.845041549666;15503.83721220913;18061.09694515269;19638.11628476722;20487.4878128048;20551.177952881717;21170.682439763415;7326.849454734515;7460.29127232912;7597.962010189914;7598.278163574451;7739.148693643695;967.3760424526141;1058.7194393485731;1142.7771584540935;1148.006973967512;1187.1689817128045;34328.82835585856;34436.82781586076;34704;34741.305173896384;34834.47748283778;1129.3844854554288;1130.2427373659668;1133.7279053027214;1155.312589009538;1187.4478367559127;16851.41020064292;18129.45611631653;18149.7277540837;18195.545111372056;18326.725099123523;590399;594890;595344;595465;599091;61134.38865611287;61189.38810611837;61633;61726.38273617296;61830;1309.3453273363318;1351.8310211223597;1399.5941177058742;1411.1815147214795;1445.7831325301079;30989;35277.47083793694;35575.46636800398;35993;36242.09394765152;53846.19230711464;54388;54455.727721362724;54456.727716361165;54512.182317266066;409288;411833;412118;412287;412963;31114.68885311118;32282.192945176557;32299.192520186243;33433;33477.66522334745;48136.27795582999;48332.27501587409;48558.75720621374;49571;49825.25262121144;41480.170396592504;41773.164536708486;41830.1633967325;42003.57996420119;43236.13527729365;41253.587464124976;41296.587034129276;41380.79309603433;41873.37189942093;42790.786046069574;41956.1608767829;42701.786491067345;42997.35503967381;43200.13599728051;43234;35063.246837657505;36042.27915441624;37031.6296837039;37303;37790.055248619006;29128.563071554367;31376.74493020345;31478;31591.420428978;31660.208494786893 19:52:42 INFO - PROCESS | 3435 | |2;jquery.html;391.87851765952576;436.28013777267506;436.32369826768513;437.17155989400095;437.5165442186384;16025.599360016005;19162.634462350547;19707.11317990692;20668.793312066875;20742.274020409288;19622.607547849035;19639.607207855835;20155.798442015577;20225.190992360287;20425.897870510664;5845.09401042839;6037.30570984338;6074.42292982166;6081.148639190517;6094.725737341808;5500.944990550103;5712.542996560286;5746.51154651853;5797.826065218029;5813.5349172066135;5745.052066409037;5746.367899531048;5759.4816466518005;5766.0486019806685;5817.389174136718;6054.061620448838;6124.479419249347;6127.5097992160745;6139;6142.447179753799;5811.447912448353;5984.670843103628;5987.43119403656;6000.669963152025;6035.758569657209;14383.137011779327;14516.637084072878;14583.125012499178;14600.926995364955;14620.488282910153;2303.6544518322216;2424.5878200705747;2572.6269690894715;2635.3543381871373;2974.3159073413235;10252.282340236112;10440.269181157322;10582.9470852646;10654.573817047387;10654.786904261871;1926.9690715467298;1977.317825350252;2052.4458396232935;2058.9176432942663;2083.114676262582;81161.18838811596;83497;83773.58113209395;83974;88276.55861720715;10358.274920755475;11600;11943.88056119445;12042.41088166031;12094.758104837854;15504.379824806882;15997.520074397788;16265.59336016609;16358.83641163597;16701.749473757904;8295.336373090138;8568.443051201652;8595;8682.86975695365;8748.300135989106;7263.564186148742;7475.887861682079;7486.363659089038;8167.550784706779;8294.502329860106;4898.975505122452;4987.925181122286;5002.374703162105;5131.435542090367;5223.921641175385;2018.0435661755598;2056.331692200041;2093.706881036656;2111.968320475194;2144.5389241312882;1044.529961517305;1058.6506452870535;1071.137734124022;1076.7900259449489;1082.718493191776;19978.400647980583;20165.899170504348;20400.897995510226;20708.482287942923;21049.368518944455;1003.8193125237527;1007.6926537406162;1021.5862575656871;1046.016744260393;1051.9053285204368;10514.526846291936;11343.716407089723;11392;11466.713332166595;11561.42192890352;468707;473253;475818;477617;479850;93203;93551.53224233972;94450.52774736083;95632.52183739036;95845;1044.5299615173203;1055.37205362809;1092.9617463388743;1113.1373185780922;1116.1405717597404;17488.562785930455;17678.82321176823;18079.638407232043;18177.454676359732;18201.635967280847;41149;42491.36262955996;43916.78041609899;44742.328865066396;44768.55231447644;60333.39666603453;60782;61419.07871381843;61699.383006171156;63065.369346305946;9106.908930910606;9223.538823058687;9232.353735238468;9267.490288033952;9366.718998430058;13208.867911320764;13800.792988104986;14018.509352173032;14249.287535623385;14406.783898241325;11629.825552616549;11864.169508134348;11924.76150477003;12334.629961101182;12383;11605.6518304451;11680.06559475257;11723.062155027406;11737;11935.34355610415;12037.217580857216;12422.689432764253;12625.494980201056;13026.153300035467;13124.803127953279;9171.128742769426;9184.540772961458;9202.49386283734;9218.26253899673;9502.904970950478;9925.255605829736;11831.526738930346;11831.704207394883;11930.821037684615;11944.163908526667 19:52:42 INFO - PROCESS | 3435 | |3;mootools.html;1401.3413695563095;1517.6661134550395;1552.487679065908;1688.805787334457;1784.7858257009152;7918.881216781753;8861.335399845013;9128.497932613704;9135;9353.953230233856;9134.132257435533;9170.541472926343;9217.262618990482;9219.308551858603;9235.538223088852;2475.3811547113223;2493.551160791057;2495.6506089147533;2495.8128140389454;2497.5629264878667;2388.5461762265113;2501.1996161228412;2505.1983365323103;2506.786923111538;2516.3834860459124;2480.7891329237036;2498.2755001049763;2532.15172917073;2534.809889258304;2538.746125387465;2434.6469761884514;2440.9511809763794;2443.328084776692;2484.2795589279085;2506.8997240110375;2400.327908185711;2438.6463962725484;2443.767842055002;2444.523317953;2471.7281099079087;8876.778080547974;8968.192862642329;9045.457272563668;9052.502112383816;9109.089091090904;5319.9734001330135;5465.59008074396;5513.365962914276;5520.558355331564;5528.06022976095;3507.8421471033867;3527.841247143859;3565.4830049642915;3612.6026137124895;3628.1111127773866;1114.331401159306;1125.3866642679677;1136.0854512117742;1146.776378606172;1162.8488296521482;12562.68593285166;12940.676483087904;12996.28520431376;13028.80456793149;13033.739325213537;4789.784459699322;4811.446683631357;4983;4985;5103.744812759347;11076;11081.612143575097;11081.944590277159;11086.722831929104;11138;5524.779008839681;5529.087700529448;5540.501354878081;5568.331800184003;5568.470995255444;4421.29259318514;4542.432195975503;4968.080905032581;5045.646804723634;5049;4294.227039132986;4313;4420.756858372756;4452.198604251266;4456.442944631921;1212.3271584270815;1276.9744605107846;1277.757226127033;1289.896808255356;1308.6205000549924;928.2620316848122;1021.3310281765456;1030.057497389895;1034.5292891734243;1047.2145890582065;6064.36324185963;6103.786367477117;6142.04798256271;6190.50475961922;6207.844803879939;458.48191543555566;458.84857996860956;460.8939943812942;462.08276571006525;465.83230037186587;4363.36731173978;4365.105153443536;4378.627816635558;4383.452068491439;4384.868453946387;11308.264962777394;11322;11406.600768973047;11509.482073306554;11567.710807229718;11309.660710178709;11480.196386253048;11514;11515.942420287845;11558.30650160993;8665.523396213268;8742.475451472805;8780.639000954861;8798.560071996375;8809.295256379603;6348.174737284124;6359.713812878339;6400.487960963205;6403.743850246125;6502.902456463252;10756.784864302826;10776.67669969902;10780.083692886192;10804.891951080388;10919.945400272947;10558.208134389797;10821.458927053773;10835.729106772395;10914.508847102059;10937.671869843918;7496.100467943664;7663.655135519027;8226.218509241613;8378.49729016261;8508.149185081442;9103.772405689911;9155.587998539948;9169.633214671605;9179.127982841619;9189.264858811146;8028.718994835152;8043.597820109088;8091;8093.271605555528;8212.384071194565;8023.077346105272;8072.152423995396;8092.5549094798;8112.959435202786;8235.094139644754;7221.133463984355;7274.527155734861;7293.562386256842;7306.232845551141;7526.774196774106;5556.305461817273;5585.273914391103;5749.367569567261;5770.249867517364;5771.797987070432;5515.4760297771645;5539.113741801292;5561.304836895388;5612.130119831432;5622.634528755618 19:52:42 INFO - PROCESS | 3435 | |4;prototype.html;336.40288487933924;338.12088569718736;341.07228338918134;344.7242206235013;349.43915016398677;5365.570754339653;5534;5589.7205139743;5660.320761508615;5683.460071293226;5358.705271210082;5478.863243335102;5499.7250137493065;5511.448855114487;5527.5301599364;4341.522432532419;4344.436003038919;4348.652107831366;4357.172137293904;4358.302671572549;4114.798582758225;4323.956760432403;4348.869533913988;4372.519022907478;4377.474703035624;4261.531231564541;4293.828246870122;4298.204832106055;4301.462317210348;4314.180305741915;4316.525182229952;4326.545712700153;4328.588784065509;4356.607905288539;4376.912461750779;3670.6696397324104;3788.6400791924725;3790.564085130218;3798.9430158547643;3801.2777572261184;5427.728613569345;5438.320209973754;5440.6735595863975;5443.59173062018;5467.835964921019;4989.101961646902;5002.774875130628;5008.924866127011;5025.572826309768;5054.519820617035;5679.687617181054;5810.3899090595305;5820.476157145878;5852;5876.353601103875;4141.337386018223;4154.335306350969;4162.979185104085;4186.246475634384;4213.494380674308;5846.707664616794;6122.785702500391;6139.539534534883;6170.8765824683705;6171.352008039136;4320.157569273962;4321.049369138785;4333.285007973649;4347.434833471629;4357.542458041923;4383.539728328544;4383.780810959439;4398.47218333802;4400.9559904401185;4405.361222622766;4987.600991920638;5001.124803159432;5016.072026675077;5022.598192144693;5031.52200540948;5486.506214440719;5605.299337582802;5813.76744930198;5874.353821079678;5889.4993925515955;1423.459085547506;1456.1627064437992;1483.4659522571865;1500.1524138861726;1500.6623509710446;972.2173650211647;990.7968866382374;1004.7789486313;1015.4871789746131;1021.341234903483;5571.387147413781;5581.525570326487;5600.719964001783;5632.633878797865;5660.830175094754;5915.515483496855;5915.550690080958;5932.93207222704;5945.137954996501;5948.464638182498;5429.56563474921;5660.716964151776;5676.779492409152;5730.215270249389;5791.623544469596;3208.6630903755236;3210;3240.141362538929;3241.5850191606933;3250.626177989514;6107.84730381744;6219.937800622027;6222.102258811106;6241.625502469867;6256.560990972011;6097.512199024068;6107.633541987495;6115.816525504242;6146.815595532141;6151.477124444383;6165.753369865245;6167.506599472032;6179.443850053517;6217.626942383471;6258.624482531062;5880.794437140462;5988.790392336247;6022.590963614589;6030.819075427744;6074.544409169242;5922.822315330547;5935;5947.256592925884;5948.817382700235;5951.285845698544;5972.790952316648;6181.96909015452;6218.595791273553;6236.532260080421;6265.587748716685;4252.277112890754;4275.850345237902;4292.484901811803;4320.589543993316;4340.370646256339;5797.101449275369;5914.852128696817;6109.2974307955155;6121.234845644294;6138;5711.8857622848145;5794.1308803679785;5816.272965879265;5817.331006934257;5831.79588714393;5806.1000544915605;5823.767049317979;5824.330202026652;5830.096335068071;5877.617954832922;5651.180578815966;5711.457411545896;5750.30996280449;5775.729339545293;5816.563757718103;5846.433675457883;5849.590528662953;5863.625231781136;5874.12168037667;5877.794277200277;5575.6933368663495;5701.401352858056;5735.7418916148035;5759.366469688247;5789.160571716993 19:52:42 INFO - PROCESS | 3435 | |5;yui.html;762.4357975098426;767.0565204798098;792.7899106736712;878.9648414063438;932.8554074118512;15478;15481.84518154818;16854.831451685463;16927.74608380874;18132.18405171767;16522.75215871763;16786.74819877703;16795.32818687251;16811.32754689814;16831.242594083265;862.1335557764438;865.8528050231445;866.1554983890687;866.2809867809734;866.514751739025;725.5646612032791;734.5629350536423;867.5315329721942;886.1404437695458;895.0154829687357;887.414306557672;887.8579427291635;890.8574628059512;891.3537685178227;891.7547674389563;878.1174919206189;880.1242763450408;883.0286684646869;883.2183517586917;888.3692578269437;552.5607142321863;564.661203278034;661.7617657643244;678.8845896197658;687.8589889072778;16801.579960500963;16833.91583042089;16837;16847.83152168487;16857.494275171764;14774.704505909822;14836.406543738238;14908.850911490963;15390.230488475643;15506.844931550653;12881.433731813926;13077.869221307761;13283.269420181887;13303.866961330456;13416.798748018788;4690.437147542317;4747.5105797239;4803.255495398219;4820.301056346845;4922.926156107661;86683;88523.11476885213;88599.11400885973;88650.11349886483;89019.10980890173;10899.128069754559;11086.889131108746;11150.330980141218;11253.493592788343;11377.772444551063;23224.535509290057;23240.53518929612;23269.185578504672;23309.41726456852;23438.531229375316;8625.568721563897;8859.822803543893;8890.244329231959;8902.109789021175;9050.728478145666;5038.686731590369;5123.410807757081;5183.740812959337;5210.374755029421;5285.682859028394;3601.873934412283;3731.458938453909;3735.0662334416397;3736;3736.906577335534;637.6461064109371;649.6329573790794;655.2137435077925;658.0425480925243;658.8616390558036;659.9901001484982;698.2214830463996;761.5202422473806;766.2796970847497;769.0387016229713;9327;9348.672796452227;9600.807983840425;10071.34536255141;10268.691939241835;649.7920665387126;722.212788061016;725.7895210389033;750.7484992527455;755.5995322479095;4597.126545956325;4780.306855505932;4816.8073277069225;4881.780319885614;4907.68100073494;127615.36192318979;131383;131703;134965;142043.2897835525;111941;117894.4105279468;118365.4081729603;119237;121706.3914680421;97468;97904.51047744715;99000;101731.49134254281;103931.4803425978;8921.286297096087;9202.35583509148;9253.67612133572;9275.301499755053;9499.47752873571;54917;57244;57950.71024645019;59550.10674839794;59650;80511.59744201241;86569.56715216384;87019.56490217762;88141.55929220314;93648;12016.699582510508;12472.251664899772;12998.935005324913;13143.671408214488;13346.532871349456;13485.325733713471;13670.453181872614;14419.206943618201;14695.853041469876;14765.630859228606;11358.318500889973;12792.936035320135;12829.550965716155;12944.417501212656;13080.607581772561;11123.610673626708;13826.377812998238;14098.647533811736;14216.928915355356;15633.452829150925;14322.14067155974;14451.85548144505;14484.855151448772;14504;15139.091654500766;12331.383430828602;12526;12731.363431828557;13258.469661213829;13264.204147751167;11784.057275417774;11851.288922664668;12130;12191.939040304742;12274.4476498556 19:52:42 INFO - PROCESS | 3435 | __end_tp_report 19:52:42 INFO - PROCESS | 3435 | __start_cc_report 19:52:42 INFO - PROCESS | 3435 | _x_x_mozilla_cycle_collect,1150 19:52:42 INFO - PROCESS | 3435 | __end_cc_report 19:52:42 INFO - PROCESS | 3435 | __startTimestamp1474599162242__endTimestamp 19:52:42 INFO - PROCESS | 3435 | 19:52:42 INFO - PROCESS | 3435 | ------- Summary: start ------- 19:52:42 INFO - PROCESS | 3435 | Number of tests: 6 19:52:42 INFO - PROCESS | 3435 | 19:52:42 INFO - PROCESS | 3435 | [#0] dojo.html Cycles:165 Average:5655.43 Median:5882.62 stddev:663.55 (11.3%) stddev-sans-first:634.71 19:52:42 INFO - PROCESS | 3435 | Values: 3105.3 3137.3 3145.5 3166.8 3184.0 5559.1 5650.5 5665.7 5678.1 5716.7 5704.0 5734.8 5747.9 5763.5 5767.0 5179.7 5204.5 5207.2 5237.3 5273.6 5012.9 5040.8 5277.8 5297.2 5329.5 5122.1 5132.2 5166.0 5171.5 5214.4 5220.7 5229.8 5299.3 5301.9 5320.7 4939.7 5122.7 5205.8 5252.7 5265.5 5484.3 5569.8 5583.9 5611.6 5683.0 5972.8 6014.4 6110.1 6111.8 6116.7 5921.4 6139.1 6148.0 6153.2 6154.3 5558.0 5611.4 5624.9 5650.6 5687.9 6245.5 6251.1 6254.5 6256.7 6260.9 5822.4 5999.1 6004.4 6004.9 6012.5 5940.6 5954.8 6011.7 6011.9 6021.2 5976.0 6007.5 6017.9 6022.6 6101.8 6116.7 6122.8 6123.9 6125.3 6142.3 4653.4 4655.1 4662.1 4671.3 4714.7 4022.9 4033.0 4088.6 4093.7 4141.0 5855.7 5855.8 5859.2 5874.6 5888.9 6109.4 6159.8 6168.1 6173.3 6174.8 5501.5 5549.0 5754.9 5810.6 5843.9 5351.0 5355.6 5357.2 5371.7 5372.1 6402.7 6407.3 6407.4 6407.8 6417.7 5821.5 5851.0 6188.8 6197.7 6203.5 6185.8 6238.2 6247.8 6343.7 6352.2 5996.6 6002.8 6010.8 6013.3 6017.3 5951.6 6062.8 6100.5 6111.6 6128.6 5854.0 5911.8 5918.3 5958.4 6047.2 6004.9 6006.3 6009.4 6016.5 6021.4 5960.2 5963.5 5967.6 5970.8 5970.9 5765.5 5863.9 5876.6 5947.2 5982.9 5875.4 5882.6 5898.5 5904.6 5907.2 19:52:42 INFO - PROCESS | 3435 | 19:52:42 INFO - PROCESS | 3435 | [#1] ext.html Cycles:180 Average:65091.19 Median:206649.12 stddev:128629.88 (62.2%) stddev-sans-first:128899.53 19:52:42 INFO - PROCESS | 3435 | Values: 580.2 623.9 642.8 657.1 671.2 33771.2 34457.8 36707.0 49292.8 51213.0 48962.0 49400.3 49430.0 49479.3 49531.0 9189.0 9334.8 9341.2 9345.3 9348.6 8529.5 8705.3 9078.0 10794.3 11159.6 11243.8 11294.1 11310.7 11310.9 11314.7 11165.3 11221.1 11232.8 11242.2 11254.8 8445.2 8657.1 10650.0 10968.4 10989.6 44834.3 45190.3 45218.5 45222.8 45233.3 3883.4 3883.6 3903.5 3992.0 4010.2 35364.6 35510.0 35566.0 35754.0 35775.8 4512.3 4526.5 4594.2 4599.2 4694.3 376060.0 388338.0 397188.0 399171.0 548313.0 66780.7 67248.7 67253.3 67296.7 67311.3 71000.6 71038.0 71303.0 71423.3 71631.3 19333.4 19630.1 19666.7 19669.8 19923.3 12272.7 15368.9 15460.8 15495.8 15503.8 18061.1 19638.1 20487.5 20551.2 21170.7 7326.8 7460.3 7598.0 7598.3 7739.1 967.4 1058.7 1142.8 1148.0 1187.2 34328.8 34436.8 34704.0 34741.3 34834.5 1129.4 1130.2 1133.7 1155.3 1187.4 16851.4 18129.5 18149.7 18195.5 18326.7 590399.0 594890.0 595344.0 595465.0 599091.0 61134.4 61189.4 61633.0 61726.4 61830.0 1309.3 1351.8 1399.6 1411.2 1445.8 30989.0 35277.5 35575.5 35993.0 36242.1 53846.2 54388.0 54455.7 54456.7 54512.2 409288.0 411833.0 412118.0 412287.0 412963.0 31114.7 32282.2 32299.2 33433.0 33477.7 48136.3 48332.3 48558.8 49571.0 49825.3 41480.2 41773.2 41830.2 42003.6 43236.1 41253.6 41296.6 41380.8 41873.4 42790.8 41956.2 42701.8 42997.4 43200.1 43234.0 35063.2 36042.3 37031.6 37303.0 37790.1 29128.6 31376.7 31478.0 31591.4 31660.2 19:52:42 INFO - PROCESS | 3435 | 19:52:42 INFO - PROCESS | 3435 | [#2] jquery.html Cycles:180 Average:28942.35 Median:11571.54 stddev:78546.90 (678.8%) stddev-sans-first:78737.99 19:52:42 INFO - PROCESS | 3435 | Values: 391.9 436.3 436.3 437.2 437.5 16025.6 19162.6 19707.1 20668.8 20742.3 19622.6 19639.6 20155.8 20225.2 20425.9 5845.1 6037.3 6074.4 6081.1 6094.7 5500.9 5712.5 5746.5 5797.8 5813.5 5745.1 5746.4 5759.5 5766.0 5817.4 6054.1 6124.5 6127.5 6139.0 6142.4 5811.4 5984.7 5987.4 6000.7 6035.8 14383.1 14516.6 14583.1 14600.9 14620.5 2303.7 2424.6 2572.6 2635.4 2974.3 10252.3 10440.3 10582.9 10654.6 10654.8 1927.0 1977.3 2052.4 2058.9 2083.1 81161.2 83497.0 83773.6 83974.0 88276.6 10358.3 11600.0 11943.9 12042.4 12094.8 15504.4 15997.5 16265.6 16358.8 16701.7 8295.3 8568.4 8595.0 8682.9 8748.3 7263.6 7475.9 7486.4 8167.6 8294.5 4899.0 4987.9 5002.4 5131.4 5223.9 2018.0 2056.3 2093.7 2112.0 2144.5 1044.5 1058.7 1071.1 1076.8 1082.7 19978.4 20165.9 20400.9 20708.5 21049.4 1003.8 1007.7 1021.6 1046.0 1051.9 10514.5 11343.7 11392.0 11466.7 11561.4 468707.0 473253.0 475818.0 477617.0 479850.0 93203.0 93551.5 94450.5 95632.5 95845.0 1044.5 1055.4 1093.0 1113.1 1116.1 17488.6 17678.8 18079.6 18177.5 18201.6 41149.0 42491.4 43916.8 44742.3 44768.6 60333.4 60782.0 61419.1 61699.4 63065.4 9106.9 9223.5 9232.4 9267.5 9366.7 13208.9 13800.8 14018.5 14249.3 14406.8 11629.8 11864.2 11924.8 12334.6 12383.0 11605.7 11680.1 11723.1 11737.0 11935.3 12037.2 12422.7 12625.5 13026.2 13124.8 9171.1 9184.5 9202.5 9218.3 9502.9 9925.3 11831.5 11831.7 11930.8 11944.2 19:52:42 INFO - PROCESS | 3435 | 19:52:42 INFO - PROCESS | 3435 | [#3] mootools.html Cycles:180 Average:6107.32 Median:2500.46 stddev:3494.88 (139.8%) stddev-sans-first:3486.79 19:52:42 INFO - PROCESS | 3435 | Values: 1401.3 1517.7 1552.5 1688.8 1784.8 7918.9 8861.3 9128.5 9135.0 9354.0 9134.1 9170.5 9217.3 9219.3 9235.5 2475.4 2493.6 2495.7 2495.8 2497.6 2388.5 2501.2 2505.2 2506.8 2516.4 2480.8 2498.3 2532.2 2534.8 2538.7 2434.6 2441.0 2443.3 2484.3 2506.9 2400.3 2438.6 2443.8 2444.5 2471.7 8876.8 8968.2 9045.5 9052.5 9109.1 5320.0 5465.6 5513.4 5520.6 5528.1 3507.8 3527.8 3565.5 3612.6 3628.1 1114.3 1125.4 1136.1 1146.8 1162.8 12562.7 12940.7 12996.3 13028.8 13033.7 4789.8 4811.4 4983.0 4985.0 5103.7 11076.0 11081.6 11081.9 11086.7 11138.0 5524.8 5529.1 5540.5 5568.3 5568.5 4421.3 4542.4 4968.1 5045.6 5049.0 4294.2 4313.0 4420.8 4452.2 4456.4 1212.3 1277.0 1277.8 1289.9 1308.6 928.3 1021.3 1030.1 1034.5 1047.2 6064.4 6103.8 6142.0 6190.5 6207.8 458.5 458.8 460.9 462.1 465.8 4363.4 4365.1 4378.6 4383.5 4384.9 11308.3 11322.0 11406.6 11509.5 11567.7 11309.7 11480.2 11514.0 11515.9 11558.3 8665.5 8742.5 8780.6 8798.6 8809.3 6348.2 6359.7 6400.5 6403.7 6502.9 10756.8 10776.7 10780.1 10804.9 10919.9 10558.2 10821.5 10835.7 10914.5 10937.7 7496.1 7663.7 8226.2 8378.5 8508.1 9103.8 9155.6 9169.6 9179.1 9189.3 8028.7 8043.6 8091.0 8093.3 8212.4 8023.1 8072.2 8092.6 8113.0 8235.1 7221.1 7274.5 7293.6 7306.2 7526.8 5556.3 5585.3 5749.4 5770.2 5771.8 5515.5 5539.1 5561.3 5612.1 5622.6 19:52:42 INFO - PROCESS | 3435 | 19:52:42 INFO - PROCESS | 3435 | [#4] prototype.html Cycles:180 Average:4923.78 Median:5595.22 stddev:1446.58 (25.9%) stddev-sans-first:1409.06 19:52:42 INFO - PROCESS | 3435 | Values: 336.4 338.1 341.1 344.7 349.4 5365.6 5534.0 5589.7 5660.3 5683.5 5358.7 5478.9 5499.7 5511.4 5527.5 4341.5 4344.4 4348.7 4357.2 4358.3 4114.8 4324.0 4348.9 4372.5 4377.5 4261.5 4293.8 4298.2 4301.5 4314.2 4316.5 4326.5 4328.6 4356.6 4376.9 3670.7 3788.6 3790.6 3798.9 3801.3 5427.7 5438.3 5440.7 5443.6 5467.8 4989.1 5002.8 5008.9 5025.6 5054.5 5679.7 5810.4 5820.5 5852.0 5876.4 4141.3 4154.3 4163.0 4186.2 4213.5 5846.7 6122.8 6139.5 6170.9 6171.4 4320.2 4321.0 4333.3 4347.4 4357.5 4383.5 4383.8 4398.5 4401.0 4405.4 4987.6 5001.1 5016.1 5022.6 5031.5 5486.5 5605.3 5813.8 5874.4 5889.5 1423.5 1456.2 1483.5 1500.2 1500.7 972.2 990.8 1004.8 1015.5 1021.3 5571.4 5581.5 5600.7 5632.6 5660.8 5915.5 5915.6 5932.9 5945.1 5948.5 5429.6 5660.7 5676.8 5730.2 5791.6 3208.7 3210.0 3240.1 3241.6 3250.6 6107.8 6219.9 6222.1 6241.6 6256.6 6097.5 6107.6 6115.8 6146.8 6151.5 6165.8 6167.5 6179.4 6217.6 6258.6 5880.8 5988.8 6022.6 6030.8 6074.5 5922.8 5935.0 5947.3 5948.8 5951.3 5972.8 6182.0 6218.6 6236.5 6265.6 4252.3 4275.9 4292.5 4320.6 4340.4 5797.1 5914.9 6109.3 6121.2 6138.0 5711.9 5794.1 5816.3 5817.3 5831.8 5806.1 5823.8 5824.3 5830.1 5877.6 5651.2 5711.5 5750.3 5775.7 5816.6 5846.4 5849.6 5863.6 5874.1 5877.8 5575.7 5701.4 5735.7 5759.4 5789.2 19:52:42 INFO - PROCESS | 3435 | 19:52:42 INFO - PROCESS | 3435 | [#5] yui.html Cycles:180 Average:23437.25 Median:4718.97 stddev:35156.33 (745.0%) stddev-sans-first:35213.73 19:52:42 INFO - PROCESS | 3435 | Values: 762.4 767.1 792.8 879.0 932.9 15478.0 15481.8 16854.8 16927.7 18132.2 16522.8 16786.7 16795.3 16811.3 16831.2 862.1 865.9 866.2 866.3 866.5 725.6 734.6 867.5 886.1 895.0 887.4 887.9 890.9 891.4 891.8 878.1 880.1 883.0 883.2 888.4 552.6 564.7 661.8 678.9 687.9 16801.6 16833.9 16837.0 16847.8 16857.5 14774.7 14836.4 14908.9 15390.2 15506.8 12881.4 13077.9 13283.3 13303.9 13416.8 4690.4 4747.5 4803.3 4820.3 4922.9 86683.0 88523.1 88599.1 88650.1 89019.1 10899.1 11086.9 11150.3 11253.5 11377.8 23224.5 23240.5 23269.2 23309.4 23438.5 8625.6 8859.8 8890.2 8902.1 9050.7 5038.7 5123.4 5183.7 5210.4 5285.7 3601.9 3731.5 3735.1 3736.0 3736.9 637.6 649.6 655.2 658.0 658.9 660.0 698.2 761.5 766.3 769.0 9327.0 9348.7 9600.8 10071.3 10268.7 649.8 722.2 725.8 750.7 755.6 4597.1 4780.3 4816.8 4881.8 4907.7 127615.4 131383.0 131703.0 134965.0 142043.3 111941.0 117894.4 118365.4 119237.0 121706.4 97468.0 97904.5 99000.0 101731.5 103931.5 8921.3 9202.4 9253.7 9275.3 9499.5 54917.0 57244.0 57950.7 59550.1 59650.0 80511.6 86569.6 87019.6 88141.6 93648.0 12016.7 12472.3 12998.9 13143.7 13346.5 13485.3 13670.5 14419.2 14695.9 14765.6 11358.3 12792.9 12829.6 12944.4 13080.6 11123.6 13826.4 14098.6 14216.9 15633.5 14322.1 14451.9 14484.9 14504.0 15139.1 12331.4 12526.0 12731.4 13258.5 13264.2 11784.1 11851.3 12130.0 12191.9 12274.4 19:52:42 INFO - PROCESS | 3435 | -------- Summary: end -------- 19:52:42 INFO - PROCESS | 3435 | 19:52:43 INFO - TEST-INFO | 3435: exit 0 19:52:43 INFO - TEST-OK | dromaeo_css | took 1114910ms 19:52:43 INFO - TEST-START | kraken 19:52:43 INFO - operating with platform_type : linux_ 19:52:43 INFO - Initialising browser for kraken test... 19:52:43 INFO - TEST-INFO | started process 3758 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpd_uZ8p/profile http://localhost:48291/getInfo.html) 19:52:47 INFO - PROCESS | 3758 | __metrics Screen width/height:1600/1200 19:52:47 INFO - PROCESS | 3758 | colorDepth:24 19:52:47 INFO - PROCESS | 3758 | Browser inner width/height: 1024/697 19:52:47 INFO - PROCESS | 3758 | __metrics 19:52:47 INFO - PROCESS | 3758 | 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" 19:52:47 INFO - PROCESS | 3758 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:52:48 INFO - PROCESS | 3758 | console.error: PushService: 19:52:48 INFO - PROCESS | 3758 | stateChangeProcessEnqueue: Error transitioning state 19:52:48 INFO - PROCESS | 3758 | UnknownError 19:52:48 INFO - TEST-INFO | 3758: exit 0 19:52:48 INFO - Browser initialized. 19:52:48 INFO - Running cycle 1/1 for kraken test... 19:52:48 INFO - TEST-INFO | started process 3848 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpd_uZ8p/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:53:00 INFO - PROCESS | 3848 | RSS: Main: 143921152 19:53:00 INFO - PROCESS | 3848 | 19:53:03 INFO - PROCESS | 3848 | Cycle 1(1): loaded http://localhost:48291/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:48291/tests/kraken/driver.html?testName=audio-beat-detection) 19:53:03 INFO - PROCESS | 3848 | RSS: Main: 186089472 19:53:03 INFO - PROCESS | 3848 | 19:53:06 INFO - PROCESS | 3848 | Cycle 1(1): loaded http://localhost:48291/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:48291/tests/kraken/driver.html?testName=audio-dft) 19:53:07 INFO - PROCESS | 3848 | RSS: Main: 175644672 19:53:07 INFO - PROCESS | 3848 | 19:53:09 INFO - PROCESS | 3848 | Cycle 1(1): loaded http://localhost:48291/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:48291/tests/kraken/driver.html?testName=audio-fft) 19:53:10 INFO - PROCESS | 3848 | RSS: Main: 266981376 19:53:10 INFO - PROCESS | 3848 | 19:53:12 INFO - PROCESS | 3848 | Cycle 1(1): loaded http://localhost:48291/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:48291/tests/kraken/driver.html?testName=audio-oscillator) 19:53:13 INFO - PROCESS | 3848 | RSS: Main: 172421120 19:53:13 INFO - PROCESS | 3848 | 19:53:15 INFO - PROCESS | 3848 | Cycle 1(1): loaded http://localhost:48291/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:48291/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:53:15 INFO - PROCESS | 3848 | RSS: Main: 174448640 19:53:15 INFO - PROCESS | 3848 | 19:53:18 INFO - PROCESS | 3848 | Cycle 1(1): loaded http://localhost:48291/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:48291/tests/kraken/driver.html?testName=imaging-darkroom) 19:53:19 INFO - PROCESS | 3848 | RSS: Main: 210538496 19:53:19 INFO - PROCESS | 3848 | 19:53:22 INFO - PROCESS | 3848 | Cycle 1(1): loaded http://localhost:48291/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:48291/tests/kraken/driver.html?testName=imaging-desaturate) 19:53:23 INFO - PROCESS | 3848 | RSS: Main: 210001920 19:53:23 INFO - PROCESS | 3848 | 19:53:26 INFO - PROCESS | 3848 | Cycle 1(1): loaded http://localhost:48291/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:48291/tests/kraken/driver.html?testName=json-parse-financial) 19:53:26 INFO - PROCESS | 3848 | RSS: Main: 209149952 19:53:26 INFO - PROCESS | 3848 | 19:53:28 INFO - PROCESS | 3848 | Cycle 1(1): loaded http://localhost:48291/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:48291/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:53:28 INFO - PROCESS | 3848 | RSS: Main: 170889216 19:53:28 INFO - PROCESS | 3848 | 19:53:30 INFO - PROCESS | 3848 | Cycle 1(1): loaded http://localhost:48291/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:48291/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:53:31 INFO - PROCESS | 3848 | RSS: Main: 177696768 19:53:31 INFO - PROCESS | 3848 | 19:53:33 INFO - PROCESS | 3848 | Cycle 1(1): loaded http://localhost:48291/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:48291/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:53:33 INFO - PROCESS | 3848 | RSS: Main: 182784000 19:53:33 INFO - PROCESS | 3848 | 19:53:36 INFO - PROCESS | 3848 | Cycle 1(1): loaded http://localhost:48291/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:48291/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:53:36 INFO - PROCESS | 3848 | RSS: Main: 184639488 19:53:36 INFO - PROCESS | 3848 | 19:53:39 INFO - PROCESS | 3848 | Cycle 1(1): loaded http://localhost:48291/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:48291/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:53:40 INFO - PROCESS | 3848 | RSS: Main: 182247424 19:53:40 INFO - PROCESS | 3848 | 19:53:41 INFO - PROCESS | 3848 | Cycle 1(1): loaded http://localhost:48291/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:48291/tests/kraken/driver.html?testName=ai-astar) 19:53:42 INFO - PROCESS | 3848 | RSS: Main: 234524672 19:53:42 INFO - PROCESS | 3848 | 19:53:42 INFO - PROCESS | 3848 | __start_tp_report 19:53:42 INFO - PROCESS | 3848 | _x_x_mozilla_page_load 19:53:42 INFO - PROCESS | 3848 | _x_x_mozilla_page_load_details 19:53:42 INFO - PROCESS | 3848 | |i|pagename|runs| 19:53:42 INFO - PROCESS | 3848 | |0;ai-astar;110;112;112;112;113;114;113;112;111;111 19:53:42 INFO - PROCESS | 3848 | |1;audio-beat-detection;140;116;116;143;119;121;116;118;149;116 19:53:42 INFO - PROCESS | 3848 | |2;audio-dft;159;160;160;159;167;158;159;162;161;169 19:53:42 INFO - PROCESS | 3848 | |3;audio-fft;75;76;75;112;75;75;76;74;99;76 19:53:42 INFO - PROCESS | 3848 | |4;audio-oscillator;82;82;104;82;81;82;102;80;81;106 19:53:42 INFO - PROCESS | 3848 | |5;imaging-gaussian-blur;117;117;117;117;116;117;117;116;117;117 19:53:42 INFO - PROCESS | 3848 | |6;imaging-darkroom;146;146;146;146;147;147;146;147;146;146 19:53:42 INFO - PROCESS | 3848 | |7;imaging-desaturate;94;96;95;95;96;95;96;94;95;95 19:53:42 INFO - PROCESS | 3848 | |8;json-parse-financial;50;49;50;49;49;48;51;50;48;49 19:53:42 INFO - PROCESS | 3848 | |9;json-stringify-tinderbox;59;59;61;60;59;59;59;59;59;60 19:53:42 INFO - PROCESS | 3848 | |10;stanford-crypto-aes;73;75;74;73;75;80;72;73;76;73 19:53:42 INFO - PROCESS | 3848 | |11;stanford-crypto-ccm;124;117;127;120;118;117;135;124;162;120 19:53:42 INFO - PROCESS | 3848 | |12;stanford-crypto-pbkdf2;152;154;155;152;155;154;150;153;157;155 19:53:42 INFO - PROCESS | 3848 | |13;stanford-crypto-sha256-iterative;62;62;62;61;61;61;62;61;61;60 19:53:42 INFO - PROCESS | 3848 | __end_tp_report 19:53:42 INFO - PROCESS | 3848 | __start_cc_report 19:53:42 INFO - PROCESS | 3848 | _x_x_mozilla_cycle_collect,1663 19:53:42 INFO - PROCESS | 3848 | __end_cc_report 19:53:42 INFO - PROCESS | 3848 | __startTimestamp1474599222064__endTimestamp 19:53:42 INFO - PROCESS | 3848 | 19:53:42 INFO - PROCESS | 3848 | ------- Summary: start ------- 19:53:42 INFO - PROCESS | 3848 | Number of tests: 14 19:53:42 INFO - PROCESS | 3848 | 19:53:42 INFO - PROCESS | 3848 | [#0] ai-astar Cycles:10 Average:112.00 Median:112.00 stddev:1.15 (1.0%) stddev-sans-first:0.97 19:53:42 INFO - PROCESS | 3848 | Values: 110.0 112.0 112.0 112.0 113.0 114.0 113.0 112.0 111.0 111.0 19:53:42 INFO - PROCESS | 3848 | 19:53:42 INFO - PROCESS | 3848 | [#1] audio-beat-detection Cycles:10 Average:125.40 Median:120.00 stddev:13.12 (10.9%) stddev-sans-first:12.80 19:53:42 INFO - PROCESS | 3848 | Values: 140.0 116.0 116.0 143.0 119.0 121.0 116.0 118.0 149.0 116.0 19:53:42 INFO - PROCESS | 3848 | 19:53:42 INFO - PROCESS | 3848 | [#2] audio-dft Cycles:10 Average:161.40 Median:160.50 stddev:3.69 (2.3%) stddev-sans-first:3.81 19:53:42 INFO - PROCESS | 3848 | Values: 159.0 160.0 160.0 159.0 167.0 158.0 159.0 162.0 161.0 169.0 19:53:42 INFO - PROCESS | 3848 | 19:53:42 INFO - PROCESS | 3848 | [#3] audio-fft Cycles:10 Average:81.30 Median:75.50 stddev:13.13 (17.4%) stddev-sans-first:13.73 19:53:42 INFO - PROCESS | 3848 | Values: 75.0 76.0 75.0 112.0 75.0 75.0 76.0 74.0 99.0 76.0 19:53:42 INFO - PROCESS | 3848 | 19:53:42 INFO - PROCESS | 3848 | [#4] audio-oscillator Cycles:10 Average:88.20 Median:81.50 stddev:10.96 (13.5%) stddev-sans-first:11.40 19:53:42 INFO - PROCESS | 3848 | Values: 82.0 82.0 104.0 82.0 81.0 82.0 102.0 80.0 81.0 106.0 19:53:42 INFO - PROCESS | 3848 | 19:53:42 INFO - PROCESS | 3848 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 19:53:42 INFO - PROCESS | 3848 | Values: 117.0 117.0 117.0 117.0 116.0 117.0 117.0 116.0 117.0 117.0 19:53:42 INFO - PROCESS | 3848 | 19:53:42 INFO - PROCESS | 3848 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 19:53:42 INFO - PROCESS | 3848 | Values: 146.0 146.0 146.0 146.0 147.0 147.0 146.0 147.0 146.0 146.0 19:53:42 INFO - PROCESS | 3848 | 19:53:42 INFO - PROCESS | 3848 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.74 (0.8%) stddev-sans-first:0.67 19:53:42 INFO - PROCESS | 3848 | Values: 94.0 96.0 95.0 95.0 96.0 95.0 96.0 94.0 95.0 95.0 19:53:42 INFO - PROCESS | 3848 | 19:53:42 INFO - PROCESS | 3848 | [#8] json-parse-financial Cycles:10 Average:49.30 Median:49.50 stddev:0.95 (1.9%) stddev-sans-first:0.97 19:53:42 INFO - PROCESS | 3848 | Values: 50.0 49.0 50.0 49.0 49.0 48.0 51.0 50.0 48.0 49.0 19:53:42 INFO - PROCESS | 3848 | 19:53:42 INFO - PROCESS | 3848 | [#9] json-stringify-tinderbox Cycles:10 Average:59.40 Median:59.00 stddev:0.70 (1.2%) stddev-sans-first:0.73 19:53:42 INFO - PROCESS | 3848 | Values: 59.0 59.0 61.0 60.0 59.0 59.0 59.0 59.0 59.0 60.0 19:53:42 INFO - PROCESS | 3848 | 19:53:42 INFO - PROCESS | 3848 | [#10] stanford-crypto-aes Cycles:10 Average:74.40 Median:74.50 stddev:2.32 (3.1%) stddev-sans-first:2.40 19:53:42 INFO - PROCESS | 3848 | Values: 73.0 75.0 74.0 73.0 75.0 80.0 72.0 73.0 76.0 73.0 19:53:42 INFO - PROCESS | 3848 | 19:53:42 INFO - PROCESS | 3848 | [#11] stanford-crypto-ccm Cycles:10 Average:126.40 Median:124.00 stddev:13.67 (11.0%) stddev-sans-first:14.47 19:53:42 INFO - PROCESS | 3848 | Values: 124.0 117.0 127.0 120.0 118.0 117.0 135.0 124.0 162.0 120.0 19:53:42 INFO - PROCESS | 3848 | 19:53:42 INFO - PROCESS | 3848 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.70 Median:154.50 stddev:2.00 (1.3%) stddev-sans-first:2.03 19:53:42 INFO - PROCESS | 3848 | Values: 152.0 154.0 155.0 152.0 155.0 154.0 150.0 153.0 157.0 155.0 19:53:42 INFO - PROCESS | 3848 | 19:53:42 INFO - PROCESS | 3848 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.30 Median:61.50 stddev:0.67 (1.1%) stddev-sans-first:0.67 19:53:42 INFO - PROCESS | 3848 | Values: 62.0 62.0 62.0 61.0 61.0 61.0 62.0 61.0 61.0 60.0 19:53:42 INFO - PROCESS | 3848 | -------- Summary: end -------- 19:53:42 INFO - PROCESS | 3848 | 19:53:43 INFO - TEST-INFO | 3848: exit 0 19:53:43 INFO - TEST-OK | kraken | took 60095ms 19:53:43 INFO - SUITE-END | took 1175s 19:53:43 INFO - Completed test suite (00:19:35) 19:53:43 INFO - javascript benchmark 19:53:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3105.2702614885507, 3137.3254750228703, 3145.496720524715, 3166.8416579171035, 3183.952240716388, 5559.110542313226, 5650.463205995429, 5665.660060396379, 5678.119891416826, 5716.685582292972, 5704.0, 5734.79928202513, 5747.885042299152, 5763.510101641355, 5767.048437007888, 5179.741012949356, 5204.479552044784, 5207.192885102815, 5237.266782650432, 5273.604479664022, 5012.924806127911, 5040.798368065273, 5277.8416647500635, 5297.205419187134, 5329.467053294659, 5122.129238029544, 5132.204508301219, 5165.974170129144, 5171.534561889429, 5214.400343960437, 5220.660657057279, 5229.843104706846, 5299.337582802111, 5301.893962120775, 5320.680759154463, 4939.678920870132, 5122.743862806845, 5205.791768329263, 5252.658577192508, 5265.4997775211295, 5484.314460692413, 5569.777208911679, 5583.916241256385, 5611.607187496877, 5683.033884239648, 5972.761089556412, 6014.3985601439945, 6110.08348747687, 6111.786087486961, 6116.663583502906, 5921.378255283176, 6139.079138129272, 6147.969260153671, 6153.1693221415435, 6154.26148862135, 5557.972210138923, 5611.438856114397, 5624.91562626561, 5650.632708873951, 5687.9431205687415, 6245.500359971283, 6251.12484252205, 6254.499640028786, 6256.655883926428, 6260.906086408708, 5822.388649191858, 5999.100134979788, 6004.39956004396, 6004.909926351108, 6012.488938440194, 5940.6435613863305, 5954.791582294599, 6011.699415029231, 6011.909821352683, 6021.217241758631, 5975.970120149372, 6007.459328660441, 6017.939820601738, 6022.608530445507, 6101.816229757492, 6116.728650446344, 6122.816315510542, 6123.923976812287, 6125.327021303102, 6142.324344322121, 4653.441587009513, 4655.138799322136, 4662.114198302312, 4671.346011558385, 4714.669973101919, 4022.939655905164, 4033.0, 4088.6320231178743, 4093.6725061995567, 4140.979295103565, 5855.677937713382, 5855.795047173328, 5859.2090067841145, 5874.618149820248, 5888.852778680482, 6109.389061093855, 6159.784407545714, 6168.074788781719, 6173.290071641729, 6174.753009879644, 5501.477359650827, 5548.9722551386985, 5754.913676294859, 5810.622309549865, 5843.853903652443, 5351.036813373552, 5355.598330125178, 5357.223202635596, 5371.677699338053, 5372.113601255827, 6402.743890244431, 6407.295197528269, 6407.423331900151, 6407.807765767035, 6417.74329026843, 5821.534277257724, 5851.034579294539, 6188.81433556994, 6197.721102550487, 6203.47270481996, 6185.84535386619, 6238.15784869055, 6247.843803904939, 6343.746250149942, 6352.237731472068, 5996.580239383201, 6002.769432266287, 6010.849728756816, 6013.30846952606, 6017.2779266488305, 5951.553633477593, 6062.848428789139, 6100.54245931548, 6111.633302001894, 6128.63228206309, 5854.0, 5911.7930872420975, 5918.3489816121055, 5958.433948774859, 6047.244094488189, 6004.909926351021, 6006.279246490449, 6009.399060093956, 6016.488598469179, 6021.397860213943, 5960.225170727951, 5963.463288304073, 5967.552433567586, 5970.820875373745, 5970.940290597038, 5765.4522820332, 5863.94136058651, 5876.6180198286975, 5947.197128387612, 5982.88034239304, 5875.441833025856, 5882.617629854046, 5898.498627616701, 5904.557158213237, 5907.202527658883], "value": 5608.146217645152, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [580.1848402993795, 623.9469645080168, 642.7975187815839, 657.1457105762507, 671.2414971082676, 33771.15572110698, 34457.82771086144, 36707.0, 49292.75353623236, 51213.0, 48962.0, 49400.25899611509, 49430.0, 49479.25781113286, 49531.0, 9188.954055229731, 9334.766630834249, 9341.205997490222, 9345.345825792198, 9348.626054957793, 8529.488230706178, 8705.303575713928, 9077.954610226972, 10794.298370605924, 11159.609413670523, 11243.83134252987, 11294.09647228224, 11310.660680179608, 11310.886891131107, 11314.660560183207, 11165.274257173298, 11221.102311815035, 11232.831507527393, 11242.213045086888, 11254.831177532344, 8445.239928406412, 8657.091005444401, 10650.0, 10968.396738179397, 10989.61536346224, 44834.32748508775, 45190.32214516752, 45218.54781452209, 45222.77388613036, 45233.321500177524, 3883.436901649245, 3883.6310550497656, 3903.4730311408166, 3991.960080399217, 4010.2380547695843, 35364.646353536395, 35510.0, 35566.0, 35754.0, 35775.82112089449, 4512.255477846152, 4526.502084770673, 4594.150082234763, 4599.241125214336, 4694.29585562165, 376060.0, 388338.0, 397188.0, 399171.0, 548312.999999996, 66780.66609667017, 67248.66375668188, 67253.3274667247, 67296.66351668308, 67311.3268867305, 71000.64499677572, 71038.0, 71303.0, 71423.28576714166, 71631.28368716246, 19333.419997400102, 19630.116644751022, 19666.70499942502, 19669.803301967084, 19923.302684405975, 12272.693182670326, 15368.923155384375, 15460.768088478682, 15495.845041549666, 15503.83721220913, 18061.09694515269, 19638.11628476722, 20487.4878128048, 20551.177952881717, 21170.682439763415, 7326.849454734515, 7460.29127232912, 7597.962010189914, 7598.278163574451, 7739.148693643695, 967.3760424526141, 1058.7194393485731, 1142.7771584540935, 1148.006973967512, 1187.1689817128045, 34328.82835585856, 34436.82781586076, 34704.0, 34741.305173896384, 34834.47748283778, 1129.3844854554288, 1130.2427373659668, 1133.7279053027214, 1155.312589009538, 1187.4478367559127, 16851.41020064292, 18129.45611631653, 18149.7277540837, 18195.545111372056, 18326.725099123523, 590399.0, 594890.0, 595344.0, 595465.0, 599091.0, 61134.38865611287, 61189.38810611837, 61633.0, 61726.38273617296, 61830.0, 1309.3453273363318, 1351.8310211223597, 1399.5941177058742, 1411.1815147214795, 1445.7831325301079, 30989.0, 35277.47083793694, 35575.46636800398, 35993.0, 36242.09394765152, 53846.19230711464, 54388.0, 54455.727721362724, 54456.727716361165, 54512.182317266066, 409288.0, 411833.0, 412118.0, 412287.0, 412963.0, 31114.68885311118, 32282.192945176557, 32299.192520186243, 33433.0, 33477.66522334745, 48136.27795582999, 48332.27501587409, 48558.75720621374, 49571.0, 49825.25262121144, 41480.170396592504, 41773.164536708486, 41830.1633967325, 42003.57996420119, 43236.13527729365, 41253.587464124976, 41296.587034129276, 41380.79309603433, 41873.37189942093, 42790.786046069574, 41956.1608767829, 42701.786491067345, 42997.35503967381, 43200.13599728051, 43234.0, 35063.246837657505, 36042.27915441624, 37031.6296837039, 37303.0, 37790.055248619006, 29128.563071554367, 31376.74493020345, 31478.0, 31591.420428978, 31660.208494786893], "value": 21820.35739917595, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [391.87851765952576, 436.28013777267506, 436.32369826768513, 437.17155989400095, 437.5165442186384, 16025.599360016005, 19162.634462350547, 19707.11317990692, 20668.793312066875, 20742.274020409288, 19622.607547849035, 19639.607207855835, 20155.798442015577, 20225.190992360287, 20425.897870510664, 5845.09401042839, 6037.30570984338, 6074.42292982166, 6081.148639190517, 6094.725737341808, 5500.944990550103, 5712.542996560286, 5746.51154651853, 5797.826065218029, 5813.5349172066135, 5745.052066409037, 5746.367899531048, 5759.4816466518005, 5766.0486019806685, 5817.389174136718, 6054.061620448838, 6124.479419249347, 6127.5097992160745, 6139.0, 6142.447179753799, 5811.447912448353, 5984.670843103628, 5987.43119403656, 6000.669963152025, 6035.758569657209, 14383.137011779327, 14516.637084072878, 14583.125012499178, 14600.926995364955, 14620.488282910153, 2303.6544518322216, 2424.5878200705747, 2572.6269690894715, 2635.3543381871373, 2974.3159073413235, 10252.282340236112, 10440.269181157322, 10582.9470852646, 10654.573817047387, 10654.786904261871, 1926.9690715467298, 1977.317825350252, 2052.4458396232935, 2058.9176432942663, 2083.114676262582, 81161.18838811596, 83497.0, 83773.58113209395, 83974.0, 88276.55861720715, 10358.274920755475, 11600.0, 11943.88056119445, 12042.41088166031, 12094.758104837854, 15504.379824806882, 15997.520074397788, 16265.59336016609, 16358.83641163597, 16701.749473757904, 8295.336373090138, 8568.443051201652, 8595.0, 8682.86975695365, 8748.300135989106, 7263.564186148742, 7475.887861682079, 7486.363659089038, 8167.550784706779, 8294.502329860106, 4898.975505122452, 4987.925181122286, 5002.374703162105, 5131.435542090367, 5223.921641175385, 2018.0435661755598, 2056.331692200041, 2093.706881036656, 2111.968320475194, 2144.5389241312882, 1044.529961517305, 1058.6506452870535, 1071.137734124022, 1076.7900259449489, 1082.718493191776, 19978.400647980583, 20165.899170504348, 20400.897995510226, 20708.482287942923, 21049.368518944455, 1003.8193125237527, 1007.6926537406162, 1021.5862575656871, 1046.016744260393, 1051.9053285204368, 10514.526846291936, 11343.716407089723, 11392.0, 11466.713332166595, 11561.42192890352, 468707.0, 473253.0, 475818.0, 477617.0, 479850.0, 93203.0, 93551.53224233972, 94450.52774736083, 95632.52183739036, 95845.0, 1044.5299615173203, 1055.37205362809, 1092.9617463388743, 1113.1373185780922, 1116.1405717597404, 17488.562785930455, 17678.82321176823, 18079.638407232043, 18177.454676359732, 18201.635967280847, 41149.0, 42491.36262955996, 43916.78041609899, 44742.328865066396, 44768.55231447644, 60333.39666603453, 60782.0, 61419.07871381843, 61699.383006171156, 63065.369346305946, 9106.908930910606, 9223.538823058687, 9232.353735238468, 9267.490288033952, 9366.718998430058, 13208.867911320764, 13800.792988104986, 14018.509352173032, 14249.287535623385, 14406.783898241325, 11629.825552616549, 11864.169508134348, 11924.76150477003, 12334.629961101182, 12383.0, 11605.6518304451, 11680.06559475257, 11723.062155027406, 11737.0, 11935.34355610415, 12037.217580857216, 12422.689432764253, 12625.494980201056, 13026.153300035467, 13124.803127953279, 9171.128742769426, 9184.540772961458, 9202.49386283734, 9218.26253899673, 9502.904970950478, 9925.255605829736, 11831.526738930346, 11831.704207394883, 11930.821037684615, 11944.163908526667], "value": 9489.763957146917, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1401.3413695563095, 1517.6661134550395, 1552.487679065908, 1688.805787334457, 1784.7858257009152, 7918.881216781753, 8861.335399845013, 9128.497932613704, 9135.0, 9353.953230233856, 9134.132257435533, 9170.541472926343, 9217.262618990482, 9219.308551858603, 9235.538223088852, 2475.3811547113223, 2493.551160791057, 2495.6506089147533, 2495.8128140389454, 2497.5629264878667, 2388.5461762265113, 2501.1996161228412, 2505.1983365323103, 2506.786923111538, 2516.3834860459124, 2480.7891329237036, 2498.2755001049763, 2532.15172917073, 2534.809889258304, 2538.746125387465, 2434.6469761884514, 2440.9511809763794, 2443.328084776692, 2484.2795589279085, 2506.8997240110375, 2400.327908185711, 2438.6463962725484, 2443.767842055002, 2444.523317953, 2471.7281099079087, 8876.778080547974, 8968.192862642329, 9045.457272563668, 9052.502112383816, 9109.089091090904, 5319.9734001330135, 5465.59008074396, 5513.365962914276, 5520.558355331564, 5528.06022976095, 3507.8421471033867, 3527.841247143859, 3565.4830049642915, 3612.6026137124895, 3628.1111127773866, 1114.331401159306, 1125.3866642679677, 1136.0854512117742, 1146.776378606172, 1162.8488296521482, 12562.68593285166, 12940.676483087904, 12996.28520431376, 13028.80456793149, 13033.739325213537, 4789.784459699322, 4811.446683631357, 4983.0, 4985.0, 5103.744812759347, 11076.0, 11081.612143575097, 11081.944590277159, 11086.722831929104, 11138.0, 5524.779008839681, 5529.087700529448, 5540.501354878081, 5568.331800184003, 5568.470995255444, 4421.29259318514, 4542.432195975503, 4968.080905032581, 5045.646804723634, 5049.0, 4294.227039132986, 4313.0, 4420.756858372756, 4452.198604251266, 4456.442944631921, 1212.3271584270815, 1276.9744605107846, 1277.757226127033, 1289.896808255356, 1308.6205000549924, 928.2620316848122, 1021.3310281765456, 1030.057497389895, 1034.5292891734243, 1047.2145890582065, 6064.36324185963, 6103.786367477117, 6142.04798256271, 6190.50475961922, 6207.844803879939, 458.48191543555566, 458.84857996860956, 460.8939943812942, 462.08276571006525, 465.83230037186587, 4363.36731173978, 4365.105153443536, 4378.627816635558, 4383.452068491439, 4384.868453946387, 11308.264962777394, 11322.0, 11406.600768973047, 11509.482073306554, 11567.710807229718, 11309.660710178709, 11480.196386253048, 11514.0, 11515.942420287845, 11558.30650160993, 8665.523396213268, 8742.475451472805, 8780.639000954861, 8798.560071996375, 8809.295256379603, 6348.174737284124, 6359.713812878339, 6400.487960963205, 6403.743850246125, 6502.902456463252, 10756.784864302826, 10776.67669969902, 10780.083692886192, 10804.891951080388, 10919.945400272947, 10558.208134389797, 10821.458927053773, 10835.729106772395, 10914.508847102059, 10937.671869843918, 7496.100467943664, 7663.655135519027, 8226.218509241613, 8378.49729016261, 8508.149185081442, 9103.772405689911, 9155.587998539948, 9169.633214671605, 9179.127982841619, 9189.264858811146, 8028.718994835152, 8043.597820109088, 8091.0, 8093.271605555528, 8212.384071194565, 8023.077346105272, 8072.152423995396, 8092.5549094798, 8112.959435202786, 8235.094139644754, 7221.133463984355, 7274.527155734861, 7293.562386256842, 7306.232845551141, 7526.774196774106, 5556.305461817273, 5585.273914391103, 5749.367569567261, 5770.249867517364, 5771.797987070432, 5515.4760297771645, 5539.113741801292, 5561.304836895388, 5612.130119831432, 5622.634528755618], "value": 4789.930320025333, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [336.40288487933924, 338.12088569718736, 341.07228338918134, 344.7242206235013, 349.43915016398677, 5365.570754339653, 5534.0, 5589.7205139743, 5660.320761508615, 5683.460071293226, 5358.705271210082, 5478.863243335102, 5499.7250137493065, 5511.448855114487, 5527.5301599364, 4341.522432532419, 4344.436003038919, 4348.652107831366, 4357.172137293904, 4358.302671572549, 4114.798582758225, 4323.956760432403, 4348.869533913988, 4372.519022907478, 4377.474703035624, 4261.531231564541, 4293.828246870122, 4298.204832106055, 4301.462317210348, 4314.180305741915, 4316.525182229952, 4326.545712700153, 4328.588784065509, 4356.607905288539, 4376.912461750779, 3670.6696397324104, 3788.6400791924725, 3790.564085130218, 3798.9430158547643, 3801.2777572261184, 5427.728613569345, 5438.320209973754, 5440.6735595863975, 5443.59173062018, 5467.835964921019, 4989.101961646902, 5002.774875130628, 5008.924866127011, 5025.572826309768, 5054.519820617035, 5679.687617181054, 5810.3899090595305, 5820.476157145878, 5852.0, 5876.353601103875, 4141.337386018223, 4154.335306350969, 4162.979185104085, 4186.246475634384, 4213.494380674308, 5846.707664616794, 6122.785702500391, 6139.539534534883, 6170.8765824683705, 6171.352008039136, 4320.157569273962, 4321.049369138785, 4333.285007973649, 4347.434833471629, 4357.542458041923, 4383.539728328544, 4383.780810959439, 4398.47218333802, 4400.9559904401185, 4405.361222622766, 4987.600991920638, 5001.124803159432, 5016.072026675077, 5022.598192144693, 5031.52200540948, 5486.506214440719, 5605.299337582802, 5813.76744930198, 5874.353821079678, 5889.4993925515955, 1423.459085547506, 1456.1627064437992, 1483.4659522571865, 1500.1524138861726, 1500.6623509710446, 972.2173650211647, 990.7968866382374, 1004.7789486313, 1015.4871789746131, 1021.341234903483, 5571.387147413781, 5581.525570326487, 5600.719964001783, 5632.633878797865, 5660.830175094754, 5915.515483496855, 5915.550690080958, 5932.93207222704, 5945.137954996501, 5948.464638182498, 5429.56563474921, 5660.716964151776, 5676.779492409152, 5730.215270249389, 5791.623544469596, 3208.6630903755236, 3210.0, 3240.141362538929, 3241.5850191606933, 3250.626177989514, 6107.84730381744, 6219.937800622027, 6222.102258811106, 6241.625502469867, 6256.560990972011, 6097.512199024068, 6107.633541987495, 6115.816525504242, 6146.815595532141, 6151.477124444383, 6165.753369865245, 6167.506599472032, 6179.443850053517, 6217.626942383471, 6258.624482531062, 5880.794437140462, 5988.790392336247, 6022.590963614589, 6030.819075427744, 6074.544409169242, 5922.822315330547, 5935.0, 5947.256592925884, 5948.817382700235, 5951.285845698544, 5972.790952316648, 6181.96909015452, 6218.595791273553, 6236.532260080421, 6265.587748716685, 4252.277112890754, 4275.850345237902, 4292.484901811803, 4320.589543993316, 4340.370646256339, 5797.101449275369, 5914.852128696817, 6109.2974307955155, 6121.234845644294, 6138.0, 5711.8857622848145, 5794.1308803679785, 5816.272965879265, 5817.331006934257, 5831.79588714393, 5806.1000544915605, 5823.767049317979, 5824.330202026652, 5830.096335068071, 5877.617954832922, 5651.180578815966, 5711.457411545896, 5750.30996280449, 5775.729339545293, 5816.563757718103, 5846.433675457883, 5849.590528662953, 5863.625231781136, 5874.12168037667, 5877.794277200277, 5575.6933368663495, 5701.401352858056, 5735.7418916148035, 5759.366469688247, 5789.160571716993], "value": 4459.686138933313, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [762.4357975098426, 767.0565204798098, 792.7899106736712, 878.9648414063438, 932.8554074118512, 15478.0, 15481.84518154818, 16854.831451685463, 16927.74608380874, 18132.18405171767, 16522.75215871763, 16786.74819877703, 16795.32818687251, 16811.32754689814, 16831.242594083265, 862.1335557764438, 865.8528050231445, 866.1554983890687, 866.2809867809734, 866.514751739025, 725.5646612032791, 734.5629350536423, 867.5315329721942, 886.1404437695458, 895.0154829687357, 887.414306557672, 887.8579427291635, 890.8574628059512, 891.3537685178227, 891.7547674389563, 878.1174919206189, 880.1242763450408, 883.0286684646869, 883.2183517586917, 888.3692578269437, 552.5607142321863, 564.661203278034, 661.7617657643244, 678.8845896197658, 687.8589889072778, 16801.579960500963, 16833.91583042089, 16837.0, 16847.83152168487, 16857.494275171764, 14774.704505909822, 14836.406543738238, 14908.850911490963, 15390.230488475643, 15506.844931550653, 12881.433731813926, 13077.869221307761, 13283.269420181887, 13303.866961330456, 13416.798748018788, 4690.437147542317, 4747.5105797239, 4803.255495398219, 4820.301056346845, 4922.926156107661, 86683.0, 88523.11476885213, 88599.11400885973, 88650.11349886483, 89019.10980890173, 10899.128069754559, 11086.889131108746, 11150.330980141218, 11253.493592788343, 11377.772444551063, 23224.535509290057, 23240.53518929612, 23269.185578504672, 23309.41726456852, 23438.531229375316, 8625.568721563897, 8859.822803543893, 8890.244329231959, 8902.109789021175, 9050.728478145666, 5038.686731590369, 5123.410807757081, 5183.740812959337, 5210.374755029421, 5285.682859028394, 3601.873934412283, 3731.458938453909, 3735.0662334416397, 3736.0, 3736.906577335534, 637.6461064109371, 649.6329573790794, 655.2137435077925, 658.0425480925243, 658.8616390558036, 659.9901001484982, 698.2214830463996, 761.5202422473806, 766.2796970847497, 769.0387016229713, 9327.0, 9348.672796452227, 9600.807983840425, 10071.34536255141, 10268.691939241835, 649.7920665387126, 722.212788061016, 725.7895210389033, 750.7484992527455, 755.5995322479095, 4597.126545956325, 4780.306855505932, 4816.8073277069225, 4881.780319885614, 4907.68100073494, 127615.36192318979, 131383.0, 131703.0, 134965.0, 142043.2897835525, 111941.0, 117894.4105279468, 118365.4081729603, 119237.0, 121706.3914680421, 97468.0, 97904.51047744715, 99000.0, 101731.49134254281, 103931.4803425978, 8921.286297096087, 9202.35583509148, 9253.67612133572, 9275.301499755053, 9499.47752873571, 54917.0, 57244.0, 57950.71024645019, 59550.10674839794, 59650.0, 80511.59744201241, 86569.56715216384, 87019.56490217762, 88141.55929220314, 93648.0, 12016.699582510508, 12472.251664899772, 12998.935005324913, 13143.671408214488, 13346.532871349456, 13485.325733713471, 13670.453181872614, 14419.206943618201, 14695.853041469876, 14765.630859228606, 11358.318500889973, 12792.936035320135, 12829.550965716155, 12944.417501212656, 13080.607581772561, 11123.610673626708, 13826.377812998238, 14098.647533811736, 14216.928915355356, 15633.452829150925, 14322.14067155974, 14451.85548144505, 14484.855151448772, 14504.0, 15139.091654500766, 12331.383430828602, 12526.0, 12731.363431828557, 13258.469661213829, 13264.204147751167, 11784.057275417774, 11851.288922664668, 12130.0, 12191.939040304742, 12274.4476498556], "value": 8060.524935111126, "unit": "score"}], "name": "dromaeo_css", "value": 7647.091406341645, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 112.0, 112.0, 112.0, 113.0, 114.0, 113.0, 112.0, 111.0, 111.0], "value": 112.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 116.0, 116.0, 143.0, 119.0, 121.0, 116.0, 118.0, 149.0, 116.0], "value": 125.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 160.0, 160.0, 159.0, 167.0, 158.0, 159.0, 162.0, 161.0, 169.0], "value": 161.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 112.0, 75.0, 75.0, 76.0, 74.0, 99.0, 76.0], "value": 81.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 104.0, 82.0, 81.0, 82.0, 102.0, 80.0, 81.0, 106.0], "value": 88.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 116.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 147.0, 147.0, 146.0, 147.0, 146.0, 146.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 96.0, 95.0, 95.0, 96.0, 95.0, 96.0, 94.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 49.0, 50.0, 49.0, 49.0, 48.0, 51.0, 50.0, 48.0, 49.0], "value": 49.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 61.0, 60.0, 59.0, 59.0, 59.0, 59.0, 59.0, 60.0], "value": 59.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 75.0, 74.0, 73.0, 75.0, 80.0, 72.0, 73.0, 76.0, 73.0], "value": 74.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 117.0, 127.0, 120.0, 118.0, 117.0, 135.0, 124.0, 162.0, 120.0], "value": 126.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 154.0, 155.0, 152.0, 155.0, 154.0, 150.0, 153.0, 157.0, 155.0], "value": 153.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 62.0, 61.0, 61.0, 61.0, 62.0, 61.0, 61.0, 60.0], "value": 61.3, "unit": "score"}], "name": "kraken", "value": 1451.0000000000002, "alertThreshold": 2.0}]} 19:53:43 INFO - Return code: 0 19:53:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:53:43 INFO - # TBPL SUCCESS # 19:53:43 INFO - Running post-action listener: _resource_record_post_action 19:53:43 INFO - [mozharness: 2016-09-23 02:53:43.383791Z] Finished run-tests step (success) 19:53:43 INFO - Running post-run listener: _resource_record_post_run 19:53:43 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 7876608; Write bytes: 377028608; Read time: 1124; Write time: 351060 19:53:43 INFO - TinderboxPrint: CPU usage
12.9% 19:53:43 INFO - TinderboxPrint: I/O read bytes / time
7,876,608 / 1,124 19:53:43 INFO - TinderboxPrint: I/O write bytes / time
377,028,608 / 351,060 19:53:43 INFO - TinderboxPrint: CPU idle
8,275.3 (87.0%) 19:53:43 INFO - TinderboxPrint: CPU user
1,185.2 (12.5%) 19:53:43 INFO - TinderboxPrint: Swap in / out
0 / 0 19:53:43 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 1269760; Read time: 0; Write time: 64 19:53:43 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 5672960; Write bytes: 375758848; Read time: 988; Write time: 350996 19:53:44 INFO - Running post-run listener: _upload_blobber_files 19:53:44 INFO - Blob upload gear active. 19:53:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:53:44 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 19:53:44 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'] 19:53:44 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 19:53:44 INFO - (blobuploader) - INFO - Open directory for files ... 19:53:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:53:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:53:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:53:48 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:53:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:53:48 INFO - (blobuploader) - INFO - Done attempting. 19:53:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:53:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:53:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:53:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:53:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:53:49 INFO - (blobuploader) - INFO - Done attempting. 19:53:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:53:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:53:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:53:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:53:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:53:50 INFO - (blobuploader) - INFO - Done attempting. 19:53:50 INFO - (blobuploader) - INFO - Iteration through files over. 19:53:50 INFO - Return code: 0 19:53:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:53:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:53:50 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1fc5540e90eaaf40afb99d75f370121d0935810abbf472cee648d5b7c8913f9a3b04dc68a38cbfa01ba2cd0dd39c41d821f4b2445a7397c11edefd2371d48393", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/edf0074489d9563ae016102c12301a81584d212f184fe9efb2ef69950201bc62d9ab48a520c302b039e053d37bf29d9d5bd129930316b26dbde23ff92e10cf32", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5531971d3ccd7fa0ca9bb029be0e558212ddc260519bd5ecc886cc9f5d9079e6711b9d8bc3653d92cd6f9e0533c1c056410c7d55e0943574cef64bfd36953005"} 19:53:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:53:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:53:50 INFO - Contents: 19:53:50 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1fc5540e90eaaf40afb99d75f370121d0935810abbf472cee648d5b7c8913f9a3b04dc68a38cbfa01ba2cd0dd39c41d821f4b2445a7397c11edefd2371d48393", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/edf0074489d9563ae016102c12301a81584d212f184fe9efb2ef69950201bc62d9ab48a520c302b039e053d37bf29d9d5bd129930316b26dbde23ff92e10cf32", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5531971d3ccd7fa0ca9bb029be0e558212ddc260519bd5ecc886cc9f5d9079e6711b9d8bc3653d92cd6f9e0533c1c056410c7d55e0943574cef64bfd36953005"} 19:53:50 INFO - Running post-run listener: copy_logs_to_upload_dir 19:53:50 INFO - Copying logs to upload dir... 19:53:50 INFO - mkdir: /builds/slave/test/build/upload/logs 19:53:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.530447 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-09-22 19:53:50.324067) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-22 19:53:50.329081) ========= 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=8887e36bb8e381bfd44124ac000001a8-1474595299.956446-1244825490 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/1fc5540e90eaaf40afb99d75f370121d0935810abbf472cee648d5b7c8913f9a3b04dc68a38cbfa01ba2cd0dd39c41d821f4b2445a7397c11edefd2371d48393", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/edf0074489d9563ae016102c12301a81584d212f184fe9efb2ef69950201bc62d9ab48a520c302b039e053d37bf29d9d5bd129930316b26dbde23ff92e10cf32", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5531971d3ccd7fa0ca9bb029be0e558212ddc260519bd5ecc886cc9f5d9079e6711b9d8bc3653d92cd6f9e0533c1c056410c7d55e0943574cef64bfd36953005"} build_url:https://queue.taskcluster.net/v1/task/AqTHr-gGTA2MCgBce9s_sw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011239 build_url: 'https://queue.taskcluster.net/v1/task/AqTHr-gGTA2MCgBce9s_sw/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/1fc5540e90eaaf40afb99d75f370121d0935810abbf472cee648d5b7c8913f9a3b04dc68a38cbfa01ba2cd0dd39c41d821f4b2445a7397c11edefd2371d48393", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/edf0074489d9563ae016102c12301a81584d212f184fe9efb2ef69950201bc62d9ab48a520c302b039e053d37bf29d9d5bd129930316b26dbde23ff92e10cf32", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5531971d3ccd7fa0ca9bb029be0e558212ddc260519bd5ecc886cc9f5d9079e6711b9d8bc3653d92cd6f9e0533c1c056410c7d55e0943574cef64bfd36953005"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-22 19:53:50.357718) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-22 19:53:50.358065) ========= 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=8887e36bb8e381bfd44124ac000001a8-1474595299.956446-1244825490 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004429 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-22 19:53:50.439100) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-22 19:53:50.439451) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-22 19:53:50.439775) ========= ========= Total master_lag: 0.20 =========