builder: autoland_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-001 starttime: 1474369524.76 results: success (0) buildid: 20160920003004 builduid: c5a7811869e64634ae31c65d0b53b411 revision: 9a5ccc0e72701ee48a3ad5d172db195210d1fb37 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-20 04:05:24.756508) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-20 04:05:24.757015) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-20 04:05:24.757333) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-20 04:05:24.865354) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 04:05:24.865824) ========= 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-1474369523.661552-1077558210 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-20 04:05:25-- 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% 46.3M=0s 2016-09-20 04:05:25 (46.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.070965 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 04:05:24.952871) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 04:05:24.953228) ========= 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-1474369523.661552-1077558210 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016458 ========= master_lag: 0.09 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 04:05:25.064347) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 04:05:25.064750) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 9a5ccc0e72701ee48a3ad5d172db195210d1fb37 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 9a5ccc0e72701ee48a3ad5d172db195210d1fb37 --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-1474369523.661552-1077558210 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-20 04:05:25,264 truncating revision to first 12 chars 2016-09-20 04:05:25,264 Setting DEBUG logging. 2016-09-20 04:05:25,264 attempt 1/10 2016-09-20 04:05:25,264 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/9a5ccc0e7270?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-20 04:05:25,586 unpacking tar archive at: autoland-9a5ccc0e7270/testing/mozharness/ program finished with exit code 0 elapsedTime=0.662786 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 04:05:25.744741) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-20 04:05:25.745043) ========= script_repo_revision: 9a5ccc0e72701ee48a3ad5d172db195210d1fb37 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-20 04:05:25.745470) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-20 04:05:25.745748) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-20 04:05:25.759603) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-09-20 04:05:25.759911) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 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', 'Autoland', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] 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-1474369523.661552-1077558210 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:05:26 INFO - MultiFileLogger online at 20160920 04:05:26 in /builds/slave/test 04:05:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 04:05:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:05:26 INFO - {'append_to_log': False, 04:05:26 INFO - 'base_work_dir': '/builds/slave/test', 04:05:26 INFO - 'blob_upload_branch': 'Autoland', 04:05:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:05:26 INFO - 'branch': 'Autoland', 04:05:26 INFO - 'buildbot_json_path': 'buildprops.json', 04:05:26 INFO - 'config_files': ('talos/linux_config.py',), 04:05:26 INFO - 'default_actions': ('clobber', 04:05:26 INFO - 'read-buildbot-config', 04:05:26 INFO - 'download-and-extract', 04:05:26 INFO - 'populate-webroot', 04:05:26 INFO - 'create-virtualenv', 04:05:26 INFO - 'install', 04:05:26 INFO - 'run-tests'), 04:05:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:05:26 INFO - 'download_minidump_stackwalk': True, 04:05:26 INFO - 'download_symbols': 'ondemand', 04:05:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:05:26 INFO - 'tooltool.py': '/tools/tooltool.py', 04:05:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:05:26 INFO - '/tools/misc-python/virtualenv.py')}, 04:05:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:05:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:05:26 INFO - 'installer_path': 'installer.exe', 04:05:26 INFO - 'log_level': 'info', 04:05:26 INFO - 'log_name': 'talos', 04:05:26 INFO - 'log_to_console': True, 04:05:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:05:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:05:26 INFO - 'opt_config_files': (), 04:05:26 INFO - 'pip_index': False, 04:05:26 INFO - 'sps_profile': False, 04:05:26 INFO - 'sps_profile_interval': 0, 04:05:26 INFO - 'suite': 'dromaeojs', 04:05:26 INFO - 'system_bits': '32', 04:05:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:05:26 INFO - 'title': 'talos-linux64-ix-001', 04:05:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:05:26 INFO - 'use_talos_json': True, 04:05:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:05:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:05:26 INFO - 'work_dir': 'build'} 04:05:26 INFO - [mozharness: 2016-09-20 11:05:26.047900Z] Running clobber step. 04:05:26 INFO - Running pre-action listener: _resource_record_pre_action 04:05:26 INFO - Running main action method: clobber 04:05:26 INFO - rmtree: /builds/slave/test/build 04:05:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:05:27 INFO - Running post-action listener: _resource_record_post_action 04:05:27 INFO - [mozharness: 2016-09-20 11:05:27.026534Z] Finished clobber step (success) 04:05:27 INFO - [mozharness: 2016-09-20 11:05:27.026635Z] Running read-buildbot-config step. 04:05:27 INFO - Running pre-action listener: _resource_record_pre_action 04:05:27 INFO - Running main action method: read_buildbot_config 04:05:27 INFO - Using buildbot properties: 04:05:27 INFO - { 04:05:27 INFO - "project": "", 04:05:27 INFO - "product": "firefox", 04:05:27 INFO - "script_repo_revision": "production", 04:05:27 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 04:05:27 INFO - "repository": "", 04:05:27 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland pgo talos dromaeojs", 04:05:27 INFO - "buildid": "20160920003004", 04:05:27 INFO - "pgo_build": "True", 04:05:27 INFO - "basedir": "/builds/slave/test", 04:05:27 INFO - "buildnumber": 221, 04:05:27 INFO - "slavename": "talos-linux64-ix-001", 04:05:27 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 04:05:27 INFO - "platform": "ubuntu64_hw", 04:05:27 INFO - "scheduler": "tests-autoland-linux64-pgo-talos", 04:05:27 INFO - "branch": "autoland", 04:05:27 INFO - "repo_path": "integration/autoland", 04:05:27 INFO - "revision": "9a5ccc0e72701ee48a3ad5d172db195210d1fb37", 04:05:27 INFO - "stage_platform": "linux64-pgo", 04:05:27 INFO - "builduid": "c5a7811869e64634ae31c65d0b53b411", 04:05:27 INFO - "slavebuilddir": "test" 04:05:27 INFO - } 04:05:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/HqXZKlw1SL2sHTXeLp0DBA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2. 04:05:27 INFO - Running post-action listener: _resource_record_post_action 04:05:27 INFO - [mozharness: 2016-09-20 11:05:27.029069Z] Finished read-buildbot-config step (success) 04:05:27 INFO - [mozharness: 2016-09-20 11:05:27.029160Z] Running download-and-extract step. 04:05:27 INFO - Running pre-action listener: _resource_record_pre_action 04:05:27 INFO - Running main action method: download_and_extract 04:05:27 INFO - mkdir: /builds/slave/test/build/tests 04:05:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:05:27 INFO - trying https://queue.taskcluster.net/v1/task/HqXZKlw1SL2sHTXeLp0DBA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 04:05:27 INFO - Downloading https://queue.taskcluster.net/v1/task/HqXZKlw1SL2sHTXeLp0DBA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 04:05:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HqXZKlw1SL2sHTXeLp0DBA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 04:05:27 INFO - Downloaded 1361 bytes. 04:05:27 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 04:05:27 INFO - Using the following test package requirements: 04:05:27 INFO - {u'common': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 04:05:27 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 04:05:27 INFO - u'firefox-52.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 04:05:27 INFO - u'gtest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 04:05:27 INFO - u'firefox-52.0a1.en-US.linux-x86_64.gtest.tests.zip'], 04:05:27 INFO - u'jittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 04:05:27 INFO - u'jsshell-linux-x86_64.zip'], 04:05:27 INFO - u'mochitest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 04:05:27 INFO - u'firefox-52.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 04:05:27 INFO - u'mozbase': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 04:05:27 INFO - u'reftest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 04:05:27 INFO - u'firefox-52.0a1.en-US.linux-x86_64.reftest.tests.zip'], 04:05:27 INFO - u'talos': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 04:05:27 INFO - u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'], 04:05:27 INFO - u'web-platform': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 04:05:27 INFO - u'firefox-52.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 04:05:27 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 04:05:27 INFO - u'firefox-52.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 04:05:27 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 04:05:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HqXZKlw1SL2sHTXeLp0DBA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 04:05:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HqXZKlw1SL2sHTXeLp0DBA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:05:27 INFO - Fetch https://queue.taskcluster.net/v1/task/HqXZKlw1SL2sHTXeLp0DBA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 04:05:28 INFO - Expected file size: 21940970 04:05:32 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 04:05:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HqXZKlw1SL2sHTXeLp0DBA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 04:05:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HqXZKlw1SL2sHTXeLp0DBA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:05:32 INFO - Fetch https://queue.taskcluster.net/v1/task/HqXZKlw1SL2sHTXeLp0DBA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 04:05:33 INFO - Expected file size: 21940970 04:05:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HqXZKlw1SL2sHTXeLp0DBA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip 04:05:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HqXZKlw1SL2sHTXeLp0DBA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 04:05:38 INFO - Fetch https://queue.taskcluster.net/v1/task/HqXZKlw1SL2sHTXeLp0DBA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip into memory 04:05:38 INFO - Expected file size: 14414059 04:05:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:05:39 INFO - trying https://queue.taskcluster.net/v1/task/HqXZKlw1SL2sHTXeLp0DBA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 04:05:39 INFO - Downloading https://queue.taskcluster.net/v1/task/HqXZKlw1SL2sHTXeLp0DBA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 04:05:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HqXZKlw1SL2sHTXeLp0DBA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 04:05:41 INFO - Downloaded 59546489 bytes. 04:05:41 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HqXZKlw1SL2sHTXeLp0DBA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 04:05:41 INFO - mkdir: /builds/slave/test/properties 04:05:41 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:05:41 INFO - Writing to file /builds/slave/test/properties/build_url 04:05:41 INFO - Contents: 04:05:41 INFO - build_url:https://queue.taskcluster.net/v1/task/HqXZKlw1SL2sHTXeLp0DBA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 04:05:42 INFO - Running post-action listener: _resource_record_post_action 04:05:42 INFO - Running post-action listener: set_extra_try_arguments 04:05:42 INFO - [mozharness: 2016-09-20 11:05:42.118950Z] Finished download-and-extract step (success) 04:05:42 INFO - [mozharness: 2016-09-20 11:05:42.119179Z] Running populate-webroot step. 04:05:42 INFO - Running pre-action listener: _resource_record_pre_action 04:05:42 INFO - Running main action method: populate_webroot 04:05:42 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:05:42 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:05:42 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:05:42 INFO - '--tppagecycles', 04:05:42 INFO - '7'], 04:05:42 INFO - 'tests': ['tsvgm']}}, 04:05:42 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:05:42 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:05:42 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:05:42 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:05:42 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:05:42 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:05:42 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:05:42 INFO - 'talos_options': ['--disable-e10s'], 04:05:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:05:42 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:05:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:05:42 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:05:42 INFO - 'talos_options': ['--disable-e10s'], 04:05:42 INFO - 'tests': ['damp', 'tps']}, 04:05:42 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:05:42 INFO - 'tests': ['damp', 'tps']}, 04:05:42 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:05:42 INFO - 'tests': ['dromaeo_dom']}, 04:05:42 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:05:42 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:05:42 INFO - 'tests': ['basic_compositor_video']}, 04:05:42 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:05:42 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:05:42 INFO - 'tests': ['a11yr', 04:05:42 INFO - 'ts_paint', 04:05:42 INFO - 'tpaint', 04:05:42 INFO - 'sessionrestore', 04:05:42 INFO - 'sessionrestore_no_auto_restore', 04:05:42 INFO - 'tabpaint']}, 04:05:42 INFO - 'other-e10s': {'tests': ['a11yr', 04:05:42 INFO - 'ts_paint', 04:05:42 INFO - 'tpaint', 04:05:42 INFO - 'sessionrestore', 04:05:42 INFO - 'sessionrestore_no_auto_restore', 04:05:42 INFO - 'tabpaint']}, 04:05:42 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:05:42 INFO - 'ts_paint', 04:05:42 INFO - 'tpaint', 04:05:42 INFO - 'sessionrestore', 04:05:42 INFO - 'sessionrestore_no_auto_restore', 04:05:42 INFO - 'tabpaint']}, 04:05:42 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:05:42 INFO - 'ts_paint', 04:05:42 INFO - 'tpaint', 04:05:42 INFO - 'sessionrestore', 04:05:42 INFO - 'sessionrestore_no_auto_restore', 04:05:42 INFO - 'tabpaint']}, 04:05:42 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:05:42 INFO - 'tests': ['a11yr', 04:05:42 INFO - 'ts_paint', 04:05:42 INFO - 'tpaint', 04:05:42 INFO - 'sessionrestore', 04:05:42 INFO - 'sessionrestore_no_auto_restore', 04:05:42 INFO - 'tabpaint']}, 04:05:42 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:05:42 INFO - 'tests': ['a11yr', 04:05:42 INFO - 'ts_paint', 04:05:42 INFO - 'tpaint', 04:05:42 INFO - 'sessionrestore', 04:05:42 INFO - 'sessionrestore_no_auto_restore', 04:05:42 INFO - 'tabpaint']}, 04:05:42 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:05:42 INFO - 'tests': ['tsvgx', 04:05:42 INFO - 'tsvgr_opacity', 04:05:42 INFO - 'tart', 04:05:42 INFO - 'tscrollx', 04:05:42 INFO - 'cart']}, 04:05:42 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:05:42 INFO - 'tsvgr_opacity', 04:05:42 INFO - 'tart', 04:05:42 INFO - 'tscrollx', 04:05:42 INFO - 'cart']}, 04:05:42 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:05:42 INFO - 'talos_options': ['--disable-e10s'], 04:05:42 INFO - 'tests': ['tp5o']}, 04:05:42 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:05:42 INFO - 'tests': ['tp5o']}, 04:05:42 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:05:42 INFO - 'talos_options': ['--disable-e10s', 04:05:42 INFO - '--xperf_path', 04:05:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:05:42 INFO - 'tests': ['tp5n']}, 04:05:42 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:05:42 INFO - 'talos_options': ['--xperf_path', 04:05:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:05:42 INFO - 'tests': ['tp5n']}}, 04:05:42 INFO - 'talos.zip': {'path': '', 04:05:42 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:05:42 INFO - Running post-action listener: _resource_record_post_action 04:05:42 INFO - [mozharness: 2016-09-20 11:05:42.136610Z] Finished populate-webroot step (success) 04:05:42 INFO - [mozharness: 2016-09-20 11:05:42.136700Z] Running create-virtualenv step. 04:05:42 INFO - Running pre-action listener: _resource_record_pre_action 04:05:42 INFO - Running main action method: create_virtualenv 04:05:42 INFO - Creating virtualenv /builds/slave/test/build/venv 04:05:42 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:05:42 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:05:42 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:05:42 INFO - Using real prefix '/usr' 04:05:42 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:05:42 INFO - Installing distribute.............................................................................................................................................................................................done. 04:05:44 INFO - Installing pip.................done. 04:05:44 INFO - Return code: 0 04:05:44 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:05:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:05:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:05:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:05:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:05:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:05:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:05:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f49ff87bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf768a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1043790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040930>, '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 0x1041830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1041cc0>, '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-1474369523.661552-1077558210', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:05:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:05:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:05:44 INFO - Using env: {'DISPLAY': ':0', 04:05:44 INFO - 'HOME': '/home/cltbld', 04:05:44 INFO - 'LANG': 'en_US.UTF-8', 04:05:44 INFO - 'LANGUAGE': 'en_US:en', 04:05:44 INFO - 'LOGNAME': 'cltbld', 04:05:44 INFO - 'MAIL': '/var/mail/cltbld', 04:05:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:05:44 INFO - 'MOZ_NO_REMOTE': '1', 04:05:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:05:44 INFO - 'NO_EM_RESTART': '1', 04:05:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:05:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:05:44 INFO - 'PWD': '/builds/slave/test', 04:05:44 INFO - 'SHELL': '/bin/bash', 04:05:44 INFO - 'SHLVL': '1', 04:05:44 INFO - 'TERM': 'linux', 04:05:44 INFO - 'TMOUT': '86400', 04:05:44 INFO - 'USER': 'cltbld', 04:05:44 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1474369523.661552-1077558210', 04:05:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:05:44 INFO - '_': '/tools/buildbot/bin/python'} 04:05:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:05:44 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 04:05:44 INFO - Cleaning up... 04:05:44 INFO - Return code: 0 04:05:44 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:05:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:05:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:05:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:05:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:05:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:05:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:05:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f49ff87bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf768a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1043790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040930>, '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 0x1041830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1041cc0>, '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-1474369523.661552-1077558210', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:05:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:05:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:05:44 INFO - Using env: {'DISPLAY': ':0', 04:05:44 INFO - 'HOME': '/home/cltbld', 04:05:44 INFO - 'LANG': 'en_US.UTF-8', 04:05:44 INFO - 'LANGUAGE': 'en_US:en', 04:05:44 INFO - 'LOGNAME': 'cltbld', 04:05:44 INFO - 'MAIL': '/var/mail/cltbld', 04:05:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:05:44 INFO - 'MOZ_NO_REMOTE': '1', 04:05:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:05:44 INFO - 'NO_EM_RESTART': '1', 04:05:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:05:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:05:44 INFO - 'PWD': '/builds/slave/test', 04:05:44 INFO - 'SHELL': '/bin/bash', 04:05:44 INFO - 'SHLVL': '1', 04:05:44 INFO - 'TERM': 'linux', 04:05:44 INFO - 'TMOUT': '86400', 04:05:44 INFO - 'USER': 'cltbld', 04:05:44 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1474369523.661552-1077558210', 04:05:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:05:44 INFO - '_': '/tools/buildbot/bin/python'} 04:05:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:05:45 INFO - Downloading/unpacking psutil>=3.1.1 04:05:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:05:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:05:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:05:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:05:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:05:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:05:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:05:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:05:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:05:47 INFO - Installing collected packages: psutil 04:05:47 INFO - Running setup.py install for psutil 04:05:47 INFO - building 'psutil._psutil_linux' extension 04:05:47 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 04:05:47 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 04:05:47 INFO - building 'psutil._psutil_posix' extension 04:05:47 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 04:05:47 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 04:05:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:05:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:05:47 INFO - Successfully installed psutil 04:05:47 INFO - Cleaning up... 04:05:47 INFO - Return code: 0 04:05:47 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:05: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')]} 04:05:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:05:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:05: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')]} 04:05:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:05:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:05:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f49ff87bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf768a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1043790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040930>, '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 0x1041830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1041cc0>, '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-1474369523.661552-1077558210', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:05:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:05:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:05:47 INFO - Using env: {'DISPLAY': ':0', 04:05:47 INFO - 'HOME': '/home/cltbld', 04:05:47 INFO - 'LANG': 'en_US.UTF-8', 04:05:47 INFO - 'LANGUAGE': 'en_US:en', 04:05:47 INFO - 'LOGNAME': 'cltbld', 04:05:47 INFO - 'MAIL': '/var/mail/cltbld', 04:05:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:05:47 INFO - 'MOZ_NO_REMOTE': '1', 04:05:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:05:47 INFO - 'NO_EM_RESTART': '1', 04:05:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:05:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:05:47 INFO - 'PWD': '/builds/slave/test', 04:05:47 INFO - 'SHELL': '/bin/bash', 04:05:47 INFO - 'SHLVL': '1', 04:05:47 INFO - 'TERM': 'linux', 04:05:47 INFO - 'TMOUT': '86400', 04:05:47 INFO - 'USER': 'cltbld', 04:05:47 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1474369523.661552-1077558210', 04:05:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:05:47 INFO - '_': '/tools/buildbot/bin/python'} 04:05:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:05:47 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:05:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:05:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:05:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:05:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:05:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:05:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:05:49 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:05:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:05:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:05:49 INFO - Installing collected packages: mozsystemmonitor 04:05:49 INFO - Running setup.py install for mozsystemmonitor 04:05:49 INFO - Successfully installed mozsystemmonitor 04:05:49 INFO - Cleaning up... 04:05:49 INFO - Return code: 0 04:05:49 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:05:49 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')]} 04:05:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:05:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:05:49 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')]} 04:05:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:05:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:05:49 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 0x7f49ff87bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf768a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1043790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040930>, '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 0x1041830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1041cc0>, '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-1474369523.661552-1077558210', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:05:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:05:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:05:49 INFO - Using env: {'DISPLAY': ':0', 04:05:49 INFO - 'HOME': '/home/cltbld', 04:05:49 INFO - 'LANG': 'en_US.UTF-8', 04:05:49 INFO - 'LANGUAGE': 'en_US:en', 04:05:49 INFO - 'LOGNAME': 'cltbld', 04:05:49 INFO - 'MAIL': '/var/mail/cltbld', 04:05:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:05:49 INFO - 'MOZ_NO_REMOTE': '1', 04:05:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:05:49 INFO - 'NO_EM_RESTART': '1', 04:05:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:05:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:05:49 INFO - 'PWD': '/builds/slave/test', 04:05:49 INFO - 'SHELL': '/bin/bash', 04:05:49 INFO - 'SHLVL': '1', 04:05:49 INFO - 'TERM': 'linux', 04:05:49 INFO - 'TMOUT': '86400', 04:05:49 INFO - 'USER': 'cltbld', 04:05:49 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1474369523.661552-1077558210', 04:05:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:05:49 INFO - '_': '/tools/buildbot/bin/python'} 04:05:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:05:50 INFO - Downloading/unpacking jsonschema==2.5.1 04:05:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:05:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:05:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:05:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:05:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:05:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:05:52 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:05:52 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:05:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:05:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:05:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:05:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:05:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:05:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:05:52 INFO - Downloading functools32-3.2.3-2.tar.gz 04:05:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:05:52 INFO - warning: no files found matching '*.txt' 04:05:52 INFO - no previously-included directories found matching 'build' 04:05:52 INFO - no previously-included directories found matching 'dist' 04:05:52 INFO - no previously-included directories found matching '.git*' 04:05:52 INFO - Installing collected packages: jsonschema, functools32 04:05:52 INFO - Running setup.py install for functools32 04:05:52 INFO - warning: no files found matching '*.txt' 04:05:52 INFO - no previously-included directories found matching 'build' 04:05:52 INFO - no previously-included directories found matching 'dist' 04:05:52 INFO - no previously-included directories found matching '.git*' 04:05:52 INFO - Successfully installed jsonschema functools32 04:05:52 INFO - Cleaning up... 04:05:52 INFO - Return code: 0 04:05:52 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:05:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:05:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:05:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:05:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:05:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:05:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:05:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f49ff87bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf768a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1043790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040930>, '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 0x1041830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1041cc0>, '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-1474369523.661552-1077558210', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:05:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:05:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 04:05:52 INFO - Using env: {'DISPLAY': ':0', 04:05:52 INFO - 'HOME': '/home/cltbld', 04:05:52 INFO - 'LANG': 'en_US.UTF-8', 04:05:52 INFO - 'LANGUAGE': 'en_US:en', 04:05:52 INFO - 'LOGNAME': 'cltbld', 04:05:52 INFO - 'MAIL': '/var/mail/cltbld', 04:05:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:05:52 INFO - 'MOZ_NO_REMOTE': '1', 04:05:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:05:52 INFO - 'NO_EM_RESTART': '1', 04:05:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:05:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:05:52 INFO - 'PWD': '/builds/slave/test', 04:05:52 INFO - 'SHELL': '/bin/bash', 04:05:52 INFO - 'SHLVL': '1', 04:05:52 INFO - 'TERM': 'linux', 04:05:52 INFO - 'TMOUT': '86400', 04:05:52 INFO - 'USER': 'cltbld', 04:05:52 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1474369523.661552-1077558210', 04:05:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:05:52 INFO - '_': '/tools/buildbot/bin/python'} 04:05:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:05:52 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:05:52 INFO - Cleaning up... 04:05:52 INFO - Return code: 0 04:05:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:05:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:05:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:05:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:05:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:05:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:05:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:05:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f49ff87bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf768a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1043790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040930>, '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 0x1041830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1041cc0>, '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-1474369523.661552-1077558210', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:05:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:05:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:05:52 INFO - Using env: {'DISPLAY': ':0', 04:05:52 INFO - 'HOME': '/home/cltbld', 04:05:52 INFO - 'LANG': 'en_US.UTF-8', 04:05:52 INFO - 'LANGUAGE': 'en_US:en', 04:05:52 INFO - 'LOGNAME': 'cltbld', 04:05:52 INFO - 'MAIL': '/var/mail/cltbld', 04:05:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:05:52 INFO - 'MOZ_NO_REMOTE': '1', 04:05:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:05:52 INFO - 'NO_EM_RESTART': '1', 04:05:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:05:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:05:52 INFO - 'PWD': '/builds/slave/test', 04:05:52 INFO - 'SHELL': '/bin/bash', 04:05:52 INFO - 'SHLVL': '1', 04:05:52 INFO - 'TERM': 'linux', 04:05:52 INFO - 'TMOUT': '86400', 04:05:52 INFO - 'USER': 'cltbld', 04:05:52 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1474369523.661552-1077558210', 04:05:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:05:52 INFO - '_': '/tools/buildbot/bin/python'} 04:05:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:05:52 INFO - Downloading/unpacking blobuploader==1.2.4 04:05:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:05:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:05:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:05:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:05:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:05:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:05:54 INFO - Downloading blobuploader-1.2.4.tar.gz 04:05:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:05:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:05:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:05:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:05:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:05:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:05:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:05:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:05:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:05:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:05:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:05:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:05:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:05:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:05:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:05:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:05:55 INFO - Downloading docopt-0.6.1.tar.gz 04:05:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:05:55 INFO - Installing collected packages: blobuploader, requests, docopt 04:05:55 INFO - Running setup.py install for blobuploader 04:05:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:05:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:05:55 INFO - Running setup.py install for requests 04:05:56 INFO - Running setup.py install for docopt 04:05:56 INFO - Successfully installed blobuploader requests docopt 04:05:56 INFO - Cleaning up... 04:05:56 INFO - Return code: 0 04:05:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:05:56 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')]} 04:05:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:05:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:05:56 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')]} 04:05:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:05:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:05:56 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 0x7f49ff87bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf768a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1043790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040930>, '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 0x1041830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1041cc0>, '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-1474369523.661552-1077558210', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:05:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:05:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:05:56 INFO - Using env: {'DISPLAY': ':0', 04:05:56 INFO - 'HOME': '/home/cltbld', 04:05:56 INFO - 'LANG': 'en_US.UTF-8', 04:05:56 INFO - 'LANGUAGE': 'en_US:en', 04:05:56 INFO - 'LOGNAME': 'cltbld', 04:05:56 INFO - 'MAIL': '/var/mail/cltbld', 04:05:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:05:56 INFO - 'MOZ_NO_REMOTE': '1', 04:05:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:05:56 INFO - 'NO_EM_RESTART': '1', 04:05:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:05:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:05:56 INFO - 'PWD': '/builds/slave/test', 04:05:56 INFO - 'SHELL': '/bin/bash', 04:05:56 INFO - 'SHLVL': '1', 04:05:56 INFO - 'TERM': 'linux', 04:05:56 INFO - 'TMOUT': '86400', 04:05:56 INFO - 'USER': 'cltbld', 04:05:56 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1474369523.661552-1077558210', 04:05:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:05:56 INFO - '_': '/tools/buildbot/bin/python'} 04:05:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:05:56 INFO - Running setup.py (path:/tmp/pip-mYQPni-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:05:56 INFO - Running setup.py (path:/tmp/pip-qlAMa8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:05:56 INFO - Running setup.py (path:/tmp/pip-4wpUPf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:05:56 INFO - Running setup.py (path:/tmp/pip-gp054E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:05:56 INFO - Running setup.py (path:/tmp/pip-XBEcMP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:05:56 INFO - Running setup.py (path:/tmp/pip-TRMPmL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:05:56 INFO - Running setup.py (path:/tmp/pip-srRhbm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:05:56 INFO - Running setup.py (path:/tmp/pip-DA0Ncm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:05:56 INFO - Running setup.py (path:/tmp/pip-SZWB3m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:05:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:05:57 INFO - Running setup.py (path:/tmp/pip-4a7JwQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:05:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:05:57 INFO - Running setup.py (path:/tmp/pip-hfTx9o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:05:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:05:57 INFO - Running setup.py (path:/tmp/pip-0AZhUy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:05:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:05:57 INFO - Running setup.py (path:/tmp/pip-CuIC3a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:05:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:05:57 INFO - Running setup.py (path:/tmp/pip-0QdcfT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:05:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:05:57 INFO - Running setup.py (path:/tmp/pip-vozssM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:05:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:05:57 INFO - Running setup.py (path:/tmp/pip-WjX7WI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:05:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:05:57 INFO - Running setup.py (path:/tmp/pip-uwq0Z6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:05:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:05:57 INFO - Running setup.py install for manifestparser 04:05:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:05:57 INFO - Running setup.py install for mozcrash 04:05:57 INFO - Running setup.py install for mozdebug 04:05:57 INFO - Running setup.py install for mozdevice 04:05:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:05:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:05:58 INFO - Running setup.py install for mozfile 04:05:58 INFO - Running setup.py install for mozhttpd 04:05:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:05:58 INFO - Running setup.py install for mozinfo 04:05:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:05:58 INFO - Running setup.py install for mozInstall 04:05:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:05:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:05:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:05:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:05:58 INFO - Running setup.py install for mozleak 04:05:58 INFO - Running setup.py install for mozlog 04:05:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:05:58 INFO - Running setup.py install for moznetwork 04:05:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:05:58 INFO - Running setup.py install for mozprocess 04:05:58 INFO - Running setup.py install for mozprofile 04:05:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:05:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:05:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:05:58 INFO - Running setup.py install for mozrunner 04:05:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:05:59 INFO - Running setup.py install for mozscreenshot 04:05:59 INFO - Running setup.py install for moztest 04:05:59 INFO - Running setup.py install for mozversion 04:05:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:05:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:05:59 INFO - Cleaning up... 04:05:59 INFO - Return code: 0 04:05:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:05:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:05:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:05:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:05:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:05:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:05:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:05:59 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 0x7f49ff87bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf768a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1043790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040930>, '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 0x1041830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1041cc0>, '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-1474369523.661552-1077558210', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:05:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:05:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:05:59 INFO - Using env: {'DISPLAY': ':0', 04:05:59 INFO - 'HOME': '/home/cltbld', 04:05:59 INFO - 'LANG': 'en_US.UTF-8', 04:05:59 INFO - 'LANGUAGE': 'en_US:en', 04:05:59 INFO - 'LOGNAME': 'cltbld', 04:05:59 INFO - 'MAIL': '/var/mail/cltbld', 04:05:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:05:59 INFO - 'MOZ_NO_REMOTE': '1', 04:05:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:05:59 INFO - 'NO_EM_RESTART': '1', 04:05:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:05:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:05:59 INFO - 'PWD': '/builds/slave/test', 04:05:59 INFO - 'SHELL': '/bin/bash', 04:05:59 INFO - 'SHLVL': '1', 04:05:59 INFO - 'TERM': 'linux', 04:05:59 INFO - 'TMOUT': '86400', 04:05:59 INFO - 'USER': 'cltbld', 04:05:59 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1474369523.661552-1077558210', 04:05:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:05:59 INFO - '_': '/tools/buildbot/bin/python'} 04:05:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:05:59 INFO - Running setup.py (path:/tmp/pip-uCzJsS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:05:59 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 04:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:05:59 INFO - Running setup.py (path:/tmp/pip-sdwMO_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:05:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:05:59 INFO - Running setup.py (path:/tmp/pip-yD6sHW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:05:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:05:59 INFO - Running setup.py (path:/tmp/pip-coO2SU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:05:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:05:59 INFO - Running setup.py (path:/tmp/pip-I0OLuQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:06:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 04:06:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:06:00 INFO - Running setup.py (path:/tmp/pip-hFH0ya-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:06:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 04:06:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:06:00 INFO - Running setup.py (path:/tmp/pip-36HDsw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:06:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 04:06:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:06:00 INFO - Running setup.py (path:/tmp/pip-aBPwXl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:06:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:06:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:06:00 INFO - Running setup.py (path:/tmp/pip-diJiCn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:06:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 04:06:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:06:00 INFO - Running setup.py (path:/tmp/pip-yVhG4o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:06:00 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)) 04:06:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:06:00 INFO - Running setup.py (path:/tmp/pip-x7MeBZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:06:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:06:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:06:00 INFO - Running setup.py (path:/tmp/pip-qQJZR8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:06:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:06:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:06:00 INFO - Running setup.py (path:/tmp/pip-YspCzJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:06:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 04:06:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:06:00 INFO - Running setup.py (path:/tmp/pip-4ujI2l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:06:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:06:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:06:00 INFO - Running setup.py (path:/tmp/pip-58rq_j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:06:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 04:06:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:06:00 INFO - Running setup.py (path:/tmp/pip-7bQgVK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:06:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 04:06:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:06:00 INFO - Running setup.py (path:/tmp/pip-Cevbah-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:06:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 04:06:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:06:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:06:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:06:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:06:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:06:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:06:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:06:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:06:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:06:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:06:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:06:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:06:02 INFO - Downloading blessings-1.6.tar.gz 04:06:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:06:02 INFO - Installing collected packages: blessings 04:06:02 INFO - Running setup.py install for blessings 04:06:03 INFO - Successfully installed blessings 04:06:03 INFO - Cleaning up... 04:06:03 INFO - Return code: 0 04:06:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:06:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:06:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:06:03 INFO - Reading from file tmpfile_stdout 04:06:03 INFO - Current package versions: 04:06:03 INFO - argparse == 1.2.1 04:06:03 INFO - blessings == 1.6 04:06:03 INFO - blobuploader == 1.2.4 04:06:03 INFO - docopt == 0.6.1 04:06:03 INFO - functools32 == 3.2.3-2 04:06:03 INFO - jsonschema == 2.5.1 04:06:03 INFO - manifestparser == 1.1 04:06:03 INFO - mozInstall == 1.12 04:06:03 INFO - mozcrash == 0.17 04:06:03 INFO - mozdebug == 0.1 04:06:03 INFO - mozdevice == 0.48 04:06:03 INFO - mozfile == 1.2 04:06:03 INFO - mozhttpd == 0.7 04:06:03 INFO - mozinfo == 0.9 04:06:03 INFO - mozleak == 0.1 04:06:03 INFO - mozlog == 3.4 04:06:03 INFO - moznetwork == 0.27 04:06:03 INFO - mozprocess == 0.23 04:06:03 INFO - mozprofile == 0.28 04:06:03 INFO - mozrunner == 6.12 04:06:03 INFO - mozscreenshot == 0.1 04:06:03 INFO - mozsystemmonitor == 0.3 04:06:03 INFO - moztest == 0.7 04:06:03 INFO - mozversion == 1.4 04:06:03 INFO - psutil == 3.1.1 04:06:03 INFO - requests == 1.2.3 04:06:03 INFO - wsgiref == 0.1.2 04:06:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:06:03 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')]} 04:06:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:06:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:06:03 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')]} 04:06:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:06:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:06:03 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 0x7f49ff87bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf768a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1043790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040930>, '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 0x1041830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1041cc0>, '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-1474369523.661552-1077558210', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:06:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 04:06:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:06:03 INFO - Using env: {'DISPLAY': ':0', 04:06:03 INFO - 'HOME': '/home/cltbld', 04:06:03 INFO - 'LANG': 'en_US.UTF-8', 04:06:03 INFO - 'LANGUAGE': 'en_US:en', 04:06:03 INFO - 'LOGNAME': 'cltbld', 04:06:03 INFO - 'MAIL': '/var/mail/cltbld', 04:06:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:06:03 INFO - 'MOZ_NO_REMOTE': '1', 04:06:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:06:03 INFO - 'NO_EM_RESTART': '1', 04:06:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:06:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:06:03 INFO - 'PWD': '/builds/slave/test', 04:06:03 INFO - 'SHELL': '/bin/bash', 04:06:03 INFO - 'SHLVL': '1', 04:06:03 INFO - 'TERM': 'linux', 04:06:03 INFO - 'TMOUT': '86400', 04:06:03 INFO - 'USER': 'cltbld', 04:06:03 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1474369523.661552-1077558210', 04:06:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:06:03 INFO - '_': '/tools/buildbot/bin/python'} 04:06:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:06:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:06:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 04:06:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 04:06:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:06:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 04:06:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 04:06:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 04:06:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 04:06:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 04:06:03 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:06:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:06:03 INFO - Cleaning up... 04:06:03 INFO - Return code: 0 04:06:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:06:03 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')]} 04:06:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:06:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:06:03 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')]} 04:06:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:06:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:06:03 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 0x7f49ff87bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf768a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1043790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040930>, '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 0x1041830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1041cc0>, '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-1474369523.661552-1077558210', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:06:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:06:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:06:03 INFO - Using env: {'DISPLAY': ':0', 04:06:03 INFO - 'HOME': '/home/cltbld', 04:06:03 INFO - 'LANG': 'en_US.UTF-8', 04:06:03 INFO - 'LANGUAGE': 'en_US:en', 04:06:03 INFO - 'LOGNAME': 'cltbld', 04:06:03 INFO - 'MAIL': '/var/mail/cltbld', 04:06:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:06:03 INFO - 'MOZ_NO_REMOTE': '1', 04:06:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:06:03 INFO - 'NO_EM_RESTART': '1', 04:06:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:06:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:06:03 INFO - 'PWD': '/builds/slave/test', 04:06:03 INFO - 'SHELL': '/bin/bash', 04:06:03 INFO - 'SHLVL': '1', 04:06:03 INFO - 'TERM': 'linux', 04:06:03 INFO - 'TMOUT': '86400', 04:06:03 INFO - 'USER': 'cltbld', 04:06:03 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1474369523.661552-1077558210', 04:06:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:06:03 INFO - '_': '/tools/buildbot/bin/python'} 04:06:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:06:03 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:06:03 INFO - Cleaning up... 04:06:03 INFO - Return code: 0 04:06:03 INFO - Running post-action listener: _resource_record_post_action 04:06:03 INFO - Running post-action listener: _start_resource_monitoring 04:06:03 INFO - Starting resource monitoring. 04:06:03 INFO - [mozharness: 2016-09-20 11:06:03.851142Z] Finished create-virtualenv step (success) 04:06:03 INFO - [mozharness: 2016-09-20 11:06:03.851651Z] Running install step. 04:06:03 INFO - Running pre-action listener: _resource_record_pre_action 04:06:03 INFO - Running main action method: install 04:06:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:06:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:06:04 INFO - Reading from file tmpfile_stdout 04:06:04 INFO - Detecting whether we're running mozinstall >=1.0... 04:06:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:06:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:06:04 INFO - Reading from file tmpfile_stdout 04:06:04 INFO - Output received: 04:06:04 INFO - Usage: mozinstall [options] installer 04:06:04 INFO - Options: 04:06:04 INFO - -h, --help show this help message and exit 04:06:04 INFO - -d DEST, --destination=DEST 04:06:04 INFO - Directory to install application into. [default: 04:06:04 INFO - "/builds/slave/test"] 04:06:04 INFO - --app=APP Application being installed. [default: firefox] 04:06:04 INFO - mkdir: /builds/slave/test/build/application 04:06:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 04:06:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 04:06:21 INFO - Reading from file tmpfile_stdout 04:06:21 INFO - Output received: 04:06:21 INFO - /builds/slave/test/build/application/firefox/firefox 04:06:21 INFO - Running post-action listener: _resource_record_post_action 04:06:21 INFO - [mozharness: 2016-09-20 11:06:21.093933Z] Finished install step (success) 04:06:21 INFO - [mozharness: 2016-09-20 11:06:21.094178Z] Running run-tests step. 04:06:21 INFO - Running pre-action listener: _resource_record_pre_action 04:06:21 INFO - Running main action method: run_tests 04:06:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:06:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:06:21 INFO - Python 2.7.3 04:06:21 INFO - Return code: 0 04:06:21 INFO - grabbing minidump binary from tooltool 04:06:21 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')]} 04:06:21 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 0x1040930>, '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 0x1041830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1041cc0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:06:21 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 04:06:21 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 04:06:21 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 04:06:21 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:06:21 INFO - Return code: 0 04:06:21 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:06:21 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:06:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:06:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:06:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:06:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HqXZKlw1SL2sHTXeLp0DBA/artifacts/public/build/firefox-52.0a1.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 04:06:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/HqXZKlw1SL2sHTXeLp0DBA/artifacts/public/build/firefox-52.0a1.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 04:06:21 INFO - Using env: {'DISPLAY': ':0', 04:06:21 INFO - 'HOME': '/home/cltbld', 04:06:21 INFO - 'LANG': 'en_US.UTF-8', 04:06:21 INFO - 'LANGUAGE': 'en_US:en', 04:06:21 INFO - 'LOGNAME': 'cltbld', 04:06:21 INFO - 'MAIL': '/var/mail/cltbld', 04:06:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:06:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:06:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:06:21 INFO - 'MOZ_NO_REMOTE': '1', 04:06:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:06:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:06:21 INFO - 'NO_EM_RESTART': '1', 04:06:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:06:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:06:21 INFO - 'PWD': '/builds/slave/test', 04:06:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:06:21 INFO - 'SHELL': '/bin/bash', 04:06:21 INFO - 'SHLVL': '1', 04:06:21 INFO - 'TERM': 'linux', 04:06:21 INFO - 'TMOUT': '86400', 04:06:21 INFO - 'USER': 'cltbld', 04:06:21 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1474369523.661552-1077558210', 04:06:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:06:21 INFO - '_': '/tools/buildbot/bin/python'} 04:06:21 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HqXZKlw1SL2sHTXeLp0DBA/artifacts/public/build/firefox-52.0a1.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 04:06:21 INFO - mozversion application_buildid: 20160920003004 04:06:21 INFO - mozversion application_changeset: 9a5ccc0e72701ee48a3ad5d172db195210d1fb37 04:06:21 INFO - mozversion application_display_name: Nightly 04:06:21 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:06:21 INFO - mozversion application_name: Firefox 04:06:21 INFO - mozversion application_remotingname: firefox 04:06:21 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 04:06:21 INFO - mozversion application_vendor: Mozilla 04:06:21 INFO - mozversion application_version: 52.0a1 04:06:21 INFO - mozversion platform_buildid: 20160920003004 04:06:21 INFO - mozversion platform_changeset: 9a5ccc0e72701ee48a3ad5d172db195210d1fb37 04:06:21 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 04:06:21 INFO - mozversion platform_version: 52.0a1 04:06:21 INFO - using testdate: 1474369581 04:06:21 INFO - actual date: 1474369581 04:06:21 INFO - starting webserver on 'localhost:52973' 04:06:21 INFO - SUITE-START | Running 2 tests 04:06:21 INFO - TEST-START | dromaeo_css 04:06:21 INFO - operating with platform_type : linux_ 04:06:21 INFO - Initialising browser for dromaeo_css test... 04:06:21 INFO - TEST-INFO | started process 11866 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp98nlDe/profile http://localhost:52973/getInfo.html) 04:06:25 INFO - PROCESS | 11866 | __metrics Screen width/height:1600/1200 04:06:25 INFO - PROCESS | 11866 | colorDepth:24 04:06:25 INFO - PROCESS | 11866 | Browser inner width/height: 1024/697 04:06:25 INFO - PROCESS | 11866 | __metrics 04:06:26 INFO - PROCESS | 11866 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:06:26 INFO - PROCESS | 11866 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:06:26 INFO - PROCESS | 11866 | console.error: PushService: 04:06:26 INFO - PROCESS | 11866 | stateChangeProcessEnqueue: Error transitioning state 04:06:26 INFO - PROCESS | 11866 | UnknownError 04:06:27 INFO - TEST-INFO | 11866: exit 0 04:06:27 INFO - Browser initialized. 04:06:27 INFO - Running cycle 1/1 for dromaeo_css test... 04:06:27 INFO - TEST-INFO | started process 11951 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp98nlDe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:06:39 INFO - PROCESS | 11951 | RSS: Main: 173355008 04:06:39 INFO - PROCESS | 11951 | 04:07:03 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:07:15 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.06668 seconds. 04:07:20 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.03334 seconds. 04:07:22 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 04:07:29 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 04:07:30 INFO - PROCESS | 11951 | 1474369650751 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 04:07:30 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.01666 seconds. 04:07:36 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 04:07:49 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:07:51 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.16672 seconds. 04:08:11 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 04:08:13 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.05004 seconds. 04:08:36 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 04:08:45 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.16672 seconds. 04:08:48 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 2.00008 seconds. 04:08:49 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:08:53 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.10004 seconds. 04:09:04 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 04:09:10 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 04:09:21 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.11672 seconds. 04:09:24 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.01665 seconds. 04:09:34 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.16671 seconds. 04:09:38 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 04:09:39 INFO - PROCESS | 11951 | Cycle 1(1): loaded http://localhost:52973/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52973/tests/dromaeo/cssquery-ext.html) 04:09:41 INFO - PROCESS | 11951 | RSS: Main: 347709440 04:09:41 INFO - PROCESS | 11951 | 04:09:45 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 04:09:47 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 04:10:02 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:10:04 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:10:12 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:10:28 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:10:30 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:10:31 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 2.01674 seconds. 04:10:38 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:10:40 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:10:47 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:10:48 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 04:10:58 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 04:11:04 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:11:27 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 04:11:39 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 04:11:47 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:11:51 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 04:11:56 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 04:12:08 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 04:12:10 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.03335 seconds. 04:12:17 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00008 seconds. 04:12:23 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 04:12:25 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 04:12:33 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:12:35 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:12:37 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:12:41 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:12:43 INFO - PROCESS | 11951 | Cycle 1(1): loaded http://localhost:52973/tests/dromaeo/cssquery-ext.html (next: http://localhost:52973/tests/dromaeo/cssquery-jquery.html) 04:12:44 INFO - PROCESS | 11951 | RSS: Main: 281423872 04:12:44 INFO - PROCESS | 11951 | 04:12:47 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 04:12:50 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.03336 seconds. 04:12:59 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:13:01 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 04:13:03 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:13:07 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 04:13:08 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 2.00005 seconds. 04:13:29 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:13:33 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:13:38 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 04:13:49 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:13:51 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:14:15 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 04:14:17 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 04:14:32 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 04:14:48 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:14:52 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:14:54 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:14:58 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:15:16 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:15:22 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 04:15:34 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:15:39 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 2.00005 seconds. 04:15:39 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 2.00005 seconds. 04:15:42 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 04:15:46 INFO - PROCESS | 11951 | Cycle 1(1): loaded http://localhost:52973/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52973/tests/dromaeo/cssquery-mootools.html) 04:15:47 INFO - PROCESS | 11951 | RSS: Main: 215588864 04:15:47 INFO - PROCESS | 11951 | 04:15:51 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00009 seconds. 04:15:55 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:15:57 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:15:59 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:16:06 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:16:29 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 04:16:37 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 04:16:47 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:17:04 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:17:10 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 04:17:24 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 04:17:32 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 04:17:48 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:18:25 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:18:28 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:18:29 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:18:37 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:18:47 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:18:49 INFO - PROCESS | 11951 | Cycle 1(1): loaded http://localhost:52973/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52973/tests/dromaeo/cssquery-prototype.html) 04:18:49 INFO - PROCESS | 11951 | RSS: Main: 233586688 04:18:49 INFO - PROCESS | 11951 | 04:18:53 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.03336 seconds. 04:18:57 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 04:19:03 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 04:19:10 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:19:12 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:19:14 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:19:22 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 04:19:30 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 04:19:38 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 04:19:39 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:19:43 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:19:53 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:19:59 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:20:10 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.01675 seconds. 04:20:23 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.03336 seconds. 04:20:25 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 04:20:33 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 04:20:52 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:21:09 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:21:12 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:21:16 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:21:23 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:21:29 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 04:21:32 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:21:52 INFO - PROCESS | 11951 | Cycle 1(1): loaded http://localhost:52973/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52973/tests/dromaeo/cssquery-yui.html) 04:21:53 INFO - PROCESS | 11951 | RSS: Main: 215117824 04:21:53 INFO - PROCESS | 11951 | 04:21:56 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.01675 seconds. 04:22:00 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 04:22:14 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 04:22:23 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 04:22:25 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:22:42 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 04:22:57 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 04:22:58 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 04:23:04 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 04:23:24 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:23:28 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 04:23:41 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00009 seconds. 04:23:54 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 04:23:58 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 04:24:07 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 04:24:15 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 04:24:17 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:24:28 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 04:24:34 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.05004 seconds. 04:24:56 INFO - PROCESS | 11951 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 04:24:56 INFO - PROCESS | 11951 | Cycle 1(1): loaded http://localhost:52973/tests/dromaeo/cssquery-yui.html (next: http://localhost:52973/tests/dromaeo/cssquery-dojo.html) 04:24:56 INFO - PROCESS | 11951 | RSS: Main: 306958336 04:24:56 INFO - PROCESS | 11951 | 04:24:56 INFO - PROCESS | 11951 | __start_tp_report 04:24:56 INFO - PROCESS | 11951 | _x_x_mozilla_page_load 04:24:56 INFO - PROCESS | 11951 | _x_x_mozilla_page_load_details 04:24:56 INFO - PROCESS | 11951 | |i|pagename|runs| 04:24:56 INFO - PROCESS | 11951 | |0;dojo.html;2936.6622838373582;2983.313837817302;2983.7314641682246;3004.864781084851;3027.0767415938144;5108.948910510903;5173.974130129349;5208.322918020652;5209.687418754873;5233.371995360562;5198.116320225561;5201.505856943594;5242.606804489669;5244.921326180111;5284.947150528503;4712.3638308828295;4792.9281060784115;4809.927851082237;4812.109759694467;4889.046635906;4858.684185527947;4909;4923.064617722639;4930.03864246472;4952.851414457572;4647.488776234628;4691.9912218873105;4733;4836.51634836517;4840.5885499732385;4826.22780355145;4837.516248375135;4857.271409288635;4883.609311255091;4914.68054576455;4768.165571025057;4796.3524924135145;4810.11012962603;4830.903381932377;4859.878503037417;5105.259737338065;5142.537171654531;5267.446918073585;5281.947180528223;5338.225957236218;5529.585281103933;5537.473939975736;5539.169124631338;5540.806071787509;5543.861403464905;5467.726613669301;5531.557475401958;5543.695096769676;5557.861053473695;5565.220869078333;5228.137357335997;5229.738513074369;5231.293775340357;5231.476852314776;5285.947140528584;5633.859153521113;5727.5990680652785;5733.88532229353;5747.109198074305;5809.157672137601;5378.731063446812;5415.702136382458;5423.674579525241;5470.042742520043;5492.80775172867;5552.028395030853;5560.749766260528;5561.221428999943;5593.916091258634;5637.746301416446;5525;5566.777328906879;5574.637648552913;5576.442355764391;5587.1339942309005;5439.5104440600535;5497.038018346773;5552.361478429952;5578.24693666358;5645.5201307888465;4339;4343.782810859444;4352.194843953878;4399;4402.163588918095;3881.5924327945722;3886.747361421555;3889.086064774776;3922.5489068756888;3930.292547341506;5103.948960510347;5169.095408303532;5354.196870469383;5375.5162035417;5433.673979561239;5514.200440936042;5517.531009864127;5559.11054231321;5588.44115588446;5689.061304884648;5309.044372012998;5310.52205301517;5312;5326.121190003606;5374;4792.664513484023;4795.520447955247;4797.4482934462985;4800.495947925488;4856.17445034345;5685.431456854281;5733.283339582552;5739.913901291484;5754.99287624664;5786.594938354275;5585.162225666182;5612.270404847343;5622;5685.374608793112;5692.459216374438;5762.884742305214;5815.476607105381;5816.47651711348;5838.766449341979;5874.676892770939;5489.984352894728;5547.5007249347755;5554.944560533486;5556.416576259435;5556.638818476786;5419.620626556103;5463.262459567907;5469.75386107634;5549.112142057413;5652.660840349592;5417.756200971045;5419.891602167856;5427.1045277528765;5427.1316589345515;5432.375276843213;5420.430854760193;5431.2667789847865;5457.7271136443815;5473.480019398151;5505.917411238915;5522.006038913034;5522.779088836402;5525.861853453695;5540.252065971043;5567.276254086943;5503.064479038653;5510.035743744748;5511.448855114457;5515.117581186991;5546.4453554644215;5194.3766747990485;5227.163653815372;5233.607479439133;5430.864803494341;5509.862253443696 04:24:56 INFO - PROCESS | 11951 | |1;ext.html;561.7163332517079;605.1104875832526;615.8337248942786;617.1267656266383;623.6538721009838;27867.163985080428;28516.287092822662;31182;31202.063938081894;45514;43341.34987975175;43792.12415751683;43813.7809310953;43821.78089109558;43893.341599876025;9067.319951003668;9214.216791572724;9215.953920230422;9217.446953182796;9221.123993220634;9027.95486022569;9143.314251431137;9144.222741067017;9148.405353652024;9148.634054637807;9126.041765614615;9177.219936305422;9184.265258779282;9187.219086377632;9188;9133.588988495534;9175.220106290973;9183;9189.264858811279;9193.942696589844;9084.273258139334;9111.362204645677;9115.863262051074;9120.45277283365;9123.08769123089;41396;41699.1660166795;41700.37449438261;41719.58280417187;41773.582264177574;3385.1875549867855;3388.0682812226587;3403.5745531808525;3496.7202623790035;3501;30687.846560767277;31416.21459463509;31460;31469.8426507866;31519.684803152133;4103.8974025649295;4153.514204658991;4169.833206671729;4333.566643335673;4413.44831896013;328747;329497;356941;356971;357863;55261.72369138129;55567.72216138974;55578.55371570265;56000.159997600065;56005;58891.705541472016;59208;59311.11033334504;59370.01654693809;59372.40627593755;17029.318827247018;17061.06164160983;17111.315547378214;17279.65440691179;17283.13584320779;13441.663958400923;13630.86369136296;13632.863671363359;13635.045546811827;13638.454461821417;16093.436729714638;16194.271257793427;16245.10651914156;16278.104704241381;16365.263563139688;5549.807825908824;5622.240997465372;5628.577856660685;5677.17680936262;5840.627452548648;887.4143065576689;1029.361795686664;1039.542601255445;1060;1064.414571985405;29341.853290733838;29904.70095299019;30257;30779;30936.845315773277;1092.1972350322508;1111.394290111891;1126.3467189030343;1130.8416821644976;1181.6041626055196;15224.543263702106;15513.767293490606;15672.138032408098;15934.760978585331;16326.75509867353;535427;538044;538554;538635;539568;55380.72309638507;55511.72244138754;55607.16589251164;55660;55854;1183.0594677231622;1210.9091818113677;1311.318114580432;1321.4119716726157;1337.7658909690765;29980.550291746076;30058.24854378658;31261;32007.519887201244;33711;52339.47660523346;52760.47239527556;52931.470685292654;52964.73517632387;53045;486481.3787103827;493165;493683;494181;494190;23627.881860590587;23830;27079.72920270772;27537.72462275432;27564.58653120245;42445.57554424416;42471.15057698767;42574;43036.78481607572;43988;37055.44416833696;37455.81272093622;37462.438063428526;37495;38796.80601596974;36652.45021324629;36812;36829.44755828611;37205;37407.43888841615;35807;36058.27883442264;36250;37413.81293093517;37443.25113497769;32293.031209063767;32448.83775581107;33593.328133436706;34167.65832341645;34253.486197707556;25970.610440843026;25995.870020649774;26023.479530409666;26029.95880164772;26640.733592663826 04:24:56 INFO - PROCESS | 11951 | |2;jquery.html;359.3854508789969;372.92168644584655;384.2161989541335;388.7492567294095;408.1001391930793;13756;20172.19311227551;20669.793302066995;20981.68527472089;21161.894190529063;17815.198316075774;18637.347692830754;19156.90421547894;19317.613647727037;19342;5630.859228519279;5878.470937615635;5882.058870580708;5886.381929897363;5887.705614719247;5630.183623374608;5774.335951365584;5793.6234144780665;5812.680302583356;5815.244018277618;5753.050746626823;5756.798512052079;5756.8272951811305;5757.42425757423;5788.594798364116;5578.497935185833;5719;5742.4831765141125;5796.50729687977;5798.478136967672;5178.559822415099;5179.922301165486;5185.144451165553;5185.403678576937;5188.481151884819;23747.168849090373;24436.755632443626;24492;25935.481290374275;26153.869230653916;2306.5271619318;2403.9519209615883;2459.938501537458;2733.0570953021174;2733.412316351994;15180.620484487976;15393.230338483143;15854.286557104842;16088.597785055466;16138.515844524683;1964.764228292614;2026.8259290858882;2067.7725450200464;2087.1964293746864;2184.803367696915;99811;102002.48998755033;102096.48951755193;104417;105097;15462.273910848817;17821.4951542686;17906.731399029024;18007.459776206735;18291.81708182928;32216.35567288641;32239.35521289561;32250.83874580659;32328.35343293168;32379.676203237665;10155.543000564992;10303.896961030294;10377.325473844176;10380.221483388776;10825.78348433027;8336.66653333872;8624.525651089125;8721.738347849574;8787.340949428819;8880.600372983103;8649.740507784776;8969.820603587892;9017.684381046633;9033.457992520469;9045.185933266037;3090.3973725123387;3291.6708329167373;3355.1248308204063;3360.3783300089563;3512.806795626214;837.9706710265111;895.4227859700409;949.9145076943108;977.4086677560116;977.4917043137528;18192.545186370447;19013.524661883563;19258;19258.4222473326;19273.903630482037;925.717656114878;927.536231884058;965.290511474066;997.8203923293877;1009.8535712321819;10436.217283703752;10450.16398688103;10642.708364477096;10752.19358548112;10778.94610526958;444517;448128;456356;457644;458835;89755;89771;90381.54809225912;91236;91984;973.0852998181545;986.6645340584236;1016.588281745894;1016.9237307201989;1017.013496907998;16335.918320408322;16389.42637007699;16869.156542172597;17079.658406831546;17235.482935511955;42475.1420987106;42646;44181.77909110434;44711.77644111759;45209.32186017147;56214.71892640674;59938.70030649819;60241.69879150576;61538.69230653818;62756.37243627505;8522.616482258189;8657.264132548804;8721.694740684046;8807.691730789647;8811.206991370807;12410.379481026093;12637.810432843331;12699.873001270238;12845.357732113544;12904.483820647445;11361.431928403712;11379.943100284445;11558.30650160993;11571.942140289244;11808.645740627795;10713.678589642323;11235.831462528233;11451.427428628702;11620.070394368598;11736.530538778694;11025.393603351893;11282.255060226003;11419.657410277705;11635.301881887113;11858.584949526725;7659.502132361378;8545.914540854512;8554.78613034679;8911.465312081296;9109.316801240066;10179.236557258384;10926.289791163548;10971.23201375896;11222.607208747655;11333.603323883624 04:24:56 INFO - PROCESS | 11951 | |3;mootools.html;1279.5009946121008;1421.2833440552954;1443.7906503556983;1524.9008814427061;1644.868410527158;7041.155061392634;7492.850142997137;7701.037370328709;7706.4990775599545;7896.091949425818;7701.422393320509;7764.068311802592;7782.455228134018;7783.805404864867;7786.961065194666;2290.530441259542;2312.710911136108;2313.8958746856433;2315.3169814904645;2317.536492701458;2233.8547994380397;2250.144944920936;2288.187693368847;2290.4617414907484;2290.7365652949875;2192.4738062864876;2215;2235.865848049122;2256.0524579676576;2259.3334966184934;2189.879556624385;2206.3160420269687;2213.269621025066;2214.2250212425683;2215.2135991722866;2175.945601359963;2181.694562761215;2186.1146235774536;2188.8796116213603;2192.7039849620255;7693.038370203724;7758.612069396508;7759.883601745978;7760.146383897767;7909.485883417559;4879.780409881529;5097.745112744348;5099.260607211933;5104.9744751276;5121.28302037715;3204.038788363505;3233.919152021195;3290.8683652653867;3316.088075779156;3348.68187522185;1130.3387518301804;1151.1193936638479;1979.9010049497467;1999.3701983875033;2000.599880024001;10614.575416983389;10662.200334974908;10721.088707459876;10723;10725.839112413241;4165.4584903962295;4307.547707490731;4354.738715677006;4390.692651514427;4400.647948164204;8466.576671166416;8581.184787445183;8616.095309992486;8621.956890215508;8645.95677021611;4927.7536123193695;4944.777485013183;4949.802007919643;4952.752362381939;4955.231939049453;4001.379786133154;4048.3927410888014;4149.921020534623;4155.8337666493;4649.465311489222;3307.9007629771145;3574.660407261306;3596.604373518911;3639.454081887738;3641.617630148849;1036.528379587286;1036.6786296248188;1044.1281529922448;1052.736815796051;1066.6693325069252;886.4814083761072;892.6518657723493;900.6622516556291;907.6732376344511;921.6866265469772;5160.148575485078;5183.481651834786;5374.731263436813;5394.244805727201;5427.45725427462;441.35782436554734;442.33207856136823;448.8339314453673;457.54728736262234;469.48825779900073;3476.7392445566684;3685.94471082934;3686.7787932724123;3803.391457366808;3804.923901521954;9659.130678238993;10099.495025248709;10105.090541851268;10117.747056323504;10128.442935638463;9964.501774911225;9994.300398972147;9997.650082247086;10001.549930253155;10006.69979900604;7225.927740722631;7297.525660832135;7513.924860751323;7537.849243015109;7734.497257678233;5465.672059676433;5651.237082993744;5652.3217213934595;5708.086706127166;5756.366799651951;8842.336824738299;8935.285177185939;9127.132922372364;9153.313501487282;9159.267258619428;9046.728598142066;9096.1813436791;9110.088991100836;9175.44947303164;9178.173964343241;6236.220472440945;6398.072279519351;6780.830479238059;6920.688569014305;6927.376536111573;7757.7672669819995;7791.298783109547;7801.336886364723;7801.9609901950125;7829.373650107864;7050;7142.857142857218;7172.282771722995;7174.892376614459;7296.635168241673;6958.412342271557;7055.753048643478;7081.504294699322;7269.491135620456;7325.963370183115;6472.708728107153;6497.285298617054;6500.479961603155;6502.837429064311;6509.99095140254;4785.952140478551;4803.807847686054;4841.346418233635;4877.5366340197625;4924.63065270099;4855.320255164351;4917.1149193145575;4943.505649435027;5032.4837581209395;5060.595152387727 04:24:56 INFO - PROCESS | 11951 | |4;prototype.html;325.1043375500497;328.9522869660133;329.823544403744;333.20364329253084;337.4566947214985;5160.948390516099;5200.713960732158;5214.296070030545;5226.817061402847;5230.320058392414;4864.854054378366;4950.6287028472825;5020.272060551218;5082.84751457456;5139.4346621871655;4109.712320137576;4136.3381858902585;4145.5232648245465;4148.8962775930695;4159.83360665573;4132.2561938850995;4140.958590414088;4143.440635514212;4146.212219678267;4146.917061658765;3838.251540949516;3915.138669492707;4101.753894766323;4111.48606424197;4145.253854306224;4073.551909289976;4101.323281658552;4128.876133715993;4129.769924240922;4143.7306575072525;3695.427208782643;3702.9259414811822;3705.073731567108;3707.480952666629;3716.4425336199515;4866.197077482211;5081.466446023152;5104.489551044903;5167.932826869245;5171.37943446785;4621.8151273949;4625.907481850378;4631.785075581239;4634.351190833286;4658.161530924433;5320.228566857822;5332.973335133338;5379.488948549882;5401.513863752242;5406.8918621627745;3711;3862.2854771867014;3890.8443662253476;3915.7454765440157;3930.941035884464;5544.168374743821;5562.833115006556;5579.8605034874045;5585.162225666182;5606.607537472338;3991.820368083443;3993.7004724645767;3996.660283875845;4009.498812648419;4029.4083836884265;3916.980415097906;4029.0531725044593;4065.1259979104634;4067.7017485121405;4071;4598.793054312564;4600.125976064537;4601.15803304238;4615.95384046162;4619.676622636383;5416.187571864173;5417.729113544307;5419.891602168013;5435;5453.754581043862;1352.6280272924828;1371.1841454334656;1384.7398867031154;1391.6451304917182;1392.498700467851;876.4390789894345;895.6730793260413;898.7079199260136;901.4816480523725;908.2643059122;4614.3309220163555;4621;4626.653001024936;4645.908211570279;4648.442186937522;4900.264960255919;4940.802367905316;4980.651354405157;5001.274815151783;5018.473060328686;4267.4025636410925;4576.908461830812;4607.677462577587;4623.53764623535;4643.117807616542;2596.818222724429;2599.025365487942;2603.401217719897;2604.6093086037245;2606.27024433159;5040.294358789773;5046.671966322251;5051.444341122474;5059.3185611625895;5086.19440098767;4614.930776038362;4965.652404331734;4980.726060066731;4981.28145789702;4985.928025474468;4998.5751211146735;5008.248762685554;5019.899602007867;5020.723860187724;5033.583879737673;4828.430760002942;4848.927266091082;4883.95116048835;4894.510548945077;4905.190643543775;5074.797008119634;5524.116141417354;5526.198701188386;5526.22632831412;5536.307961504812;5793;5794.3626201117;5795.82612521625;5809.622374545641;5813.15709222152;3830.6743926766535;4023.416604592376;4043.0700938783657;4050.7772072535113;4052.8378864845076;5315;5483.341998959991;5522.889542209053;5540.972295138659;5630.268065151504;5384.569234461312;5427.480305514462;5459.262999495176;5463.890722185613;5467.289252397322;5283.067897317026;5473.31583552056;5485.177223416519;5487.957288115245;5489.478499542537;5367.892642147213;5383.434739352469;5415.864603384789;5418.187271909245;5450.672959622435;5435.076037073786;5494.780208791603;5495.395506494362;5497;5498.917516237179;5129.153689641167;5241.6068794839775;5272.789088436573;5354.973225134006;5367 04:24:56 INFO - PROCESS | 11951 | |5;yui.html;719.1149050659517;722.1658983873627;740.5963749756382;774.961251937403;829.6183755472484;15642.608934776636;16900.577485562837;16985.660286794257;17103.486895393125;17155.399561015365;14380.928095359508;14604;14740.778888316683;15023.173725445096;15166.165860877647;769.7498313048254;786.7403756760284;787.6888628991574;788.0543347982415;791.0151860933146;649.1398896462183;666.0941120076692;750.8198032472222;774.0904437286194;774.3727580659655;824.7979245084963;825.8224481736426;825.9917400825975;826.1655727715021;826.2893911236362;820.7127505373131;820.8645573480399;821.5070957425556;821.5892053973014;822.3873214455239;499.3109508877762;505.32791387454967;571.7884382778399;584.9532037436995;601.7683191971079;13867.930660346734;14022.789658155136;14034.157950523002;14063.226522541256;14065.156090634595;13772.24252666103;14107.647308817259;14611.561653150422;14632.487862924852;14740.189289589069;12621.873781262162;12757.018905015917;13105.475780968749;13186.340682965812;13317.86682133176;4361.912761744779;4433.423654924872;4461.308497182942;4470.105978804252;4492.3710680504755;82647.5867620664;85606.5719671404;86759.56620216859;86857;88621.55689221577;11113.777724445466;11142.164337674707;11142.944285278683;11387.886121138683;11444.771104578029;21734.78265217359;22751.658725119138;23028;23061.19285824988;23225.53548929012;8474.152584741476;8476.27951624119;8481.703140390056;8603.225709686034;8924.330675199386;4660.930086048712;4739.3601863748645;4742.605760999223;4807.95192048082;4813.205821039559;3201.759868009862;3276.164578032634;3330.234046169346;3336.366090442808;3339.632640409553;522.874510117569;525.8396189162265;526.1292560811786;528.1022262154353;550.4028129479559;577.3735496985734;600.7326739600836;633.3349982518338;657.329523885634;658.8517583543664;8641.351898607629;9433.528323583794;9623.422594644344;10512.842307365396;10649.680509584725;605.4218221598363;670.9667112646567;673.5285300289817;680.4624346766099;728.0971595221887;4523.773811309421;4746;4816.494268101869;4820.132376172252;4954.529319714621;97081.51459242658;111512.4424377873;112519.43740281246;117003;121766.39116804536;78748.21251787408;80861;83452.58273708592;99570.5021474888;100110;50632.493675062775;51190;71192.28807711856;81818.77265341832;81930.59034704907;9481.336306458757;10236.539355729159;10257;10288.897111029093;10309.123724483503;35346;36495.175241238365;41979.58020419879;49679;49724.25413618871;63886.36113638804;70105.64947175435;71263.28736712773;71658.64170679114;74549;12570.470669234348;13126;13166.144200626928;13201.867981320063;13970.231637260049;14291.213983231022;14692.632684182981;14735.92632036833;15109.39562417491;15142.470013549473;12853.293068880927;12931.159474634118;12982;13168.341582921008;14083.577492675236;13140.802887956497;13556.525521606696;14069.507567235098;14671.779923301372;14683.265836708335;13142.540011099565;13513.189208647513;14775.482858099915;14900;14929.402823886923;11163.162762792661;11481.600952829689;11831.34927578957;12442.688932776391;12943.741125177632;11284.492197851281;11565.7108572283;11986.280823150639;11997.940010299893;12314.938425307817 04:24:56 INFO - PROCESS | 11951 | __end_tp_report 04:24:56 INFO - PROCESS | 11951 | __start_cc_report 04:24:56 INFO - PROCESS | 11951 | _x_x_mozilla_cycle_collect,1329 04:24:56 INFO - PROCESS | 11951 | __end_cc_report 04:24:56 INFO - PROCESS | 11951 | __startTimestamp1474370696313__endTimestamp 04:24:56 INFO - PROCESS | 11951 | 04:24:56 INFO - PROCESS | 11951 | ------- Summary: start ------- 04:24:56 INFO - PROCESS | 11951 | Number of tests: 6 04:24:56 INFO - PROCESS | 11951 | 04:24:56 INFO - PROCESS | 11951 | [#0] dojo.html Cycles:165 Average:5203.70 Median:5420.43 stddev:573.16 (10.6%) stddev-sans-first:546.63 04:24:56 INFO - PROCESS | 11951 | Values: 2936.7 2983.3 2983.7 3004.9 3027.1 5108.9 5174.0 5208.3 5209.7 5233.4 5198.1 5201.5 5242.6 5244.9 5284.9 4712.4 4792.9 4809.9 4812.1 4889.0 4858.7 4909.0 4923.1 4930.0 4952.9 4647.5 4692.0 4733.0 4836.5 4840.6 4826.2 4837.5 4857.3 4883.6 4914.7 4768.2 4796.4 4810.1 4830.9 4859.9 5105.3 5142.5 5267.4 5281.9 5338.2 5529.6 5537.5 5539.2 5540.8 5543.9 5467.7 5531.6 5543.7 5557.9 5565.2 5228.1 5229.7 5231.3 5231.5 5285.9 5633.9 5727.6 5733.9 5747.1 5809.2 5378.7 5415.7 5423.7 5470.0 5492.8 5552.0 5560.7 5561.2 5593.9 5637.7 5525.0 5566.8 5574.6 5576.4 5587.1 5439.5 5497.0 5552.4 5578.2 5645.5 4339.0 4343.8 4352.2 4399.0 4402.2 3881.6 3886.7 3889.1 3922.5 3930.3 5103.9 5169.1 5354.2 5375.5 5433.7 5514.2 5517.5 5559.1 5588.4 5689.1 5309.0 5310.5 5312.0 5326.1 5374.0 4792.7 4795.5 4797.4 4800.5 4856.2 5685.4 5733.3 5739.9 5755.0 5786.6 5585.2 5612.3 5622.0 5685.4 5692.5 5762.9 5815.5 5816.5 5838.8 5874.7 5490.0 5547.5 5554.9 5556.4 5556.6 5419.6 5463.3 5469.8 5549.1 5652.7 5417.8 5419.9 5427.1 5427.1 5432.4 5420.4 5431.3 5457.7 5473.5 5505.9 5522.0 5522.8 5525.9 5540.3 5567.3 5503.1 5510.0 5511.4 5515.1 5546.4 5194.4 5227.2 5233.6 5430.9 5509.9 04:24:56 INFO - PROCESS | 11951 | 04:24:56 INFO - PROCESS | 11951 | [#1] ext.html Cycles:180 Average:60452.89 Median:37478.72 stddev:123982.25 (330.8%) stddev-sans-first:124248.51 04:24:56 INFO - PROCESS | 11951 | Values: 561.7 605.1 615.8 617.1 623.7 27867.2 28516.3 31182.0 31202.1 45514.0 43341.3 43792.1 43813.8 43821.8 43893.3 9067.3 9214.2 9216.0 9217.4 9221.1 9028.0 9143.3 9144.2 9148.4 9148.6 9126.0 9177.2 9184.3 9187.2 9188.0 9133.6 9175.2 9183.0 9189.3 9193.9 9084.3 9111.4 9115.9 9120.5 9123.1 41396.0 41699.2 41700.4 41719.6 41773.6 3385.2 3388.1 3403.6 3496.7 3501.0 30687.8 31416.2 31460.0 31469.8 31519.7 4103.9 4153.5 4169.8 4333.6 4413.4 328747.0 329497.0 356941.0 356971.0 357863.0 55261.7 55567.7 55578.6 56000.2 56005.0 58891.7 59208.0 59311.1 59370.0 59372.4 17029.3 17061.1 17111.3 17279.7 17283.1 13441.7 13630.9 13632.9 13635.0 13638.5 16093.4 16194.3 16245.1 16278.1 16365.3 5549.8 5622.2 5628.6 5677.2 5840.6 887.4 1029.4 1039.5 1060.0 1064.4 29341.9 29904.7 30257.0 30779.0 30936.8 1092.2 1111.4 1126.3 1130.8 1181.6 15224.5 15513.8 15672.1 15934.8 16326.8 535427.0 538044.0 538554.0 538635.0 539568.0 55380.7 55511.7 55607.2 55660.0 55854.0 1183.1 1210.9 1311.3 1321.4 1337.8 29980.6 30058.2 31261.0 32007.5 33711.0 52339.5 52760.5 52931.5 52964.7 53045.0 486481.4 493165.0 493683.0 494181.0 494190.0 23627.9 23830.0 27079.7 27537.7 27564.6 42445.6 42471.2 42574.0 43036.8 43988.0 37055.4 37455.8 37462.4 37495.0 38796.8 36652.5 36812.0 36829.4 37205.0 37407.4 35807.0 36058.3 36250.0 37413.8 37443.3 32293.0 32448.8 33593.3 34167.7 34253.5 25970.6 25995.9 26023.5 26030.0 26640.7 04:24:56 INFO - PROCESS | 11951 | 04:24:56 INFO - PROCESS | 11951 | [#2] jquery.html Cycles:180 Average:29670.95 Median:32245.10 stddev:75288.59 (233.5%) stddev-sans-first:75467.63 04:24:56 INFO - PROCESS | 11951 | Values: 359.4 372.9 384.2 388.7 408.1 13756.0 20172.2 20669.8 20981.7 21161.9 17815.2 18637.3 19156.9 19317.6 19342.0 5630.9 5878.5 5882.1 5886.4 5887.7 5630.2 5774.3 5793.6 5812.7 5815.2 5753.1 5756.8 5756.8 5757.4 5788.6 5578.5 5719.0 5742.5 5796.5 5798.5 5178.6 5179.9 5185.1 5185.4 5188.5 23747.2 24436.8 24492.0 25935.5 26153.9 2306.5 2404.0 2459.9 2733.1 2733.4 15180.6 15393.2 15854.3 16088.6 16138.5 1964.8 2026.8 2067.8 2087.2 2184.8 99811.0 102002.5 102096.5 104417.0 105097.0 15462.3 17821.5 17906.7 18007.5 18291.8 32216.4 32239.4 32250.8 32328.4 32379.7 10155.5 10303.9 10377.3 10380.2 10825.8 8336.7 8624.5 8721.7 8787.3 8880.6 8649.7 8969.8 9017.7 9033.5 9045.2 3090.4 3291.7 3355.1 3360.4 3512.8 838.0 895.4 949.9 977.4 977.5 18192.5 19013.5 19258.0 19258.4 19273.9 925.7 927.5 965.3 997.8 1009.9 10436.2 10450.2 10642.7 10752.2 10778.9 444517.0 448128.0 456356.0 457644.0 458835.0 89755.0 89771.0 90381.5 91236.0 91984.0 973.1 986.7 1016.6 1016.9 1017.0 16335.9 16389.4 16869.2 17079.7 17235.5 42475.1 42646.0 44181.8 44711.8 45209.3 56214.7 59938.7 60241.7 61538.7 62756.4 8522.6 8657.3 8721.7 8807.7 8811.2 12410.4 12637.8 12699.9 12845.4 12904.5 11361.4 11379.9 11558.3 11571.9 11808.6 10713.7 11235.8 11451.4 11620.1 11736.5 11025.4 11282.3 11419.7 11635.3 11858.6 7659.5 8545.9 8554.8 8911.5 9109.3 10179.2 10926.3 10971.2 11222.6 11333.6 04:24:56 INFO - PROCESS | 11951 | 04:24:56 INFO - PROCESS | 11951 | [#3] mootools.html Cycles:180 Average:5272.63 Median:4944.14 stddev:2894.88 (58.6%) stddev-sans-first:2887.44 04:24:56 INFO - PROCESS | 11951 | Values: 1279.5 1421.3 1443.8 1524.9 1644.9 7041.2 7492.9 7701.0 7706.5 7896.1 7701.4 7764.1 7782.5 7783.8 7787.0 2290.5 2312.7 2313.9 2315.3 2317.5 2233.9 2250.1 2288.2 2290.5 2290.7 2192.5 2215.0 2235.9 2256.1 2259.3 2189.9 2206.3 2213.3 2214.2 2215.2 2175.9 2181.7 2186.1 2188.9 2192.7 7693.0 7758.6 7759.9 7760.1 7909.5 4879.8 5097.7 5099.3 5105.0 5121.3 3204.0 3233.9 3290.9 3316.1 3348.7 1130.3 1151.1 1979.9 1999.4 2000.6 10614.6 10662.2 10721.1 10723.0 10725.8 4165.5 4307.5 4354.7 4390.7 4400.6 8466.6 8581.2 8616.1 8622.0 8646.0 4927.8 4944.8 4949.8 4952.8 4955.2 4001.4 4048.4 4149.9 4155.8 4649.5 3307.9 3574.7 3596.6 3639.5 3641.6 1036.5 1036.7 1044.1 1052.7 1066.7 886.5 892.7 900.7 907.7 921.7 5160.1 5183.5 5374.7 5394.2 5427.5 441.4 442.3 448.8 457.5 469.5 3476.7 3685.9 3686.8 3803.4 3804.9 9659.1 10099.5 10105.1 10117.7 10128.4 9964.5 9994.3 9997.7 10001.5 10006.7 7225.9 7297.5 7513.9 7537.8 7734.5 5465.7 5651.2 5652.3 5708.1 5756.4 8842.3 8935.3 9127.1 9153.3 9159.3 9046.7 9096.2 9110.1 9175.4 9178.2 6236.2 6398.1 6780.8 6920.7 6927.4 7757.8 7791.3 7801.3 7802.0 7829.4 7050.0 7142.9 7172.3 7174.9 7296.6 6958.4 7055.8 7081.5 7269.5 7326.0 6472.7 6497.3 6500.5 6502.8 6510.0 4786.0 4803.8 4841.3 4877.5 4924.6 4855.3 4917.1 4943.5 5032.5 5060.6 04:24:56 INFO - PROCESS | 11951 | 04:24:56 INFO - PROCESS | 11951 | [#4] prototype.html Cycles:180 Average:4442.23 Median:4958.14 stddev:1282.63 (25.9%) stddev-sans-first:1248.44 04:24:56 INFO - PROCESS | 11951 | Values: 325.1 329.0 329.8 333.2 337.5 5160.9 5200.7 5214.3 5226.8 5230.3 4864.9 4950.6 5020.3 5082.8 5139.4 4109.7 4136.3 4145.5 4148.9 4159.8 4132.3 4141.0 4143.4 4146.2 4146.9 3838.3 3915.1 4101.8 4111.5 4145.3 4073.6 4101.3 4128.9 4129.8 4143.7 3695.4 3702.9 3705.1 3707.5 3716.4 4866.2 5081.5 5104.5 5167.9 5171.4 4621.8 4625.9 4631.8 4634.4 4658.2 5320.2 5333.0 5379.5 5401.5 5406.9 3711.0 3862.3 3890.8 3915.7 3930.9 5544.2 5562.8 5579.9 5585.2 5606.6 3991.8 3993.7 3996.7 4009.5 4029.4 3917.0 4029.1 4065.1 4067.7 4071.0 4598.8 4600.1 4601.2 4616.0 4619.7 5416.2 5417.7 5419.9 5435.0 5453.8 1352.6 1371.2 1384.7 1391.6 1392.5 876.4 895.7 898.7 901.5 908.3 4614.3 4621.0 4626.7 4645.9 4648.4 4900.3 4940.8 4980.7 5001.3 5018.5 4267.4 4576.9 4607.7 4623.5 4643.1 2596.8 2599.0 2603.4 2604.6 2606.3 5040.3 5046.7 5051.4 5059.3 5086.2 4614.9 4965.7 4980.7 4981.3 4985.9 4998.6 5008.2 5019.9 5020.7 5033.6 4828.4 4848.9 4884.0 4894.5 4905.2 5074.8 5524.1 5526.2 5526.2 5536.3 5793.0 5794.4 5795.8 5809.6 5813.2 3830.7 4023.4 4043.1 4050.8 4052.8 5315.0 5483.3 5522.9 5541.0 5630.3 5384.6 5427.5 5459.3 5463.9 5467.3 5283.1 5473.3 5485.2 5488.0 5489.5 5367.9 5383.4 5415.9 5418.2 5450.7 5435.1 5494.8 5495.4 5497.0 5498.9 5129.2 5241.6 5272.8 5355.0 5367.0 04:24:56 INFO - PROCESS | 11951 | 04:24:56 INFO - PROCESS | 11951 | [#5] yui.html Cycles:180 Average:19976.74 Median:4447.37 stddev:28190.35 (633.9%) stddev-sans-first:28232.34 04:24:56 INFO - PROCESS | 11951 | Values: 719.1 722.2 740.6 775.0 829.6 15642.6 16900.6 16985.7 17103.5 17155.4 14380.9 14604.0 14740.8 15023.2 15166.2 769.7 786.7 787.7 788.1 791.0 649.1 666.1 750.8 774.1 774.4 824.8 825.8 826.0 826.2 826.3 820.7 820.9 821.5 821.6 822.4 499.3 505.3 571.8 585.0 601.8 13867.9 14022.8 14034.2 14063.2 14065.2 13772.2 14107.6 14611.6 14632.5 14740.2 12621.9 12757.0 13105.5 13186.3 13317.9 4361.9 4433.4 4461.3 4470.1 4492.4 82647.6 85606.6 86759.6 86857.0 88621.6 11113.8 11142.2 11142.9 11387.9 11444.8 21734.8 22751.7 23028.0 23061.2 23225.5 8474.2 8476.3 8481.7 8603.2 8924.3 4660.9 4739.4 4742.6 4808.0 4813.2 3201.8 3276.2 3330.2 3336.4 3339.6 522.9 525.8 526.1 528.1 550.4 577.4 600.7 633.3 657.3 658.9 8641.4 9433.5 9623.4 10512.8 10649.7 605.4 671.0 673.5 680.5 728.1 4523.8 4746.0 4816.5 4820.1 4954.5 97081.5 111512.4 112519.4 117003.0 121766.4 78748.2 80861.0 83452.6 99570.5 100110.0 50632.5 51190.0 71192.3 81818.8 81930.6 9481.3 10236.5 10257.0 10288.9 10309.1 35346.0 36495.2 41979.6 49679.0 49724.3 63886.4 70105.6 71263.3 71658.6 74549.0 12570.5 13126.0 13166.1 13201.9 13970.2 14291.2 14692.6 14735.9 15109.4 15142.5 12853.3 12931.2 12982.0 13168.3 14083.6 13140.8 13556.5 14069.5 14671.8 14683.3 13142.5 13513.2 14775.5 14900.0 14929.4 11163.2 11481.6 11831.3 12442.7 12943.7 11284.5 11565.7 11986.3 11997.9 12314.9 04:24:56 INFO - PROCESS | 11951 | -------- Summary: end -------- 04:24:56 INFO - PROCESS | 11951 | 04:24:57 INFO - TEST-INFO | 11951: exit 0 04:24:57 INFO - TEST-OK | dromaeo_css | took 1115855ms 04:24:57 INFO - TEST-START | kraken 04:24:57 INFO - operating with platform_type : linux_ 04:24:57 INFO - Initialising browser for kraken test... 04:24:57 INFO - TEST-INFO | started process 12264 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgkd8vV/profile http://localhost:52973/getInfo.html) 04:25:01 INFO - PROCESS | 12264 | __metrics Screen width/height:1600/1200 04:25:01 INFO - PROCESS | 12264 | colorDepth:24 04:25:01 INFO - PROCESS | 12264 | Browser inner width/height: 1024/697 04:25:01 INFO - PROCESS | 12264 | __metrics 04:25:02 INFO - PROCESS | 12264 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:25:02 INFO - PROCESS | 12264 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:25:02 INFO - PROCESS | 12264 | console.error: PushService: 04:25:02 INFO - PROCESS | 12264 | stateChangeProcessEnqueue: Error transitioning state 04:25:02 INFO - PROCESS | 12264 | UnknownError 04:25:03 INFO - TEST-INFO | 12264: exit 0 04:25:03 INFO - Browser initialized. 04:25:03 INFO - Running cycle 1/1 for kraken test... 04:25:03 INFO - TEST-INFO | started process 12352 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgkd8vV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:25:15 INFO - PROCESS | 12352 | RSS: Main: 172941312 04:25:15 INFO - PROCESS | 12352 | 04:25:17 INFO - PROCESS | 12352 | Cycle 1(1): loaded http://localhost:52973/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52973/tests/kraken/driver.html?testName=audio-beat-detection) 04:25:18 INFO - PROCESS | 12352 | RSS: Main: 232701952 04:25:18 INFO - PROCESS | 12352 | 04:25:21 INFO - PROCESS | 12352 | Cycle 1(1): loaded http://localhost:52973/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52973/tests/kraken/driver.html?testName=audio-dft) 04:25:22 INFO - PROCESS | 12352 | RSS: Main: 216870912 04:25:22 INFO - PROCESS | 12352 | 04:25:25 INFO - PROCESS | 12352 | Cycle 1(1): loaded http://localhost:52973/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52973/tests/kraken/driver.html?testName=audio-fft) 04:25:25 INFO - PROCESS | 12352 | RSS: Main: 298598400 04:25:25 INFO - PROCESS | 12352 | 04:25:27 INFO - PROCESS | 12352 | Cycle 1(1): loaded http://localhost:52973/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52973/tests/kraken/driver.html?testName=audio-oscillator) 04:25:28 INFO - PROCESS | 12352 | RSS: Main: 207593472 04:25:28 INFO - PROCESS | 12352 | 04:25:30 INFO - PROCESS | 12352 | Cycle 1(1): loaded http://localhost:52973/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52973/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:25:31 INFO - PROCESS | 12352 | RSS: Main: 212910080 04:25:31 INFO - PROCESS | 12352 | 04:25:34 INFO - PROCESS | 12352 | Cycle 1(1): loaded http://localhost:52973/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52973/tests/kraken/driver.html?testName=imaging-darkroom) 04:25:34 INFO - PROCESS | 12352 | RSS: Main: 249126912 04:25:34 INFO - PROCESS | 12352 | 04:25:38 INFO - PROCESS | 12352 | Cycle 1(1): loaded http://localhost:52973/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52973/tests/kraken/driver.html?testName=imaging-desaturate) 04:25:38 INFO - PROCESS | 12352 | RSS: Main: 247689216 04:25:38 INFO - PROCESS | 12352 | 04:25:41 INFO - PROCESS | 12352 | Cycle 1(1): loaded http://localhost:52973/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52973/tests/kraken/driver.html?testName=json-parse-financial) 04:25:42 INFO - PROCESS | 12352 | RSS: Main: 247848960 04:25:42 INFO - PROCESS | 12352 | 04:25:43 INFO - PROCESS | 12352 | Cycle 1(1): loaded http://localhost:52973/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52973/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:25:44 INFO - PROCESS | 12352 | RSS: Main: 210067456 04:25:44 INFO - PROCESS | 12352 | 04:25:45 INFO - PROCESS | 12352 | Cycle 1(1): loaded http://localhost:52973/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52973/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:25:46 INFO - PROCESS | 12352 | RSS: Main: 217116672 04:25:46 INFO - PROCESS | 12352 | 04:25:48 INFO - PROCESS | 12352 | Cycle 1(1): loaded http://localhost:52973/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52973/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:25:49 INFO - PROCESS | 12352 | RSS: Main: 221384704 04:25:49 INFO - PROCESS | 12352 | 04:25:51 INFO - PROCESS | 12352 | Cycle 1(1): loaded http://localhost:52973/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52973/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:25:52 INFO - PROCESS | 12352 | RSS: Main: 223096832 04:25:52 INFO - PROCESS | 12352 | 04:25:55 INFO - PROCESS | 12352 | Cycle 1(1): loaded http://localhost:52973/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52973/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:25:55 INFO - PROCESS | 12352 | RSS: Main: 221253632 04:25:55 INFO - PROCESS | 12352 | 04:25:57 INFO - PROCESS | 12352 | Cycle 1(1): loaded http://localhost:52973/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52973/tests/kraken/driver.html?testName=ai-astar) 04:25:57 INFO - PROCESS | 12352 | RSS: Main: 271917056 04:25:57 INFO - PROCESS | 12352 | 04:25:57 INFO - PROCESS | 12352 | __start_tp_report 04:25:57 INFO - PROCESS | 12352 | _x_x_mozilla_page_load 04:25:57 INFO - PROCESS | 12352 | _x_x_mozilla_page_load_details 04:25:57 INFO - PROCESS | 12352 | |i|pagename|runs| 04:25:57 INFO - PROCESS | 12352 | |0;ai-astar;110;113;111;111;112;110;111;111;110;111 04:25:57 INFO - PROCESS | 12352 | |1;audio-beat-detection;147;119;118;158;124;121;118;119;153;120 04:25:57 INFO - PROCESS | 12352 | |2;audio-dft;160;161;160;161;161;164;161;161;161;162 04:25:57 INFO - PROCESS | 12352 | |3;audio-fft;75;77;76;108;75;76;76;76;99;75 04:25:57 INFO - PROCESS | 12352 | |4;audio-oscillator;83;82;122;83;86;82;101;81;82;114 04:25:57 INFO - PROCESS | 12352 | |5;imaging-gaussian-blur;116;116;117;117;117;117;116;116;117;117 04:25:57 INFO - PROCESS | 12352 | |6;imaging-darkroom;147;146;146;146;146;146;147;146;146;147 04:25:57 INFO - PROCESS | 12352 | |7;imaging-desaturate;96;95;95;95;94;94;95;95;95;96 04:25:57 INFO - PROCESS | 12352 | |8;json-parse-financial;51;51;50;50;51;52;61;50;51;51 04:25:57 INFO - PROCESS | 12352 | |9;json-stringify-tinderbox;61;61;61;61;61;61;62;62;60;60 04:25:57 INFO - PROCESS | 12352 | |10;stanford-crypto-aes;81;79;79;80;88;79;80;82;79;84 04:25:57 INFO - PROCESS | 12352 | |11;stanford-crypto-ccm;127;125;127;127;132;127;138;126;162;126 04:25:57 INFO - PROCESS | 12352 | |12;stanford-crypto-pbkdf2;164;164;162;162;162;162;162;159;164;160 04:25:57 INFO - PROCESS | 12352 | |13;stanford-crypto-sha256-iterative;65;71;66;66;67;64;65;65;65;64 04:25:57 INFO - PROCESS | 12352 | __end_tp_report 04:25:57 INFO - PROCESS | 12352 | __start_cc_report 04:25:57 INFO - PROCESS | 12352 | _x_x_mozilla_cycle_collect,1607 04:25:57 INFO - PROCESS | 12352 | __end_cc_report 04:25:57 INFO - PROCESS | 12352 | __startTimestamp1474370757773__endTimestamp 04:25:57 INFO - PROCESS | 12352 | 04:25:57 INFO - PROCESS | 12352 | ------- Summary: start ------- 04:25:57 INFO - PROCESS | 12352 | Number of tests: 14 04:25:57 INFO - PROCESS | 12352 | 04:25:57 INFO - PROCESS | 12352 | [#0] ai-astar Cycles:10 Average:111.00 Median:111.00 stddev:0.94 (0.8%) stddev-sans-first:0.93 04:25:57 INFO - PROCESS | 12352 | Values: 110.0 113.0 111.0 111.0 112.0 110.0 111.0 111.0 110.0 111.0 04:25:57 INFO - PROCESS | 12352 | 04:25:57 INFO - PROCESS | 12352 | [#1] audio-beat-detection Cycles:10 Average:129.70 Median:122.50 stddev:16.15 (13.2%) stddev-sans-first:15.87 04:25:57 INFO - PROCESS | 12352 | Values: 147.0 119.0 118.0 158.0 124.0 121.0 118.0 119.0 153.0 120.0 04:25:57 INFO - PROCESS | 12352 | 04:25:57 INFO - PROCESS | 12352 | [#2] audio-dft Cycles:10 Average:161.20 Median:161.00 stddev:1.14 (0.7%) stddev-sans-first:1.12 04:25:57 INFO - PROCESS | 12352 | Values: 160.0 161.0 160.0 161.0 161.0 164.0 161.0 161.0 161.0 162.0 04:25:57 INFO - PROCESS | 12352 | 04:25:57 INFO - PROCESS | 12352 | [#3] audio-fft Cycles:10 Average:81.30 Median:76.00 stddev:11.91 (15.7%) stddev-sans-first:12.41 04:25:57 INFO - PROCESS | 12352 | Values: 75.0 77.0 76.0 108.0 75.0 76.0 76.0 76.0 99.0 75.0 04:25:57 INFO - PROCESS | 12352 | 04:25:57 INFO - PROCESS | 12352 | [#4] audio-oscillator Cycles:10 Average:91.60 Median:82.00 stddev:15.21 (18.6%) stddev-sans-first:15.81 04:25:57 INFO - PROCESS | 12352 | Values: 83.0 82.0 122.0 83.0 86.0 82.0 101.0 81.0 82.0 114.0 04:25:57 INFO - PROCESS | 12352 | 04:25:57 INFO - PROCESS | 12352 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.50 04:25:57 INFO - PROCESS | 12352 | Values: 116.0 116.0 117.0 117.0 117.0 117.0 116.0 116.0 117.0 117.0 04:25:57 INFO - PROCESS | 12352 | 04:25:57 INFO - PROCESS | 12352 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.00 stddev:0.48 (0.3%) stddev-sans-first:0.44 04:25:57 INFO - PROCESS | 12352 | Values: 147.0 146.0 146.0 146.0 146.0 146.0 147.0 146.0 146.0 147.0 04:25:57 INFO - PROCESS | 12352 | 04:25:57 INFO - PROCESS | 12352 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.67 (0.7%) stddev-sans-first:0.60 04:25:57 INFO - PROCESS | 12352 | Values: 96.0 95.0 95.0 95.0 94.0 94.0 95.0 95.0 95.0 96.0 04:25:57 INFO - PROCESS | 12352 | 04:25:57 INFO - PROCESS | 12352 | [#8] json-parse-financial Cycles:10 Average:51.80 Median:51.00 stddev:3.29 (6.5%) stddev-sans-first:3.48 04:25:57 INFO - PROCESS | 12352 | Values: 51.0 51.0 50.0 50.0 51.0 52.0 61.0 50.0 51.0 51.0 04:25:57 INFO - PROCESS | 12352 | 04:25:57 INFO - PROCESS | 12352 | [#9] json-stringify-tinderbox Cycles:10 Average:61.00 Median:61.00 stddev:0.67 (1.1%) stddev-sans-first:0.71 04:25:57 INFO - PROCESS | 12352 | Values: 61.0 61.0 61.0 61.0 61.0 61.0 62.0 62.0 60.0 60.0 04:25:57 INFO - PROCESS | 12352 | 04:25:57 INFO - PROCESS | 12352 | [#10] stanford-crypto-aes Cycles:10 Average:81.10 Median:80.50 stddev:2.92 (3.6%) stddev-sans-first:3.10 04:25:57 INFO - PROCESS | 12352 | Values: 81.0 79.0 79.0 80.0 88.0 79.0 80.0 82.0 79.0 84.0 04:25:57 INFO - PROCESS | 12352 | 04:25:57 INFO - PROCESS | 12352 | [#11] stanford-crypto-ccm Cycles:10 Average:131.70 Median:127.00 stddev:11.33 (8.9%) stddev-sans-first:11.89 04:25:57 INFO - PROCESS | 12352 | Values: 127.0 125.0 127.0 127.0 132.0 127.0 138.0 126.0 162.0 126.0 04:25:57 INFO - PROCESS | 12352 | 04:25:57 INFO - PROCESS | 12352 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.10 Median:162.00 stddev:1.66 (1.0%) stddev-sans-first:1.62 04:25:57 INFO - PROCESS | 12352 | Values: 164.0 164.0 162.0 162.0 162.0 162.0 162.0 159.0 164.0 160.0 04:25:57 INFO - PROCESS | 12352 | 04:25:57 INFO - PROCESS | 12352 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.80 Median:65.50 stddev:2.04 (3.1%) stddev-sans-first:2.15 04:25:57 INFO - PROCESS | 12352 | Values: 65.0 71.0 66.0 66.0 67.0 64.0 65.0 65.0 65.0 64.0 04:25:57 INFO - PROCESS | 12352 | -------- Summary: end -------- 04:25:57 INFO - PROCESS | 12352 | 04:25:58 INFO - TEST-INFO | 12352: exit 0 04:25:59 INFO - TEST-OK | kraken | took 61796ms 04:25:59 INFO - SUITE-END | took 1177s 04:25:59 INFO - Completed test suite (00:19:38) 04:25:59 INFO - javascript benchmark 04:25:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2936.6622838373582, 2983.313837817302, 2983.7314641682246, 3004.864781084851, 3027.0767415938144, 5108.948910510903, 5173.974130129349, 5208.322918020652, 5209.687418754873, 5233.371995360562, 5198.116320225561, 5201.505856943594, 5242.606804489669, 5244.921326180111, 5284.947150528503, 4712.3638308828295, 4792.9281060784115, 4809.927851082237, 4812.109759694467, 4889.046635906, 4858.684185527947, 4909.0, 4923.064617722639, 4930.03864246472, 4952.851414457572, 4647.488776234628, 4691.9912218873105, 4733.0, 4836.51634836517, 4840.5885499732385, 4826.22780355145, 4837.516248375135, 4857.271409288635, 4883.609311255091, 4914.68054576455, 4768.165571025057, 4796.3524924135145, 4810.11012962603, 4830.903381932377, 4859.878503037417, 5105.259737338065, 5142.537171654531, 5267.446918073585, 5281.947180528223, 5338.225957236218, 5529.585281103933, 5537.473939975736, 5539.169124631338, 5540.806071787509, 5543.861403464905, 5467.726613669301, 5531.557475401958, 5543.695096769676, 5557.861053473695, 5565.220869078333, 5228.137357335997, 5229.738513074369, 5231.293775340357, 5231.476852314776, 5285.947140528584, 5633.859153521113, 5727.5990680652785, 5733.88532229353, 5747.109198074305, 5809.157672137601, 5378.731063446812, 5415.702136382458, 5423.674579525241, 5470.042742520043, 5492.80775172867, 5552.028395030853, 5560.749766260528, 5561.221428999943, 5593.916091258634, 5637.746301416446, 5525.0, 5566.777328906879, 5574.637648552913, 5576.442355764391, 5587.1339942309005, 5439.5104440600535, 5497.038018346773, 5552.361478429952, 5578.24693666358, 5645.5201307888465, 4339.0, 4343.782810859444, 4352.194843953878, 4399.0, 4402.163588918095, 3881.5924327945722, 3886.747361421555, 3889.086064774776, 3922.5489068756888, 3930.292547341506, 5103.948960510347, 5169.095408303532, 5354.196870469383, 5375.5162035417, 5433.673979561239, 5514.200440936042, 5517.531009864127, 5559.11054231321, 5588.44115588446, 5689.061304884648, 5309.044372012998, 5310.52205301517, 5312.0, 5326.121190003606, 5374.0, 4792.664513484023, 4795.520447955247, 4797.4482934462985, 4800.495947925488, 4856.17445034345, 5685.431456854281, 5733.283339582552, 5739.913901291484, 5754.99287624664, 5786.594938354275, 5585.162225666182, 5612.270404847343, 5622.0, 5685.374608793112, 5692.459216374438, 5762.884742305214, 5815.476607105381, 5816.47651711348, 5838.766449341979, 5874.676892770939, 5489.984352894728, 5547.5007249347755, 5554.944560533486, 5556.416576259435, 5556.638818476786, 5419.620626556103, 5463.262459567907, 5469.75386107634, 5549.112142057413, 5652.660840349592, 5417.756200971045, 5419.891602167856, 5427.1045277528765, 5427.1316589345515, 5432.375276843213, 5420.430854760193, 5431.2667789847865, 5457.7271136443815, 5473.480019398151, 5505.917411238915, 5522.006038913034, 5522.779088836402, 5525.861853453695, 5540.252065971043, 5567.276254086943, 5503.064479038653, 5510.035743744748, 5511.448855114457, 5515.117581186991, 5546.4453554644215, 5194.3766747990485, 5227.163653815372, 5233.607479439133, 5430.864803494341, 5509.862253443696], "value": 5165.652298019235, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [561.7163332517079, 605.1104875832526, 615.8337248942786, 617.1267656266383, 623.6538721009838, 27867.163985080428, 28516.287092822662, 31182.0, 31202.063938081894, 45514.0, 43341.34987975175, 43792.12415751683, 43813.7809310953, 43821.78089109558, 43893.341599876025, 9067.319951003668, 9214.216791572724, 9215.953920230422, 9217.446953182796, 9221.123993220634, 9027.95486022569, 9143.314251431137, 9144.222741067017, 9148.405353652024, 9148.634054637807, 9126.041765614615, 9177.219936305422, 9184.265258779282, 9187.219086377632, 9188.0, 9133.588988495534, 9175.220106290973, 9183.0, 9189.264858811279, 9193.942696589844, 9084.273258139334, 9111.362204645677, 9115.863262051074, 9120.45277283365, 9123.08769123089, 41396.0, 41699.1660166795, 41700.37449438261, 41719.58280417187, 41773.582264177574, 3385.1875549867855, 3388.0682812226587, 3403.5745531808525, 3496.7202623790035, 3501.0, 30687.846560767277, 31416.21459463509, 31460.0, 31469.8426507866, 31519.684803152133, 4103.8974025649295, 4153.514204658991, 4169.833206671729, 4333.566643335673, 4413.44831896013, 328747.0, 329497.0, 356941.0, 356971.0, 357863.0, 55261.72369138129, 55567.72216138974, 55578.55371570265, 56000.159997600065, 56005.0, 58891.705541472016, 59208.0, 59311.11033334504, 59370.01654693809, 59372.40627593755, 17029.318827247018, 17061.06164160983, 17111.315547378214, 17279.65440691179, 17283.13584320779, 13441.663958400923, 13630.86369136296, 13632.863671363359, 13635.045546811827, 13638.454461821417, 16093.436729714638, 16194.271257793427, 16245.10651914156, 16278.104704241381, 16365.263563139688, 5549.807825908824, 5622.240997465372, 5628.577856660685, 5677.17680936262, 5840.627452548648, 887.4143065576689, 1029.361795686664, 1039.542601255445, 1060.0, 1064.414571985405, 29341.853290733838, 29904.70095299019, 30257.0, 30779.0, 30936.845315773277, 1092.1972350322508, 1111.394290111891, 1126.3467189030343, 1130.8416821644976, 1181.6041626055196, 15224.543263702106, 15513.767293490606, 15672.138032408098, 15934.760978585331, 16326.75509867353, 535427.0, 538044.0, 538554.0, 538635.0, 539568.0, 55380.72309638507, 55511.72244138754, 55607.16589251164, 55660.0, 55854.0, 1183.0594677231622, 1210.9091818113677, 1311.318114580432, 1321.4119716726157, 1337.7658909690765, 29980.550291746076, 30058.24854378658, 31261.0, 32007.519887201244, 33711.0, 52339.47660523346, 52760.47239527556, 52931.470685292654, 52964.73517632387, 53045.0, 486481.3787103827, 493165.0, 493683.0, 494181.0, 494190.0, 23627.881860590587, 23830.0, 27079.72920270772, 27537.72462275432, 27564.58653120245, 42445.57554424416, 42471.15057698767, 42574.0, 43036.78481607572, 43988.0, 37055.44416833696, 37455.81272093622, 37462.438063428526, 37495.0, 38796.80601596974, 36652.45021324629, 36812.0, 36829.44755828611, 37205.0, 37407.43888841615, 35807.0, 36058.27883442264, 36250.0, 37413.81293093517, 37443.25113497769, 32293.031209063767, 32448.83775581107, 33593.328133436706, 34167.65832341645, 34253.486197707556, 25970.610440843026, 25995.870020649774, 26023.479530409666, 26029.95880164772, 26640.733592663826], "value": 19372.837352859264, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [359.3854508789969, 372.92168644584655, 384.2161989541335, 388.7492567294095, 408.1001391930793, 13756.0, 20172.19311227551, 20669.793302066995, 20981.68527472089, 21161.894190529063, 17815.198316075774, 18637.347692830754, 19156.90421547894, 19317.613647727037, 19342.0, 5630.859228519279, 5878.470937615635, 5882.058870580708, 5886.381929897363, 5887.705614719247, 5630.183623374608, 5774.335951365584, 5793.6234144780665, 5812.680302583356, 5815.244018277618, 5753.050746626823, 5756.798512052079, 5756.8272951811305, 5757.42425757423, 5788.594798364116, 5578.497935185833, 5719.0, 5742.4831765141125, 5796.50729687977, 5798.478136967672, 5178.559822415099, 5179.922301165486, 5185.144451165553, 5185.403678576937, 5188.481151884819, 23747.168849090373, 24436.755632443626, 24492.0, 25935.481290374275, 26153.869230653916, 2306.5271619318, 2403.9519209615883, 2459.938501537458, 2733.0570953021174, 2733.412316351994, 15180.620484487976, 15393.230338483143, 15854.286557104842, 16088.597785055466, 16138.515844524683, 1964.764228292614, 2026.8259290858882, 2067.7725450200464, 2087.1964293746864, 2184.803367696915, 99811.0, 102002.48998755033, 102096.48951755193, 104417.0, 105097.0, 15462.273910848817, 17821.4951542686, 17906.731399029024, 18007.459776206735, 18291.81708182928, 32216.35567288641, 32239.35521289561, 32250.83874580659, 32328.35343293168, 32379.676203237665, 10155.543000564992, 10303.896961030294, 10377.325473844176, 10380.221483388776, 10825.78348433027, 8336.66653333872, 8624.525651089125, 8721.738347849574, 8787.340949428819, 8880.600372983103, 8649.740507784776, 8969.820603587892, 9017.684381046633, 9033.457992520469, 9045.185933266037, 3090.3973725123387, 3291.6708329167373, 3355.1248308204063, 3360.3783300089563, 3512.806795626214, 837.9706710265111, 895.4227859700409, 949.9145076943108, 977.4086677560116, 977.4917043137528, 18192.545186370447, 19013.524661883563, 19258.0, 19258.4222473326, 19273.903630482037, 925.717656114878, 927.536231884058, 965.290511474066, 997.8203923293877, 1009.8535712321819, 10436.217283703752, 10450.16398688103, 10642.708364477096, 10752.19358548112, 10778.94610526958, 444517.0, 448128.0, 456356.0, 457644.0, 458835.0, 89755.0, 89771.0, 90381.54809225912, 91236.0, 91984.0, 973.0852998181545, 986.6645340584236, 1016.588281745894, 1016.9237307201989, 1017.013496907998, 16335.918320408322, 16389.42637007699, 16869.156542172597, 17079.658406831546, 17235.482935511955, 42475.1420987106, 42646.0, 44181.77909110434, 44711.77644111759, 45209.32186017147, 56214.71892640674, 59938.70030649819, 60241.69879150576, 61538.69230653818, 62756.37243627505, 8522.616482258189, 8657.264132548804, 8721.694740684046, 8807.691730789647, 8811.206991370807, 12410.379481026093, 12637.810432843331, 12699.873001270238, 12845.357732113544, 12904.483820647445, 11361.431928403712, 11379.943100284445, 11558.30650160993, 11571.942140289244, 11808.645740627795, 10713.678589642323, 11235.831462528233, 11451.427428628702, 11620.070394368598, 11736.530538778694, 11025.393603351893, 11282.255060226003, 11419.657410277705, 11635.301881887113, 11858.584949526725, 7659.502132361378, 8545.914540854512, 8554.78613034679, 8911.465312081296, 9109.316801240066, 10179.236557258384, 10926.289791163548, 10971.23201375896, 11222.607208747655, 11333.603323883624], "value": 10075.469597001134, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1279.5009946121008, 1421.2833440552954, 1443.7906503556983, 1524.9008814427061, 1644.868410527158, 7041.155061392634, 7492.850142997137, 7701.037370328709, 7706.4990775599545, 7896.091949425818, 7701.422393320509, 7764.068311802592, 7782.455228134018, 7783.805404864867, 7786.961065194666, 2290.530441259542, 2312.710911136108, 2313.8958746856433, 2315.3169814904645, 2317.536492701458, 2233.8547994380397, 2250.144944920936, 2288.187693368847, 2290.4617414907484, 2290.7365652949875, 2192.4738062864876, 2215.0, 2235.865848049122, 2256.0524579676576, 2259.3334966184934, 2189.879556624385, 2206.3160420269687, 2213.269621025066, 2214.2250212425683, 2215.2135991722866, 2175.945601359963, 2181.694562761215, 2186.1146235774536, 2188.8796116213603, 2192.7039849620255, 7693.038370203724, 7758.612069396508, 7759.883601745978, 7760.146383897767, 7909.485883417559, 4879.780409881529, 5097.745112744348, 5099.260607211933, 5104.9744751276, 5121.28302037715, 3204.038788363505, 3233.919152021195, 3290.8683652653867, 3316.088075779156, 3348.68187522185, 1130.3387518301804, 1151.1193936638479, 1979.9010049497467, 1999.3701983875033, 2000.599880024001, 10614.575416983389, 10662.200334974908, 10721.088707459876, 10723.0, 10725.839112413241, 4165.4584903962295, 4307.547707490731, 4354.738715677006, 4390.692651514427, 4400.647948164204, 8466.576671166416, 8581.184787445183, 8616.095309992486, 8621.956890215508, 8645.95677021611, 4927.7536123193695, 4944.777485013183, 4949.802007919643, 4952.752362381939, 4955.231939049453, 4001.379786133154, 4048.3927410888014, 4149.921020534623, 4155.8337666493, 4649.465311489222, 3307.9007629771145, 3574.660407261306, 3596.604373518911, 3639.454081887738, 3641.617630148849, 1036.528379587286, 1036.6786296248188, 1044.1281529922448, 1052.736815796051, 1066.6693325069252, 886.4814083761072, 892.6518657723493, 900.6622516556291, 907.6732376344511, 921.6866265469772, 5160.148575485078, 5183.481651834786, 5374.731263436813, 5394.244805727201, 5427.45725427462, 441.35782436554734, 442.33207856136823, 448.8339314453673, 457.54728736262234, 469.48825779900073, 3476.7392445566684, 3685.94471082934, 3686.7787932724123, 3803.391457366808, 3804.923901521954, 9659.130678238993, 10099.495025248709, 10105.090541851268, 10117.747056323504, 10128.442935638463, 9964.501774911225, 9994.300398972147, 9997.650082247086, 10001.549930253155, 10006.69979900604, 7225.927740722631, 7297.525660832135, 7513.924860751323, 7537.849243015109, 7734.497257678233, 5465.672059676433, 5651.237082993744, 5652.3217213934595, 5708.086706127166, 5756.366799651951, 8842.336824738299, 8935.285177185939, 9127.132922372364, 9153.313501487282, 9159.267258619428, 9046.728598142066, 9096.1813436791, 9110.088991100836, 9175.44947303164, 9178.173964343241, 6236.220472440945, 6398.072279519351, 6780.830479238059, 6920.688569014305, 6927.376536111573, 7757.7672669819995, 7791.298783109547, 7801.336886364723, 7801.9609901950125, 7829.373650107864, 7050.0, 7142.857142857218, 7172.282771722995, 7174.892376614459, 7296.635168241673, 6958.412342271557, 7055.753048643478, 7081.504294699322, 7269.491135620456, 7325.963370183115, 6472.708728107153, 6497.285298617054, 6500.479961603155, 6502.837429064311, 6509.99095140254, 4785.952140478551, 4803.807847686054, 4841.346418233635, 4877.5366340197625, 4924.63065270099, 4855.320255164351, 4917.1149193145575, 4943.505649435027, 5032.4837581209395, 5060.595152387727], "value": 4234.487574750177, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [325.1043375500497, 328.9522869660133, 329.823544403744, 333.20364329253084, 337.4566947214985, 5160.948390516099, 5200.713960732158, 5214.296070030545, 5226.817061402847, 5230.320058392414, 4864.854054378366, 4950.6287028472825, 5020.272060551218, 5082.84751457456, 5139.4346621871655, 4109.712320137576, 4136.3381858902585, 4145.5232648245465, 4148.8962775930695, 4159.83360665573, 4132.2561938850995, 4140.958590414088, 4143.440635514212, 4146.212219678267, 4146.917061658765, 3838.251540949516, 3915.138669492707, 4101.753894766323, 4111.48606424197, 4145.253854306224, 4073.551909289976, 4101.323281658552, 4128.876133715993, 4129.769924240922, 4143.7306575072525, 3695.427208782643, 3702.9259414811822, 3705.073731567108, 3707.480952666629, 3716.4425336199515, 4866.197077482211, 5081.466446023152, 5104.489551044903, 5167.932826869245, 5171.37943446785, 4621.8151273949, 4625.907481850378, 4631.785075581239, 4634.351190833286, 4658.161530924433, 5320.228566857822, 5332.973335133338, 5379.488948549882, 5401.513863752242, 5406.8918621627745, 3711.0, 3862.2854771867014, 3890.8443662253476, 3915.7454765440157, 3930.941035884464, 5544.168374743821, 5562.833115006556, 5579.8605034874045, 5585.162225666182, 5606.607537472338, 3991.820368083443, 3993.7004724645767, 3996.660283875845, 4009.498812648419, 4029.4083836884265, 3916.980415097906, 4029.0531725044593, 4065.1259979104634, 4067.7017485121405, 4071.0, 4598.793054312564, 4600.125976064537, 4601.15803304238, 4615.95384046162, 4619.676622636383, 5416.187571864173, 5417.729113544307, 5419.891602168013, 5435.0, 5453.754581043862, 1352.6280272924828, 1371.1841454334656, 1384.7398867031154, 1391.6451304917182, 1392.498700467851, 876.4390789894345, 895.6730793260413, 898.7079199260136, 901.4816480523725, 908.2643059122, 4614.3309220163555, 4621.0, 4626.653001024936, 4645.908211570279, 4648.442186937522, 4900.264960255919, 4940.802367905316, 4980.651354405157, 5001.274815151783, 5018.473060328686, 4267.4025636410925, 4576.908461830812, 4607.677462577587, 4623.53764623535, 4643.117807616542, 2596.818222724429, 2599.025365487942, 2603.401217719897, 2604.6093086037245, 2606.27024433159, 5040.294358789773, 5046.671966322251, 5051.444341122474, 5059.3185611625895, 5086.19440098767, 4614.930776038362, 4965.652404331734, 4980.726060066731, 4981.28145789702, 4985.928025474468, 4998.5751211146735, 5008.248762685554, 5019.899602007867, 5020.723860187724, 5033.583879737673, 4828.430760002942, 4848.927266091082, 4883.95116048835, 4894.510548945077, 4905.190643543775, 5074.797008119634, 5524.116141417354, 5526.198701188386, 5526.22632831412, 5536.307961504812, 5793.0, 5794.3626201117, 5795.82612521625, 5809.622374545641, 5813.15709222152, 3830.6743926766535, 4023.416604592376, 4043.0700938783657, 4050.7772072535113, 4052.8378864845076, 5315.0, 5483.341998959991, 5522.889542209053, 5540.972295138659, 5630.268065151504, 5384.569234461312, 5427.480305514462, 5459.262999495176, 5463.890722185613, 5467.289252397322, 5283.067897317026, 5473.31583552056, 5485.177223416519, 5487.957288115245, 5489.478499542537, 5367.892642147213, 5383.434739352469, 5415.864603384789, 5418.187271909245, 5450.672959622435, 5435.076037073786, 5494.780208791603, 5495.395506494362, 5497.0, 5498.917516237179, 5129.153689641167, 5241.6068794839775, 5272.789088436573, 5354.973225134006, 5367.0], "value": 4036.4934489436637, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [719.1149050659517, 722.1658983873627, 740.5963749756382, 774.961251937403, 829.6183755472484, 15642.608934776636, 16900.577485562837, 16985.660286794257, 17103.486895393125, 17155.399561015365, 14380.928095359508, 14604.0, 14740.778888316683, 15023.173725445096, 15166.165860877647, 769.7498313048254, 786.7403756760284, 787.6888628991574, 788.0543347982415, 791.0151860933146, 649.1398896462183, 666.0941120076692, 750.8198032472222, 774.0904437286194, 774.3727580659655, 824.7979245084963, 825.8224481736426, 825.9917400825975, 826.1655727715021, 826.2893911236362, 820.7127505373131, 820.8645573480399, 821.5070957425556, 821.5892053973014, 822.3873214455239, 499.3109508877762, 505.32791387454967, 571.7884382778399, 584.9532037436995, 601.7683191971079, 13867.930660346734, 14022.789658155136, 14034.157950523002, 14063.226522541256, 14065.156090634595, 13772.24252666103, 14107.647308817259, 14611.561653150422, 14632.487862924852, 14740.189289589069, 12621.873781262162, 12757.018905015917, 13105.475780968749, 13186.340682965812, 13317.86682133176, 4361.912761744779, 4433.423654924872, 4461.308497182942, 4470.105978804252, 4492.3710680504755, 82647.5867620664, 85606.5719671404, 86759.56620216859, 86857.0, 88621.55689221577, 11113.777724445466, 11142.164337674707, 11142.944285278683, 11387.886121138683, 11444.771104578029, 21734.78265217359, 22751.658725119138, 23028.0, 23061.19285824988, 23225.53548929012, 8474.152584741476, 8476.27951624119, 8481.703140390056, 8603.225709686034, 8924.330675199386, 4660.930086048712, 4739.3601863748645, 4742.605760999223, 4807.95192048082, 4813.205821039559, 3201.759868009862, 3276.164578032634, 3330.234046169346, 3336.366090442808, 3339.632640409553, 522.874510117569, 525.8396189162265, 526.1292560811786, 528.1022262154353, 550.4028129479559, 577.3735496985734, 600.7326739600836, 633.3349982518338, 657.329523885634, 658.8517583543664, 8641.351898607629, 9433.528323583794, 9623.422594644344, 10512.842307365396, 10649.680509584725, 605.4218221598363, 670.9667112646567, 673.5285300289817, 680.4624346766099, 728.0971595221887, 4523.773811309421, 4746.0, 4816.494268101869, 4820.132376172252, 4954.529319714621, 97081.51459242658, 111512.4424377873, 112519.43740281246, 117003.0, 121766.39116804536, 78748.21251787408, 80861.0, 83452.58273708592, 99570.5021474888, 100110.0, 50632.493675062775, 51190.0, 71192.28807711856, 81818.77265341832, 81930.59034704907, 9481.336306458757, 10236.539355729159, 10257.0, 10288.897111029093, 10309.123724483503, 35346.0, 36495.175241238365, 41979.58020419879, 49679.0, 49724.25413618871, 63886.36113638804, 70105.64947175435, 71263.28736712773, 71658.64170679114, 74549.0, 12570.470669234348, 13126.0, 13166.144200626928, 13201.867981320063, 13970.231637260049, 14291.213983231022, 14692.632684182981, 14735.92632036833, 15109.39562417491, 15142.470013549473, 12853.293068880927, 12931.159474634118, 12982.0, 13168.341582921008, 14083.577492675236, 13140.802887956497, 13556.525521606696, 14069.507567235098, 14671.779923301372, 14683.265836708335, 13142.540011099565, 13513.189208647513, 14775.482858099915, 14900.0, 14929.402823886923, 11163.162762792661, 11481.600952829689, 11831.34927578957, 12442.688932776391, 12943.741125177632, 11284.492197851281, 11565.7108572283, 11986.280823150639, 11997.940010299893, 12314.938425307817], "value": 7417.992952817074, "unit": "score"}], "name": "dromaeo_css", "value": 7097.755979896903, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 113.0, 111.0, 111.0, 112.0, 110.0, 111.0, 111.0, 110.0, 111.0], "value": 111.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 119.0, 118.0, 158.0, 124.0, 121.0, 118.0, 119.0, 153.0, 120.0], "value": 129.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 160.0, 161.0, 161.0, 164.0, 161.0, 161.0, 161.0, 162.0], "value": 161.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 76.0, 108.0, 75.0, 76.0, 76.0, 76.0, 99.0, 75.0], "value": 81.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 122.0, 83.0, 86.0, 82.0, 101.0, 81.0, 82.0, 114.0], "value": 91.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 117.0, 117.0, 117.0, 117.0, 116.0, 116.0, 117.0, 117.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 146.0, 146.0, 146.0, 147.0, 146.0, 146.0, 147.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 95.0, 95.0, 94.0, 94.0, 95.0, 95.0, 95.0, 96.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 51.0, 50.0, 50.0, 51.0, 52.0, 61.0, 50.0, 51.0, 51.0], "value": 51.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 61.0, 61.0, 61.0, 61.0, 62.0, 62.0, 60.0, 60.0], "value": 61.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 79.0, 79.0, 80.0, 88.0, 79.0, 80.0, 82.0, 79.0, 84.0], "value": 81.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 125.0, 127.0, 127.0, 132.0, 127.0, 138.0, 126.0, 162.0, 126.0], "value": 131.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 164.0, 162.0, 162.0, 162.0, 162.0, 162.0, 159.0, 164.0, 160.0], "value": 162.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 71.0, 66.0, 66.0, 67.0, 64.0, 65.0, 65.0, 65.0, 64.0], "value": 65.8, "unit": "score"}], "name": "kraken", "value": 1486.1999999999998, "alertThreshold": 2.0}]} 04:25:59 INFO - Return code: 0 04:25:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:25:59 INFO - # TBPL SUCCESS # 04:25:59 INFO - Running post-action listener: _resource_record_post_action 04:25:59 INFO - [mozharness: 2016-09-20 11:25:59.591054Z] Finished run-tests step (success) 04:25:59 INFO - Running post-run listener: _resource_record_post_run 04:25:59 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 4096; Write bytes: 410533888; Read time: 16; Write time: 262236 04:25:59 INFO - TinderboxPrint: CPU usage
13.1% 04:25:59 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 04:25:59 INFO - TinderboxPrint: I/O write bytes / time
410,533,888 / 262,236 04:25:59 INFO - TinderboxPrint: CPU idle
8,284.6 (86.9%) 04:25:59 INFO - TinderboxPrint: CPU user
1,194.9 (12.5%) 04:25:59 INFO - TinderboxPrint: Swap in / out
0 / 0 04:25:59 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 103759872; Read time: 0; Write time: 73032 04:25:59 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 0; Write bytes: 289058816; Read time: 0; Write time: 181000 04:26:00 INFO - Running post-run listener: _upload_blobber_files 04:26:00 INFO - Blob upload gear active. 04:26:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:26:00 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 04:26:00 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:26:00 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:26:00 INFO - (blobuploader) - INFO - Open directory for files ... 04:26:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:26:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:26:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:26:04 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:26:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:26:04 INFO - (blobuploader) - INFO - Done attempting. 04:26:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:26:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:26:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:26:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:26:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:26:05 INFO - (blobuploader) - INFO - Done attempting. 04:26:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:26:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:26:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:26:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:26:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:26:06 INFO - (blobuploader) - INFO - Done attempting. 04:26:06 INFO - (blobuploader) - INFO - Iteration through files over. 04:26:06 INFO - Return code: 0 04:26:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:26:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:26:06 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/822b01fd755229783812624c970559acf6275253b32e10ab6b0f868c213cdc72e3a8ae31257c2347d91a06d8f03a744a1c1525ff767d64f8456b5ad759dbbd23", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2a2e10c11bfbc1309b26c5fa795f7357ebbd84cfd80a82b530b58daf25fbd38d84ed663acd0f0b815d15fb56a965d08fc61fc821493032d493ed6036017b60fa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6878e105d4d7146efb9e0b01249f28e42a9ee689d4165ddbe4f3188a33db114b8d230f7ae952b706ac3c98dfda044a2c41e89407f83f90ae203fe5793ab63021"} 04:26:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:26:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:26:06 INFO - Contents: 04:26:06 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/822b01fd755229783812624c970559acf6275253b32e10ab6b0f868c213cdc72e3a8ae31257c2347d91a06d8f03a744a1c1525ff767d64f8456b5ad759dbbd23", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2a2e10c11bfbc1309b26c5fa795f7357ebbd84cfd80a82b530b58daf25fbd38d84ed663acd0f0b815d15fb56a965d08fc61fc821493032d493ed6036017b60fa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6878e105d4d7146efb9e0b01249f28e42a9ee689d4165ddbe4f3188a33db114b8d230f7ae952b706ac3c98dfda044a2c41e89407f83f90ae203fe5793ab63021"} 04:26:06 INFO - Running post-run listener: copy_logs_to_upload_dir 04:26:06 INFO - Copying logs to upload dir... 04:26:06 INFO - mkdir: /builds/slave/test/build/upload/logs 04:26:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1240.311396 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-09-20 04:26:06.112310) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-20 04:26:06.116274) ========= 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-1474369523.661552-1077558210 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/822b01fd755229783812624c970559acf6275253b32e10ab6b0f868c213cdc72e3a8ae31257c2347d91a06d8f03a744a1c1525ff767d64f8456b5ad759dbbd23", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2a2e10c11bfbc1309b26c5fa795f7357ebbd84cfd80a82b530b58daf25fbd38d84ed663acd0f0b815d15fb56a965d08fc61fc821493032d493ed6036017b60fa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6878e105d4d7146efb9e0b01249f28e42a9ee689d4165ddbe4f3188a33db114b8d230f7ae952b706ac3c98dfda044a2c41e89407f83f90ae203fe5793ab63021"} build_url:https://queue.taskcluster.net/v1/task/HqXZKlw1SL2sHTXeLp0DBA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011455 build_url: 'https://queue.taskcluster.net/v1/task/HqXZKlw1SL2sHTXeLp0DBA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/822b01fd755229783812624c970559acf6275253b32e10ab6b0f868c213cdc72e3a8ae31257c2347d91a06d8f03a744a1c1525ff767d64f8456b5ad759dbbd23", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2a2e10c11bfbc1309b26c5fa795f7357ebbd84cfd80a82b530b58daf25fbd38d84ed663acd0f0b815d15fb56a965d08fc61fc821493032d493ed6036017b60fa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6878e105d4d7146efb9e0b01249f28e42a9ee689d4165ddbe4f3188a33db114b8d230f7ae952b706ac3c98dfda044a2c41e89407f83f90ae203fe5793ab63021"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-20 04:26:06.149363) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 04:26:06.149763) ========= 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-1474369523.661552-1077558210 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004529 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 04:26:06.232576) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-20 04:26:06.236006) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-20 04:26:06.236401) ========= ========= Total master_lag: 0.27 =========