builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-018 starttime: 1474118757.77 results: success (0) buildid: 20160917033004 builduid: 6f0cff3df9d748ec8a591fc1b8f0763d revision: 752f33561e8f624642291842f8963efa9e9290d5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-17 06:25:57.768149) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-17 06:25:57.768586) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-17 06:25:57.768918) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-17 06:25:57.833464) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 06:25:57.833766) ========= 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=62c568580acbfdaac3eec7be000001dc-1474114211.156946-886130276 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-17 06:25:58-- 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% 37.6M=0s 2016-09-17 06:25:58 (37.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.110287 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 06:25:57.980147) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 06:25:57.980467) ========= 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=62c568580acbfdaac3eec7be000001dc-1474114211.156946-886130276 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020031 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 06:25:58.041057) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 06:25:58.041406) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 752f33561e8f624642291842f8963efa9e9290d5 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 752f33561e8f624642291842f8963efa9e9290d5 --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=62c568580acbfdaac3eec7be000001dc-1474114211.156946-886130276 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-17 06:25:58,363 truncating revision to first 12 chars 2016-09-17 06:25:58,364 Setting DEBUG logging. 2016-09-17 06:25:58,364 attempt 1/10 2016-09-17 06:25:58,364 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/752f33561e8f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-17 06:25:58,830 unpacking tar archive at: mozilla-inbound-752f33561e8f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.894866 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 06:25:58.956849) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-17 06:25:58.957534) ========= script_repo_revision: 752f33561e8f624642291842f8963efa9e9290d5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-17 06:25:58.959048) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-17 06:25:58.959766) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-17 06:25:58.971665) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-09-17 06:25:58.972363) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] 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=62c568580acbfdaac3eec7be000001dc-1474114211.156946-886130276 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 06:25:59 INFO - MultiFileLogger online at 20160917 06:25:59 in /builds/slave/test 06:25:59 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 06:25:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:25:59 INFO - {'append_to_log': False, 06:25:59 INFO - 'base_work_dir': '/builds/slave/test', 06:25:59 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 06:25:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:25:59 INFO - 'branch': 'Mozilla-Inbound', 06:25:59 INFO - 'buildbot_json_path': 'buildprops.json', 06:25:59 INFO - 'config_files': ('talos/linux_config.py',), 06:25:59 INFO - 'default_actions': ('clobber', 06:25:59 INFO - 'read-buildbot-config', 06:25:59 INFO - 'download-and-extract', 06:25:59 INFO - 'populate-webroot', 06:25:59 INFO - 'create-virtualenv', 06:25:59 INFO - 'install', 06:25:59 INFO - 'run-tests'), 06:25:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:25:59 INFO - 'download_minidump_stackwalk': True, 06:25:59 INFO - 'download_symbols': 'ondemand', 06:25:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:25:59 INFO - 'tooltool.py': '/tools/tooltool.py', 06:25:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:25:59 INFO - '/tools/misc-python/virtualenv.py')}, 06:25:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:25:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:25:59 INFO - 'installer_path': 'installer.exe', 06:25:59 INFO - 'log_level': 'info', 06:25:59 INFO - 'log_name': 'talos', 06:25:59 INFO - 'log_to_console': True, 06:25:59 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 06:25:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 06:25:59 INFO - 'opt_config_files': (), 06:25:59 INFO - 'pip_index': False, 06:25:59 INFO - 'sps_profile': False, 06:25:59 INFO - 'sps_profile_interval': 0, 06:25:59 INFO - 'suite': 'dromaeojs', 06:25:59 INFO - 'system_bits': '32', 06:25:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:25:59 INFO - 'title': 'talos-linux64-ix-018', 06:25:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:25:59 INFO - 'use_talos_json': True, 06:25:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:25:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:25:59 INFO - 'work_dir': 'build'} 06:25:59 INFO - [mozharness: 2016-09-17 13:25:59.390710Z] Running clobber step. 06:25:59 INFO - Running pre-action listener: _resource_record_pre_action 06:25:59 INFO - Running main action method: clobber 06:25:59 INFO - rmtree: /builds/slave/test/build 06:25:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:26:00 INFO - Running post-action listener: _resource_record_post_action 06:26:00 INFO - [mozharness: 2016-09-17 13:26:00.373048Z] Finished clobber step (success) 06:26:00 INFO - [mozharness: 2016-09-17 13:26:00.373147Z] Running read-buildbot-config step. 06:26:00 INFO - Running pre-action listener: _resource_record_pre_action 06:26:00 INFO - Running main action method: read_buildbot_config 06:26:00 INFO - Using buildbot properties: 06:26:00 INFO - { 06:26:00 INFO - "project": "", 06:26:00 INFO - "product": "firefox", 06:26:00 INFO - "script_repo_revision": "production", 06:26:00 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 06:26:00 INFO - "repository": "", 06:26:00 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 06:26:00 INFO - "buildid": "20160917033004", 06:26:00 INFO - "pgo_build": "True", 06:26:00 INFO - "basedir": "/builds/slave/test", 06:26:00 INFO - "buildnumber": 1712, 06:26:00 INFO - "slavename": "talos-linux64-ix-018", 06:26:00 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 06:26:00 INFO - "platform": "ubuntu64_hw", 06:26:00 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 06:26:00 INFO - "branch": "mozilla-inbound", 06:26:00 INFO - "repo_path": "integration/mozilla-inbound", 06:26:00 INFO - "revision": "752f33561e8f624642291842f8963efa9e9290d5", 06:26:00 INFO - "stage_platform": "linux64-pgo", 06:26:00 INFO - "builduid": "6f0cff3df9d748ec8a591fc1b8f0763d", 06:26:00 INFO - "slavebuilddir": "test" 06:26:00 INFO - } 06:26:00 INFO - Found installer url https://queue.taskcluster.net/v1/task/UG5r-Cb5QfW1wupiktEfmg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 06:26:00 INFO - Running post-action listener: _resource_record_post_action 06:26:00 INFO - [mozharness: 2016-09-17 13:26:00.375571Z] Finished read-buildbot-config step (success) 06:26:00 INFO - [mozharness: 2016-09-17 13:26:00.375659Z] Running download-and-extract step. 06:26:00 INFO - Running pre-action listener: _resource_record_pre_action 06:26:00 INFO - Running main action method: download_and_extract 06:26:00 INFO - mkdir: /builds/slave/test/build/tests 06:26:00 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')]} 06:26:00 INFO - trying https://queue.taskcluster.net/v1/task/UG5r-Cb5QfW1wupiktEfmg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 06:26:00 INFO - Downloading https://queue.taskcluster.net/v1/task/UG5r-Cb5QfW1wupiktEfmg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 06:26:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UG5r-Cb5QfW1wupiktEfmg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 06:26:00 INFO - Downloaded 1361 bytes. 06:26:00 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 06:26:00 INFO - Using the following test package requirements: 06:26:00 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 06:26:00 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 06:26:00 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 06:26:00 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 06:26:00 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 06:26:00 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 06:26:00 INFO - u'jsshell-linux-x86_64.zip'], 06:26:00 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 06:26:00 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 06:26:00 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 06:26:00 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 06:26:00 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 06:26:00 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 06:26:00 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 06:26:00 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 06:26:00 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 06:26:00 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 06:26:00 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 06:26:00 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 06:26:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UG5r-Cb5QfW1wupiktEfmg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 06:26:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UG5r-Cb5QfW1wupiktEfmg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 06:26:00 INFO - Fetch https://queue.taskcluster.net/v1/task/UG5r-Cb5QfW1wupiktEfmg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 06:26:01 INFO - Expected file size: 21936546 06:26:05 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 06:26:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UG5r-Cb5QfW1wupiktEfmg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 06:26:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UG5r-Cb5QfW1wupiktEfmg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 06:26:05 INFO - Fetch https://queue.taskcluster.net/v1/task/UG5r-Cb5QfW1wupiktEfmg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 06:26:06 INFO - Expected file size: 21936546 06:26:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UG5r-Cb5QfW1wupiktEfmg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 06:26:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UG5r-Cb5QfW1wupiktEfmg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 06:26:11 INFO - Fetch https://queue.taskcluster.net/v1/task/UG5r-Cb5QfW1wupiktEfmg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip into memory 06:26:11 INFO - Expected file size: 14414048 06:26:12 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')]} 06:26:12 INFO - trying https://queue.taskcluster.net/v1/task/UG5r-Cb5QfW1wupiktEfmg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 06:26:12 INFO - Downloading https://queue.taskcluster.net/v1/task/UG5r-Cb5QfW1wupiktEfmg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 06:26:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UG5r-Cb5QfW1wupiktEfmg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 06:26:13 INFO - Downloaded 59559389 bytes. 06:26:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UG5r-Cb5QfW1wupiktEfmg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 06:26:13 INFO - mkdir: /builds/slave/test/properties 06:26:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:26:13 INFO - Writing to file /builds/slave/test/properties/build_url 06:26:13 INFO - Contents: 06:26:13 INFO - build_url:https://queue.taskcluster.net/v1/task/UG5r-Cb5QfW1wupiktEfmg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 06:26:14 INFO - Running post-action listener: _resource_record_post_action 06:26:14 INFO - Running post-action listener: set_extra_try_arguments 06:26:14 INFO - [mozharness: 2016-09-17 13:26:14.030946Z] Finished download-and-extract step (success) 06:26:14 INFO - [mozharness: 2016-09-17 13:26:14.031173Z] Running populate-webroot step. 06:26:14 INFO - Running pre-action listener: _resource_record_pre_action 06:26:14 INFO - Running main action method: populate_webroot 06:26:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:26:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:26:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:26:14 INFO - '--tppagecycles', 06:26:14 INFO - '7'], 06:26:14 INFO - 'tests': ['tsvgm']}}, 06:26:14 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:26:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:26:14 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:26:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:26:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:26:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:26:14 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:26:14 INFO - 'talos_options': ['--disable-e10s'], 06:26:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:26:14 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:26:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:26:14 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:26:14 INFO - 'talos_options': ['--disable-e10s'], 06:26:14 INFO - 'tests': ['damp', 'tps']}, 06:26:14 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:26:14 INFO - 'tests': ['damp', 'tps']}, 06:26:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:26:14 INFO - 'tests': ['dromaeo_dom']}, 06:26:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:26:14 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:26:14 INFO - 'tests': ['basic_compositor_video']}, 06:26:14 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 06:26:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:26:14 INFO - 'tests': ['a11yr', 06:26:14 INFO - 'ts_paint', 06:26:14 INFO - 'tpaint', 06:26:14 INFO - 'sessionrestore', 06:26:14 INFO - 'sessionrestore_no_auto_restore', 06:26:14 INFO - 'tabpaint']}, 06:26:14 INFO - 'other-e10s': {'tests': ['a11yr', 06:26:14 INFO - 'ts_paint', 06:26:14 INFO - 'tpaint', 06:26:14 INFO - 'sessionrestore', 06:26:14 INFO - 'sessionrestore_no_auto_restore', 06:26:14 INFO - 'tabpaint']}, 06:26:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:26:14 INFO - 'ts_paint', 06:26:14 INFO - 'tpaint', 06:26:14 INFO - 'sessionrestore', 06:26:14 INFO - 'sessionrestore_no_auto_restore', 06:26:14 INFO - 'tabpaint']}, 06:26:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:26:14 INFO - 'ts_paint', 06:26:14 INFO - 'tpaint', 06:26:14 INFO - 'sessionrestore', 06:26:14 INFO - 'sessionrestore_no_auto_restore', 06:26:14 INFO - 'tabpaint']}, 06:26:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:26:14 INFO - 'tests': ['a11yr', 06:26:14 INFO - 'ts_paint', 06:26:14 INFO - 'tpaint', 06:26:14 INFO - 'sessionrestore', 06:26:14 INFO - 'sessionrestore_no_auto_restore', 06:26:14 INFO - 'tabpaint']}, 06:26:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:26:14 INFO - 'tests': ['a11yr', 06:26:14 INFO - 'ts_paint', 06:26:14 INFO - 'tpaint', 06:26:14 INFO - 'sessionrestore', 06:26:14 INFO - 'sessionrestore_no_auto_restore', 06:26:14 INFO - 'tabpaint']}, 06:26:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:26:14 INFO - 'tests': ['tsvgx', 06:26:14 INFO - 'tsvgr_opacity', 06:26:14 INFO - 'tart', 06:26:14 INFO - 'tscrollx', 06:26:14 INFO - 'cart']}, 06:26:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:26:14 INFO - 'tsvgr_opacity', 06:26:14 INFO - 'tart', 06:26:14 INFO - 'tscrollx', 06:26:14 INFO - 'cart']}, 06:26:14 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:26:14 INFO - 'talos_options': ['--disable-e10s'], 06:26:14 INFO - 'tests': ['tp5o']}, 06:26:14 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:26:14 INFO - 'tests': ['tp5o']}, 06:26:14 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:26:14 INFO - 'talos_options': ['--disable-e10s', 06:26:14 INFO - '--xperf_path', 06:26:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:26:14 INFO - 'tests': ['tp5n']}, 06:26:14 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:26:14 INFO - 'talos_options': ['--xperf_path', 06:26:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:26:14 INFO - 'tests': ['tp5n']}}, 06:26:14 INFO - 'talos.zip': {'path': '', 06:26:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:26:14 INFO - Running post-action listener: _resource_record_post_action 06:26:14 INFO - [mozharness: 2016-09-17 13:26:14.055943Z] Finished populate-webroot step (success) 06:26:14 INFO - [mozharness: 2016-09-17 13:26:14.056031Z] Running create-virtualenv step. 06:26:14 INFO - Running pre-action listener: _resource_record_pre_action 06:26:14 INFO - Running main action method: create_virtualenv 06:26:14 INFO - Creating virtualenv /builds/slave/test/build/venv 06:26:14 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 06:26:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:26:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:26:14 INFO - Using real prefix '/usr' 06:26:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:26:14 INFO - Installing distribute.............................................................................................................................................................................................done. 06:26:16 INFO - Installing pip.................done. 06:26:16 INFO - Return code: 0 06:26:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:26:16 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')]} 06:26:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:26:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:26:16 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')]} 06:26:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:26:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:26:16 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 0x7f9aca11ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15428a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x160fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x160cf60>, '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 0x160dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x160e1d0>, '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': '62c568580acbfdaac3eec7be000001dc-1474114211.156946-886130276', '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 06:26:16 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 06:26:16 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 06:26:16 INFO - Using env: {'DISPLAY': ':0', 06:26:16 INFO - 'HOME': '/home/cltbld', 06:26:16 INFO - 'LANG': 'en_US.UTF-8', 06:26:16 INFO - 'LANGUAGE': 'en_US:en', 06:26:16 INFO - 'LOGNAME': 'cltbld', 06:26:16 INFO - 'MAIL': '/var/mail/cltbld', 06:26:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:26:16 INFO - 'MOZ_NO_REMOTE': '1', 06:26:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:26:16 INFO - 'NO_EM_RESTART': '1', 06:26:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:26:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:26:16 INFO - 'PWD': '/builds/slave/test', 06:26:16 INFO - 'SHELL': '/bin/bash', 06:26:16 INFO - 'SHLVL': '1', 06:26:16 INFO - 'TERM': 'linux', 06:26:16 INFO - 'TMOUT': '86400', 06:26:16 INFO - 'USER': 'cltbld', 06:26:16 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1474114211.156946-886130276', 06:26:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:26:16 INFO - '_': '/tools/buildbot/bin/python'} 06:26:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:26:16 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 06:26:16 INFO - Cleaning up... 06:26:16 INFO - Return code: 0 06:26:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:26:16 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')]} 06:26:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:26:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:26:16 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')]} 06:26:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:26:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:26:16 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 0x7f9aca11ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15428a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x160fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x160cf60>, '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 0x160dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x160e1d0>, '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': '62c568580acbfdaac3eec7be000001dc-1474114211.156946-886130276', '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 06:26:16 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 06:26:16 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 06:26:16 INFO - Using env: {'DISPLAY': ':0', 06:26:16 INFO - 'HOME': '/home/cltbld', 06:26:16 INFO - 'LANG': 'en_US.UTF-8', 06:26:16 INFO - 'LANGUAGE': 'en_US:en', 06:26:16 INFO - 'LOGNAME': 'cltbld', 06:26:16 INFO - 'MAIL': '/var/mail/cltbld', 06:26:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:26:16 INFO - 'MOZ_NO_REMOTE': '1', 06:26:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:26:16 INFO - 'NO_EM_RESTART': '1', 06:26:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:26:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:26:16 INFO - 'PWD': '/builds/slave/test', 06:26:16 INFO - 'SHELL': '/bin/bash', 06:26:16 INFO - 'SHLVL': '1', 06:26:16 INFO - 'TERM': 'linux', 06:26:16 INFO - 'TMOUT': '86400', 06:26:16 INFO - 'USER': 'cltbld', 06:26:16 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1474114211.156946-886130276', 06:26:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:26:16 INFO - '_': '/tools/buildbot/bin/python'} 06:26:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:26:17 INFO - Downloading/unpacking psutil>=3.1.1 06:26:17 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 06:26:17 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 06:26:17 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 06:26:17 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 06:26:17 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 06:26:17 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 06:26:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:26:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:26:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:26:19 INFO - Installing collected packages: psutil 06:26:19 INFO - Running setup.py install for psutil 06:26:19 INFO - building 'psutil._psutil_linux' extension 06:26:19 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 06:26:19 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 06:26:19 INFO - building 'psutil._psutil_posix' extension 06:26:19 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 06:26:19 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 06:26:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:26:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:26:19 INFO - Successfully installed psutil 06:26:19 INFO - Cleaning up... 06:26:19 INFO - Return code: 0 06:26:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:26:19 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')]} 06:26:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:26:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:26:19 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')]} 06:26:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:26:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:26:19 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 0x7f9aca11ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15428a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x160fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x160cf60>, '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 0x160dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x160e1d0>, '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': '62c568580acbfdaac3eec7be000001dc-1474114211.156946-886130276', '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 06:26:19 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 06:26:19 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 06:26:19 INFO - Using env: {'DISPLAY': ':0', 06:26:19 INFO - 'HOME': '/home/cltbld', 06:26:19 INFO - 'LANG': 'en_US.UTF-8', 06:26:19 INFO - 'LANGUAGE': 'en_US:en', 06:26:19 INFO - 'LOGNAME': 'cltbld', 06:26:19 INFO - 'MAIL': '/var/mail/cltbld', 06:26:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:26:19 INFO - 'MOZ_NO_REMOTE': '1', 06:26:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:26:19 INFO - 'NO_EM_RESTART': '1', 06:26:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:26:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:26:19 INFO - 'PWD': '/builds/slave/test', 06:26:19 INFO - 'SHELL': '/bin/bash', 06:26:19 INFO - 'SHLVL': '1', 06:26:19 INFO - 'TERM': 'linux', 06:26:19 INFO - 'TMOUT': '86400', 06:26:19 INFO - 'USER': 'cltbld', 06:26:19 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1474114211.156946-886130276', 06:26:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:26:19 INFO - '_': '/tools/buildbot/bin/python'} 06:26:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:26:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:26:19 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 06:26:19 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 06:26:19 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 06:26:19 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 06:26:19 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 06:26:19 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 06:26:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:26:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:26:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:26:21 INFO - Installing collected packages: mozsystemmonitor 06:26:21 INFO - Running setup.py install for mozsystemmonitor 06:26:21 INFO - Successfully installed mozsystemmonitor 06:26:21 INFO - Cleaning up... 06:26:21 INFO - Return code: 0 06:26:21 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:26: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')]} 06:26:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:26:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:26: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')]} 06:26:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:26:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:26:21 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 0x7f9aca11ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15428a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x160fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x160cf60>, '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 0x160dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x160e1d0>, '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': '62c568580acbfdaac3eec7be000001dc-1474114211.156946-886130276', '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 06:26:21 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 06:26:21 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 06:26:21 INFO - Using env: {'DISPLAY': ':0', 06:26:21 INFO - 'HOME': '/home/cltbld', 06:26:21 INFO - 'LANG': 'en_US.UTF-8', 06:26:21 INFO - 'LANGUAGE': 'en_US:en', 06:26:21 INFO - 'LOGNAME': 'cltbld', 06:26:21 INFO - 'MAIL': '/var/mail/cltbld', 06:26:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:26:21 INFO - 'MOZ_NO_REMOTE': '1', 06:26:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:26:21 INFO - 'NO_EM_RESTART': '1', 06:26:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:26:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:26:21 INFO - 'PWD': '/builds/slave/test', 06:26:21 INFO - 'SHELL': '/bin/bash', 06:26:21 INFO - 'SHLVL': '1', 06:26:21 INFO - 'TERM': 'linux', 06:26:21 INFO - 'TMOUT': '86400', 06:26:21 INFO - 'USER': 'cltbld', 06:26:21 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1474114211.156946-886130276', 06:26:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:26:21 INFO - '_': '/tools/buildbot/bin/python'} 06:26:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:26:22 INFO - Downloading/unpacking jsonschema==2.5.1 06:26:22 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 06:26:22 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 06:26:22 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 06:26:22 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 06:26:22 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 06:26:22 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 06:26:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:26:23 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:26:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:26:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:26:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:26:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:26:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:26:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:26:24 INFO - Downloading functools32-3.2.3-2.tar.gz 06:26:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:26:24 INFO - warning: no files found matching '*.txt' 06:26:24 INFO - no previously-included directories found matching 'build' 06:26:24 INFO - no previously-included directories found matching 'dist' 06:26:24 INFO - no previously-included directories found matching '.git*' 06:26:24 INFO - Installing collected packages: jsonschema, functools32 06:26:24 INFO - Running setup.py install for functools32 06:26:24 INFO - warning: no files found matching '*.txt' 06:26:24 INFO - no previously-included directories found matching 'build' 06:26:24 INFO - no previously-included directories found matching 'dist' 06:26:24 INFO - no previously-included directories found matching '.git*' 06:26:24 INFO - Successfully installed jsonschema functools32 06:26:24 INFO - Cleaning up... 06:26:24 INFO - Return code: 0 06:26:24 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:26:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:26:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:26:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:26:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:26:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:26:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:26:24 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 0x7f9aca11ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15428a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x160fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x160cf60>, '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 0x160dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x160e1d0>, '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': '62c568580acbfdaac3eec7be000001dc-1474114211.156946-886130276', '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 06:26:24 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 06:26:24 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 06:26:24 INFO - Using env: {'DISPLAY': ':0', 06:26:24 INFO - 'HOME': '/home/cltbld', 06:26:24 INFO - 'LANG': 'en_US.UTF-8', 06:26:24 INFO - 'LANGUAGE': 'en_US:en', 06:26:24 INFO - 'LOGNAME': 'cltbld', 06:26:24 INFO - 'MAIL': '/var/mail/cltbld', 06:26:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:26:24 INFO - 'MOZ_NO_REMOTE': '1', 06:26:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:26:24 INFO - 'NO_EM_RESTART': '1', 06:26:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:26:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:26:24 INFO - 'PWD': '/builds/slave/test', 06:26:24 INFO - 'SHELL': '/bin/bash', 06:26:24 INFO - 'SHLVL': '1', 06:26:24 INFO - 'TERM': 'linux', 06:26:24 INFO - 'TMOUT': '86400', 06:26:24 INFO - 'USER': 'cltbld', 06:26:24 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1474114211.156946-886130276', 06:26:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:26:24 INFO - '_': '/tools/buildbot/bin/python'} 06:26:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:26:24 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:26:24 INFO - Cleaning up... 06:26:24 INFO - Return code: 0 06:26:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:26:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:26:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:26:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:26:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:26:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:26:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:26:24 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 0x7f9aca11ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15428a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x160fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x160cf60>, '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 0x160dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x160e1d0>, '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': '62c568580acbfdaac3eec7be000001dc-1474114211.156946-886130276', '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 06:26:24 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 06:26:24 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 06:26:24 INFO - Using env: {'DISPLAY': ':0', 06:26:24 INFO - 'HOME': '/home/cltbld', 06:26:24 INFO - 'LANG': 'en_US.UTF-8', 06:26:24 INFO - 'LANGUAGE': 'en_US:en', 06:26:24 INFO - 'LOGNAME': 'cltbld', 06:26:24 INFO - 'MAIL': '/var/mail/cltbld', 06:26:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:26:24 INFO - 'MOZ_NO_REMOTE': '1', 06:26:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:26:24 INFO - 'NO_EM_RESTART': '1', 06:26:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:26:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:26:24 INFO - 'PWD': '/builds/slave/test', 06:26:24 INFO - 'SHELL': '/bin/bash', 06:26:24 INFO - 'SHLVL': '1', 06:26:24 INFO - 'TERM': 'linux', 06:26:24 INFO - 'TMOUT': '86400', 06:26:24 INFO - 'USER': 'cltbld', 06:26:24 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1474114211.156946-886130276', 06:26:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:26:24 INFO - '_': '/tools/buildbot/bin/python'} 06:26:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:26:24 INFO - Downloading/unpacking blobuploader==1.2.4 06:26:24 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 06:26:24 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 06:26:24 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 06:26:24 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 06:26:24 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 06:26:24 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 06:26:26 INFO - Downloading blobuploader-1.2.4.tar.gz 06:26:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:26:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:26:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:26:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:26:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:26:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:26:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:26:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:26:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:26:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:26:27 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 06:26:27 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 06:26:27 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 06:26:27 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 06:26:27 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 06:26:27 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 06:26:27 INFO - Downloading docopt-0.6.1.tar.gz 06:26:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:26:27 INFO - Installing collected packages: blobuploader, requests, docopt 06:26:27 INFO - Running setup.py install for blobuploader 06:26:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:26:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:26:27 INFO - Running setup.py install for requests 06:26:28 INFO - Running setup.py install for docopt 06:26:28 INFO - Successfully installed blobuploader requests docopt 06:26:28 INFO - Cleaning up... 06:26:28 INFO - Return code: 0 06:26:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:26:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:26:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:26:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:26:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:26:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:26:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:26:28 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 0x7f9aca11ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15428a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x160fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x160cf60>, '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 0x160dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x160e1d0>, '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': '62c568580acbfdaac3eec7be000001dc-1474114211.156946-886130276', '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 06:26:28 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 06:26:28 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 06:26:28 INFO - Using env: {'DISPLAY': ':0', 06:26:28 INFO - 'HOME': '/home/cltbld', 06:26:28 INFO - 'LANG': 'en_US.UTF-8', 06:26:28 INFO - 'LANGUAGE': 'en_US:en', 06:26:28 INFO - 'LOGNAME': 'cltbld', 06:26:28 INFO - 'MAIL': '/var/mail/cltbld', 06:26:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:26:28 INFO - 'MOZ_NO_REMOTE': '1', 06:26:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:26:28 INFO - 'NO_EM_RESTART': '1', 06:26:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:26:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:26:28 INFO - 'PWD': '/builds/slave/test', 06:26:28 INFO - 'SHELL': '/bin/bash', 06:26:28 INFO - 'SHLVL': '1', 06:26:28 INFO - 'TERM': 'linux', 06:26:28 INFO - 'TMOUT': '86400', 06:26:28 INFO - 'USER': 'cltbld', 06:26:28 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1474114211.156946-886130276', 06:26:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:26:28 INFO - '_': '/tools/buildbot/bin/python'} 06:26:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:26:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:26:28 INFO - Running setup.py (path:/tmp/pip-A5Xdgi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:26:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:26:28 INFO - Running setup.py (path:/tmp/pip-73wPDy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:26:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:26:28 INFO - Running setup.py (path:/tmp/pip-JwvM6S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:26:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:26:28 INFO - Running setup.py (path:/tmp/pip-oRB1Af-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:26:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:26:28 INFO - Running setup.py (path:/tmp/pip-1nmTdi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:26:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:26:28 INFO - Running setup.py (path:/tmp/pip-RFjPAK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:26:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:26:28 INFO - Running setup.py (path:/tmp/pip-8FQYwV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:26:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:26:28 INFO - Running setup.py (path:/tmp/pip-7YNfDJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:26:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:26:28 INFO - Running setup.py (path:/tmp/pip-LOtMcp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:26:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:26:29 INFO - Running setup.py (path:/tmp/pip-oWKLOj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:26:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:26:29 INFO - Running setup.py (path:/tmp/pip-c1iCQK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:26:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:26:29 INFO - Running setup.py (path:/tmp/pip-Z81CyR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:26:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:26:29 INFO - Running setup.py (path:/tmp/pip-VLvMNf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:26:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:26:29 INFO - Running setup.py (path:/tmp/pip-k0f6UM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:26:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:26:29 INFO - Running setup.py (path:/tmp/pip-YRDSBs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:26:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:26:29 INFO - Running setup.py (path:/tmp/pip-ua2qTX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:26:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:26:29 INFO - Running setup.py (path:/tmp/pip-iU5AUp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:26:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:26:29 INFO - Running setup.py install for manifestparser 06:26:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:26:29 INFO - Running setup.py install for mozcrash 06:26:29 INFO - Running setup.py install for mozdebug 06:26:29 INFO - Running setup.py install for mozdevice 06:26:29 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:26:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:26:30 INFO - Running setup.py install for mozfile 06:26:30 INFO - Running setup.py install for mozhttpd 06:26:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:26:30 INFO - Running setup.py install for mozinfo 06:26:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:26:30 INFO - Running setup.py install for mozInstall 06:26:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:26:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:26:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:26:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:26:30 INFO - Running setup.py install for mozleak 06:26:30 INFO - Running setup.py install for mozlog 06:26:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:26:30 INFO - Running setup.py install for moznetwork 06:26:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:26:30 INFO - Running setup.py install for mozprocess 06:26:30 INFO - Running setup.py install for mozprofile 06:26:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:26:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:26:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:26:30 INFO - Running setup.py install for mozrunner 06:26:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:26:31 INFO - Running setup.py install for mozscreenshot 06:26:31 INFO - Running setup.py install for moztest 06:26:31 INFO - Running setup.py install for mozversion 06:26:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:26:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:26:31 INFO - Cleaning up... 06:26:31 INFO - Return code: 0 06:26:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:26:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:26:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:26:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:26:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:26:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:26:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:26:31 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 0x7f9aca11ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15428a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x160fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x160cf60>, '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 0x160dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x160e1d0>, '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': '62c568580acbfdaac3eec7be000001dc-1474114211.156946-886130276', '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 06:26:31 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 06:26:31 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 06:26:31 INFO - Using env: {'DISPLAY': ':0', 06:26:31 INFO - 'HOME': '/home/cltbld', 06:26:31 INFO - 'LANG': 'en_US.UTF-8', 06:26:31 INFO - 'LANGUAGE': 'en_US:en', 06:26:31 INFO - 'LOGNAME': 'cltbld', 06:26:31 INFO - 'MAIL': '/var/mail/cltbld', 06:26:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:26:31 INFO - 'MOZ_NO_REMOTE': '1', 06:26:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:26:31 INFO - 'NO_EM_RESTART': '1', 06:26:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:26:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:26:31 INFO - 'PWD': '/builds/slave/test', 06:26:31 INFO - 'SHELL': '/bin/bash', 06:26:31 INFO - 'SHLVL': '1', 06:26:31 INFO - 'TERM': 'linux', 06:26:31 INFO - 'TMOUT': '86400', 06:26:31 INFO - 'USER': 'cltbld', 06:26:31 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1474114211.156946-886130276', 06:26:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:26:31 INFO - '_': '/tools/buildbot/bin/python'} 06:26:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:26:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:26:31 INFO - Running setup.py (path:/tmp/pip-sZevi4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:26:31 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)) 06:26:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:26:31 INFO - Running setup.py (path:/tmp/pip-mcr07C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:26:31 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)) 06:26:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:26:31 INFO - Running setup.py (path:/tmp/pip-uDSklm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:26:31 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)) 06:26:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:26:31 INFO - Running setup.py (path:/tmp/pip-sPmJQo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:26:31 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)) 06:26:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:26:31 INFO - Running setup.py (path:/tmp/pip-Th67pX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:26:31 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)) 06:26:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:26:31 INFO - Running setup.py (path:/tmp/pip-pa_iwW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:26:32 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)) 06:26:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:26:32 INFO - Running setup.py (path:/tmp/pip-0L2Yzo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:26:32 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)) 06:26:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:26:32 INFO - Running setup.py (path:/tmp/pip-V2lHVW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:26:32 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)) 06:26:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:26:32 INFO - Running setup.py (path:/tmp/pip-dQEKSf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:26:32 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)) 06:26:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:26:32 INFO - Running setup.py (path:/tmp/pip-L9NyrG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:26:32 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)) 06:26:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:26:32 INFO - Running setup.py (path:/tmp/pip-j5BNYe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:26:32 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)) 06:26:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:26:32 INFO - Running setup.py (path:/tmp/pip-sMTNs1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:26:32 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)) 06:26:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:26:32 INFO - Running setup.py (path:/tmp/pip-oAux4F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:26:32 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)) 06:26:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:26:32 INFO - Running setup.py (path:/tmp/pip-HkASA8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:26:32 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)) 06:26:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:26:32 INFO - Running setup.py (path:/tmp/pip-XWq2QR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:26:32 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)) 06:26:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:26:32 INFO - Running setup.py (path:/tmp/pip-uCAkQR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:26:32 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)) 06:26:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:26:32 INFO - Running setup.py (path:/tmp/pip-GCBiUV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:26:32 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)) 06:26:32 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)) 06:26:32 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)) 06:26:32 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)) 06:26:32 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)) 06:26:32 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)) 06:26:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:26:32 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 06:26:32 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 06:26:32 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 06:26:32 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 06:26:32 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 06:26:32 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 06:26:34 INFO - Downloading blessings-1.6.tar.gz 06:26:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:26:34 INFO - Installing collected packages: blessings 06:26:34 INFO - Running setup.py install for blessings 06:26:34 INFO - Successfully installed blessings 06:26:34 INFO - Cleaning up... 06:26:34 INFO - Return code: 0 06:26:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:26:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:26:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:26:35 INFO - Reading from file tmpfile_stdout 06:26:35 INFO - Current package versions: 06:26:35 INFO - argparse == 1.2.1 06:26:35 INFO - blessings == 1.6 06:26:35 INFO - blobuploader == 1.2.4 06:26:35 INFO - docopt == 0.6.1 06:26:35 INFO - functools32 == 3.2.3-2 06:26:35 INFO - jsonschema == 2.5.1 06:26:35 INFO - manifestparser == 1.1 06:26:35 INFO - mozInstall == 1.12 06:26:35 INFO - mozcrash == 0.17 06:26:35 INFO - mozdebug == 0.1 06:26:35 INFO - mozdevice == 0.48 06:26:35 INFO - mozfile == 1.2 06:26:35 INFO - mozhttpd == 0.7 06:26:35 INFO - mozinfo == 0.9 06:26:35 INFO - mozleak == 0.1 06:26:35 INFO - mozlog == 3.4 06:26:35 INFO - moznetwork == 0.27 06:26:35 INFO - mozprocess == 0.23 06:26:35 INFO - mozprofile == 0.28 06:26:35 INFO - mozrunner == 6.12 06:26:35 INFO - mozscreenshot == 0.1 06:26:35 INFO - mozsystemmonitor == 0.3 06:26:35 INFO - moztest == 0.7 06:26:35 INFO - mozversion == 1.4 06:26:35 INFO - psutil == 3.1.1 06:26:35 INFO - requests == 1.2.3 06:26:35 INFO - wsgiref == 0.1.2 06:26:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:26:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:26:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:26:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:26:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:26:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:26:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:26:35 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 0x7f9aca11ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15428a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x160fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x160cf60>, '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 0x160dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x160e1d0>, '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': '62c568580acbfdaac3eec7be000001dc-1474114211.156946-886130276', '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 06:26:35 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 06:26:35 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 06:26:35 INFO - Using env: {'DISPLAY': ':0', 06:26:35 INFO - 'HOME': '/home/cltbld', 06:26:35 INFO - 'LANG': 'en_US.UTF-8', 06:26:35 INFO - 'LANGUAGE': 'en_US:en', 06:26:35 INFO - 'LOGNAME': 'cltbld', 06:26:35 INFO - 'MAIL': '/var/mail/cltbld', 06:26:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:26:35 INFO - 'MOZ_NO_REMOTE': '1', 06:26:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:26:35 INFO - 'NO_EM_RESTART': '1', 06:26:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:26:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:26:35 INFO - 'PWD': '/builds/slave/test', 06:26:35 INFO - 'SHELL': '/bin/bash', 06:26:35 INFO - 'SHLVL': '1', 06:26:35 INFO - 'TERM': 'linux', 06:26:35 INFO - 'TMOUT': '86400', 06:26:35 INFO - 'USER': 'cltbld', 06:26:35 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1474114211.156946-886130276', 06:26:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:26:35 INFO - '_': '/tools/buildbot/bin/python'} 06:26:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:26:35 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)) 06:26:35 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)) 06:26:35 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)) 06:26:35 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)) 06:26:35 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)) 06:26:35 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)) 06:26:35 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)) 06:26:35 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)) 06:26:35 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)) 06:26:35 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)) 06:26:35 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)) 06:26:35 INFO - Cleaning up... 06:26:35 INFO - Return code: 0 06:26:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:26:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:26:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:26:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:26:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:26:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:26:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:26:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9aca11ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15428a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x160fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x160cf60>, '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 0x160dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x160e1d0>, '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': '62c568580acbfdaac3eec7be000001dc-1474114211.156946-886130276', '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 06:26:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 06:26:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 06:26:35 INFO - Using env: {'DISPLAY': ':0', 06:26:35 INFO - 'HOME': '/home/cltbld', 06:26:35 INFO - 'LANG': 'en_US.UTF-8', 06:26:35 INFO - 'LANGUAGE': 'en_US:en', 06:26:35 INFO - 'LOGNAME': 'cltbld', 06:26:35 INFO - 'MAIL': '/var/mail/cltbld', 06:26:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:26:35 INFO - 'MOZ_NO_REMOTE': '1', 06:26:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:26:35 INFO - 'NO_EM_RESTART': '1', 06:26:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:26:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:26:35 INFO - 'PWD': '/builds/slave/test', 06:26:35 INFO - 'SHELL': '/bin/bash', 06:26:35 INFO - 'SHLVL': '1', 06:26:35 INFO - 'TERM': 'linux', 06:26:35 INFO - 'TMOUT': '86400', 06:26:35 INFO - 'USER': 'cltbld', 06:26:35 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1474114211.156946-886130276', 06:26:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:26:35 INFO - '_': '/tools/buildbot/bin/python'} 06:26:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:26:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:26:35 INFO - Cleaning up... 06:26:35 INFO - Return code: 0 06:26:35 INFO - Running post-action listener: _resource_record_post_action 06:26:35 INFO - Running post-action listener: _start_resource_monitoring 06:26:35 INFO - Starting resource monitoring. 06:26:35 INFO - [mozharness: 2016-09-17 13:26:35.643922Z] Finished create-virtualenv step (success) 06:26:35 INFO - [mozharness: 2016-09-17 13:26:35.644414Z] Running install step. 06:26:35 INFO - Running pre-action listener: _resource_record_pre_action 06:26:35 INFO - Running main action method: install 06:26:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:26:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:26:35 INFO - Reading from file tmpfile_stdout 06:26:35 INFO - Detecting whether we're running mozinstall >=1.0... 06:26:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:26:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:26:35 INFO - Reading from file tmpfile_stdout 06:26:35 INFO - Output received: 06:26:35 INFO - Usage: mozinstall [options] installer 06:26:35 INFO - Options: 06:26:35 INFO - -h, --help show this help message and exit 06:26:35 INFO - -d DEST, --destination=DEST 06:26:35 INFO - Directory to install application into. [default: 06:26:35 INFO - "/builds/slave/test"] 06:26:35 INFO - --app=APP Application being installed. [default: firefox] 06:26:35 INFO - mkdir: /builds/slave/test/build/application 06:26:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 06:26:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 06:26:52 INFO - Reading from file tmpfile_stdout 06:26:52 INFO - Output received: 06:26:52 INFO - /builds/slave/test/build/application/firefox/firefox 06:26:52 INFO - Running post-action listener: _resource_record_post_action 06:26:52 INFO - [mozharness: 2016-09-17 13:26:52.885252Z] Finished install step (success) 06:26:52 INFO - [mozharness: 2016-09-17 13:26:52.885629Z] Running run-tests step. 06:26:52 INFO - Running pre-action listener: _resource_record_pre_action 06:26:52 INFO - Running main action method: run_tests 06:26:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:26:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:26:52 INFO - Python 2.7.3 06:26:52 INFO - Return code: 0 06:26:52 INFO - grabbing minidump binary from tooltool 06:26: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')]} 06:26:52 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 0x160cf60>, '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 0x160dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x160e1d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:26:52 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 06:26:52 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 06:26:52 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 06:26:52 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:26:52 INFO - Return code: 0 06:26:52 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 06:26:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:26:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:26:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 06:26:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:26:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UG5r-Cb5QfW1wupiktEfmg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--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 06:26:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/UG5r-Cb5QfW1wupiktEfmg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-018 --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 06:26:52 INFO - Using env: {'DISPLAY': ':0', 06:26:52 INFO - 'HOME': '/home/cltbld', 06:26:52 INFO - 'LANG': 'en_US.UTF-8', 06:26:52 INFO - 'LANGUAGE': 'en_US:en', 06:26:52 INFO - 'LOGNAME': 'cltbld', 06:26:52 INFO - 'MAIL': '/var/mail/cltbld', 06:26:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:26:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 06:26:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:26:52 INFO - 'MOZ_NO_REMOTE': '1', 06:26:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:26:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:26:52 INFO - 'NO_EM_RESTART': '1', 06:26:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:26:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:26:52 INFO - 'PWD': '/builds/slave/test', 06:26:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:26:52 INFO - 'SHELL': '/bin/bash', 06:26:52 INFO - 'SHLVL': '1', 06:26:52 INFO - 'TERM': 'linux', 06:26:52 INFO - 'TMOUT': '86400', 06:26:52 INFO - 'USER': 'cltbld', 06:26:52 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1474114211.156946-886130276', 06:26:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:26:52 INFO - '_': '/tools/buildbot/bin/python'} 06:26:52 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UG5r-Cb5QfW1wupiktEfmg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--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 06:26:53 INFO - mozversion application_buildid: 20160917033004 06:26:53 INFO - mozversion application_changeset: 752f33561e8f624642291842f8963efa9e9290d5 06:26:53 INFO - mozversion application_display_name: Nightly 06:26:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:26:53 INFO - mozversion application_name: Firefox 06:26:53 INFO - mozversion application_remotingname: firefox 06:26:53 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 06:26:53 INFO - mozversion application_vendor: Mozilla 06:26:53 INFO - mozversion application_version: 51.0a1 06:26:53 INFO - mozversion platform_buildid: 20160917033004 06:26:53 INFO - mozversion platform_changeset: 752f33561e8f624642291842f8963efa9e9290d5 06:26:53 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 06:26:53 INFO - mozversion platform_version: 51.0a1 06:26:53 INFO - using testdate: 1474118813 06:26:53 INFO - actual date: 1474118813 06:26:53 INFO - starting webserver on 'localhost:54954' 06:26:53 INFO - SUITE-START | Running 2 tests 06:26:53 INFO - TEST-START | dromaeo_css 06:26:53 INFO - operating with platform_type : linux_ 06:26:53 INFO - Initialising browser for dromaeo_css test... 06:26:53 INFO - TEST-INFO | started process 26680 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFUf0Wv/profile http://localhost:54954/getInfo.html) 06:26:57 INFO - PROCESS | 26680 | __metrics Screen width/height:1600/1200 06:26:57 INFO - PROCESS | 26680 | colorDepth:24 06:26:57 INFO - PROCESS | 26680 | Browser inner width/height: 1024/697 06:26:57 INFO - PROCESS | 26680 | __metrics 06:26:57 INFO - PROCESS | 26680 | 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" 06:26:57 INFO - PROCESS | 26680 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:26:58 INFO - PROCESS | 26680 | console.error: PushService: 06:26:58 INFO - PROCESS | 26680 | stateChangeProcessEnqueue: Error transitioning state 06:26:58 INFO - PROCESS | 26680 | UnknownError 06:26:58 INFO - TEST-INFO | 26680: exit 0 06:26:58 INFO - Browser initialized. 06:26:58 INFO - Running cycle 1/1 for dromaeo_css test... 06:26:58 INFO - TEST-INFO | started process 26762 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFUf0Wv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:27:11 INFO - PROCESS | 26762 | RSS: Main: 172949504 06:27:11 INFO - PROCESS | 26762 | 06:27:20 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 06:27:21 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 2.01671 seconds. 06:27:34 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 06:27:45 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.05003 seconds. 06:27:51 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 06:27:53 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 06:27:55 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 06:28:02 INFO - PROCESS | 26762 | 1474118882268 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] 06:28:03 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.03334 seconds. 06:28:07 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 06:28:10 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 06:28:16 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.16671 seconds. 06:28:21 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.05005 seconds. 06:28:26 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 06:28:27 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 06:28:29 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 06:28:31 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 06:28:33 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.16671 seconds. 06:28:38 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.05005 seconds. 06:28:44 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.01666 seconds. 06:28:47 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 06:28:49 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.06671 seconds. 06:29:19 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 06:29:23 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 06:29:27 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 06:29:33 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.05004 seconds. 06:29:37 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.03335 seconds. 06:29:39 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 06:29:54 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.03341 seconds. 06:30:03 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.16667 seconds. 06:30:05 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 06:30:11 INFO - PROCESS | 26762 | Cycle 1(1): loaded http://localhost:54954/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54954/tests/dromaeo/cssquery-ext.html) 06:30:12 INFO - PROCESS | 26762 | RSS: Main: 314843136 06:30:12 INFO - PROCESS | 26762 | 06:30:16 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 06:30:29 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 06:30:45 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 06:30:53 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 06:30:59 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 06:31:07 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 06:31:18 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.01677 seconds. 06:31:24 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 06:31:30 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 06:31:36 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 06:31:52 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 06:31:56 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 06:31:58 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 06:32:04 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 06:32:08 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 06:32:18 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 06:32:22 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 06:32:24 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 06:32:26 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 06:32:32 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 06:32:44 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 06:32:59 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 06:33:11 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 06:33:15 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 06:33:15 INFO - PROCESS | 26762 | Cycle 1(1): loaded http://localhost:54954/tests/dromaeo/cssquery-ext.html (next: http://localhost:54954/tests/dromaeo/cssquery-jquery.html) 06:33:15 INFO - PROCESS | 26762 | RSS: Main: 276307968 06:33:15 INFO - PROCESS | 26762 | 06:33:19 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 06:33:22 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.05006 seconds. 06:33:26 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 06:33:37 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 06:33:38 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 06:34:10 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 06:34:26 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 06:34:32 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 06:34:43 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 06:34:53 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 06:34:55 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 06:34:57 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 06:35:01 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 06:35:09 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 06:35:11 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 06:35:13 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 06:35:20 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 06:35:27 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 06:35:31 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 06:35:33 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 06:35:43 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 06:35:51 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 06:35:53 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 06:36:03 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 06:36:05 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 06:36:11 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 06:36:17 INFO - PROCESS | 26762 | Cycle 1(1): loaded http://localhost:54954/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54954/tests/dromaeo/cssquery-mootools.html) 06:36:18 INFO - PROCESS | 26762 | RSS: Main: 214659072 06:36:18 INFO - PROCESS | 26762 | 06:36:21 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 06:36:23 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.05005 seconds. 06:36:29 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 06:36:41 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 06:36:43 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 06:36:49 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 06:36:53 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 06:37:11 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 06:37:17 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 06:37:41 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 06:38:32 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 06:38:34 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 06:38:43 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 06:38:46 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 06:38:56 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 06:39:08 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 06:39:20 INFO - PROCESS | 26762 | Cycle 1(1): loaded http://localhost:54954/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54954/tests/dromaeo/cssquery-prototype.html) 06:39:21 INFO - PROCESS | 26762 | RSS: Main: 267816960 06:39:21 INFO - PROCESS | 26762 | 06:39:24 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.08346 seconds. 06:39:43 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 06:39:55 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 06:40:07 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 06:40:22 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 06:40:26 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 06:40:44 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 06:40:48 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 06:41:05 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.01664 seconds. 06:41:06 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 2.01666 seconds. 06:41:11 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 06:41:13 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 06:41:31 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 06:41:47 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 06:41:49 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 06:42:03 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 06:42:04 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 2.00007 seconds. 06:42:05 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 06:42:11 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 06:42:15 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00008 seconds. 06:42:23 INFO - PROCESS | 26762 | Cycle 1(1): loaded http://localhost:54954/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54954/tests/dromaeo/cssquery-yui.html) 06:42:24 INFO - PROCESS | 26762 | RSS: Main: 213344256 06:42:24 INFO - PROCESS | 26762 | 06:42:27 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 06:42:30 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 06:42:47 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 06:43:05 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 06:43:21 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 06:43:38 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 06:43:41 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00009 seconds. 06:43:43 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 06:43:47 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 06:44:00 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 06:44:20 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 06:44:22 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 06:44:25 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 06:44:26 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 06:44:28 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 06:44:32 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 06:44:46 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 06:45:17 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 06:45:19 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.0334 seconds. 06:45:21 INFO - PROCESS | 26762 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 06:45:27 INFO - PROCESS | 26762 | Cycle 1(1): loaded http://localhost:54954/tests/dromaeo/cssquery-yui.html (next: http://localhost:54954/tests/dromaeo/cssquery-dojo.html) 06:45:27 INFO - PROCESS | 26762 | RSS: Main: 303132672 06:45:27 INFO - PROCESS | 26762 | 06:45:27 INFO - PROCESS | 26762 | __start_tp_report 06:45:27 INFO - PROCESS | 26762 | _x_x_mozilla_page_load 06:45:27 INFO - PROCESS | 26762 | _x_x_mozilla_page_load_details 06:45:27 INFO - PROCESS | 26762 | |i|pagename|runs| 06:45:27 INFO - PROCESS | 26762 | |0;dojo.html;3017.668056513783;3024.5916801231833;3038.012645890085;3060;3087.1664650544353;5153.742312884359;5244.501772331632;5260.184671375938;5297.125974214255;5335.226392173133;5264.131418315973;5284.2866213061225;5306.867328316785;5314.229436731681;5329.893402131965;4706.670533062687;4756.595689366391;4790.880227994293;4791.496892826255;4857.684250523723;4872.585830204437;4902.215645496722;4921.778519966592;4923.310736496894;4940.802367905279;4683.227267500842;4702.31816386622;4818.903621927542;4826.662133650646;4872.512748725135;4844.394450693663;4904.264360345941;4929.482404347527;4944.579710724593;4956.479569645207;4794.808207671689;4816.470188279286;4821.204501257288;4836.588889944359;4883.658143929926;5122.667026643257;5314.601404894648;5324.41431442541;5327.331635050593;5338.466153384669;5528.75120619573;5604.355499117612;5640.689762063085;5641.097424412074;5738.598298119134;5654.236678048452;5671.489565939044;5676.120201368794;5678;5679.744411501451;5313.601479889024;5323.574114070865;5323.893522129574;5327.600429967729;5378.489043540857;5505.449455054462;5548.917960997566;5685.6304340218585;5691.573132015115;5755.568332375088;5491.533219676372;5534.086875665551;5534.861628459321;5545.500904918576;5600;5583.302087239095;5625.578081643893;5630.774769009276;5634.830955071355;5636.887262254732;5484.2047903053835;5514.503694667499;5605.663660180402;5635.098384258499;5644.604877658607;5727.570432217601;5728.598998070179;5728.656280623176;5762.337331206965;5794.391588883191;4419.381286619876;4419.469663640384;4419.911601767946;4463.598276155161;4472.08322293929;3832.329342365075;3852.4991751072744;3902.024493876531;3924.47019652349;3999.700022498266;5317.441668624816;5355.705436201047;5356.65181763192;5398.595105367113;5422.728863556807;5647.943520564824;5669.801556945569;5681.630694004876;5693.231413759089;5696.060150075274;5169.663971841818;5178.300929374486;5372.301600791872;5387.622866399314;5443.482869127427;4801.423829140525;4828.7102773833685;4868.245421959531;4883.926741098886;4934.7285899275885;5855.531557475477;5860.706964651665;5864.677442740605;5915.704214789243;5926.733297001645;5681.176229446624;5687;5690.630109042899;5694.088945768657;5696.829095127153;5608.158776183605;5621.887562248814;5748.540116790563;5843.006688862988;5849.970750146222;5554.694491802989;5572.944270557405;5584.46947539983;5598.608097433141;5655.4344565543115;5680.318361796611;5688.77244910199;5695.031844586513;5695.857603559944;5700.144978253295;5320.787168513371;5430.864228394164;5548.167774833646;5557.583181261502;5563.109902415594;5484.588655850747;5500.752466138953;5527.060399731975;5535.6955367455175;5601.215829783752;5514.5864060194845;5558.305211848519;5560.666360018412;5561.193626923992;5565.666060036411;5264.815731449381;5305.204219367126;5557.583181261502;5563.916541251965;5576.860578485408;5452.209429632601;5466.753996070266;5493.533049690821;5502.559795216293;5564.8330550083565 06:45:27 INFO - PROCESS | 26762 | |1;ext.html;559.9160125981102;616.2450997528032;618.4681174190196;628.2774808969688;629.2103410220172;33616.83191584045;45187.77406112969;46256.76871615637;46394.999999999956;49481.505184948226;46434.303485447745;46481;46804.76597617016;46839.76580117095;46906.76546617262;9054.999999999984;9179.632814687438;9197.448153110834;9211.263098952102;9214.907850921505;8988.730338089836;9027.95486022569;9116.498592577405;9118;9122.315826313019;9090.50002249876;9123.133302336268;9127.634894604207;9136.223421009223;9142.588583513758;9071.410358326688;9117.680881169194;9128.771780705501;9142.862857057149;9158.450492970376;8983.910160898373;9013.954930225307;9024.77438064047;9044.3668943174;9055;44051.33922991158;44317.55682443167;44345.334819977725;44556.10887782226;44662.77668611669;3479.6138827773593;3723.7765734056043;3791.298609757204;4018.718689691723;4071.816768245436;33538.1615459613;34334.656653433645;34378.48432273518;34418.655813441794;34442.483362749575;4260.261817166649;4538.568835960578;4670.1126785910565;4676.485586585472;4678.181318269323;340034;340651;340853;341989.9999999975;342777;61032.38967610356;61524.38475615276;61544.384556154764;61598.296323698356;61645;63004.68497657483;63120.68439657864;63138.36861631325;63346;63358;17505;17686.469405917844;17702.114894255235;17732.468025959242;17738.201780919888;13649.658758530917;13691.383887725076;13730;13732.45070197201;13739.24434156131;14003.929980350236;17811.287548498174;18238;18360.632787344177;18369.908150459163;5395.032731997386;6042.909356359658;6226.968865155645;6372.235331760218;6476.838079048061;978.5596481583174;981.0532835813474;982.2044144243185;991.4051569058652;1069.534752382716;32385.35229295401;33658.66341336555;33746;33876.91787564466;33994.83002584971;1065.2383545764815;1069.1019543583272;1107.0092267420614;1123.0229700160912;1163.2497039409702;17535.649287014443;17545.82454175442;17560.121993900255;17582.824171758373;17667.55831104207;163093;164320.17839910724;165040;165190;165327;59666.701666492256;59984.40015599875;60015.699921500986;60023.09965350523;60125.69937150374;1256.6104507602672;1271.9809202861964;1322.550332886823;1368.1790925444654;1389.0346209384381;31210.531842021934;33764.66235337615;34855;36291.637083628826;36403.08558344589;58268.4173158263;58426.12360814506;58450.707746461;58550;58559.707201465426;516816;520833;520847;523116;523263;28357.716422836333;29698.55452168176;29863;29932.401351973273;29949;49427.50572494229;49462;50260;50453.24320135127;50879.74560127176;41324.1735165301;41769.58230417657;42488.57511424846;43075.353869692604;43571.56428435675;41903.21951804729;42532.36201456919;42564.44613323211;42787.358189627805;43004.78497607617;40855;41870.371944421466;42266.36600450934;42315.78842105893;42434.787826060674;34776;36966;37806.62193378141;38321.42517862179;38545.421818673305;28627.570586440805;28674.56988145221;28785.280367990967;28824.711752883042;28959.85520072386 06:45:27 INFO - PROCESS | 26762 | |2;jquery.html;377.52243412083703;392.56033242768086;401.963823255907;404.2783631218275;422.934445161;13078.60764177075;14827.555173344803;18948.62102757946;19731;19870.70193947092;18001.729974050366;18714;18870.622587548274;18874.528136796587;18915.148818303176;5573.526250268722;5622.859428514299;5866.413358664142;5874.911876321838;5875.559333050017;5654.9151762723595;5784.479396854283;5792.623479473842;5794.449527294901;5795.536357091422;5595.552355811526;5678.472700418137;5743.908657355089;5754.367019627837;5756.222909907151;5669.9433005669825;5706;5710.600257981943;5742.310922689262;5755.366909639936;4972.5276098770555;5087.923681144785;5140.074786538422;5163.3545806774155;5171.172612382038;22383.776162238493;22419.439514012116;22426.215082472034;22578.54842903133;22597.54804903909;2486.8880900359527;2801.5937689034977;2910.2287893708185;3046.0136130239275;3109.8445077746023;13604.795928061087;14422.206778627171;14446.855531444657;14628.853711462962;14646.290436269217;1921.269917431367;1940.1075505267452;1997.950051248716;1998.7501562304712;2069.658506346451;81364.9999999994;81677;82070.58964705198;84152.57923710342;84327.5783621078;10157.339772914736;10267.691969240936;10443;10598.788024239471;10790.352578845295;14232;14336.139831610137;14455.566333009816;14470.27648617565;14476.032413355237;8232.588370581567;8302.294304984143;8460.74617761456;8462.449940753955;8479.70321038773;8020.639071241808;8043.879341809878;8102.837943241102;8296.460730052528;8299.211574900375;6246.437820596168;6390.648514331757;6433.935660643334;6489.156409666713;6616.569922954993;1853.8238867307584;1882.2282864025683;1893.5644801695687;1915.5594213330733;1963.783983761785;914.8033172867865;954.4034978138664;978.5205249427871;1017.1760873692333;1052.726291164303;19182.616347672967;19543.706844397344;19621.117049732795;19657.311994080137;19770.497853092485;997.8253805583994;1002.3685078400707;1008.1128606825946;1018.3686114608842;1056.7727938493108;10484.580616775243;10928.01647851697;10953.014228719454;11116.555337786398;11151.219414640897;472274;479092;479528;480290;481548;82492;82843.5857820707;83720.58139709262;88099.55950220207;100724.49637751764;451.84185535063125;469.6477641768673;470.05049799404327;1018.5874720738113;1074.167520171873;9983.500824958868;10417.47912604382;10648.414337211218;10700.946495267473;10739.409332486788;41557.58442415537;41862.37206441845;41892;42267;42452;55682.72158639181;57272.427275728376;57391.98256122199;57461.712691436274;58279.708601456725;8072.475289106189;8262.628181731956;8429.072801991653;8508.617112230088;8620;12006.879931200576;12548.560800371943;13159.342032898125;13261.66845828862;13282.867171328164;11327.603533876274;11614.709632258922;11635.88364116348;11987;12003.699907502032;11347.602833900773;11634.476448560004;11659.941700291443;11796.882031179579;11854.822177667515;11937.28376297425;11960.342181180118;12103.878961210276;12257.95807356349;12295.262284262973;8324.750257492286;8400.201980811813;8446.070932197576;8658.740237792876;8701.216890479887;10320.587176512856;11540.192186546861;11608.361540115375;11628.476718547516;12029.578964736193 06:45:27 INFO - PROCESS | 26762 | |3;mootools.html;1317.22283852527;1448.2758620689658;1463.1294379843991;1577.5661693034415;1732.2118436111562;7604.163542010381;8409;8615.052344242144;8618.181272779091;8742.562871856398;8601.139886011395;8663.047064822864;8671.913280867206;8673.219410253076;8675.47947123173;2413.8189635777344;2421.789105447276;2440;2440.4752978109705;2441.377448750566;2321.6401457774073;2344.9564943600103;2369.5379401016808;2372.50177462733;2372.738998710149;2327.6857624220684;2330.9743712766413;2350.5651454480976;2367.763223677636;2370.6444033394955;2319.9072037118494;2328.4178955261186;2336.2757545161053;2338.918137865175;2366.4438856868624;2267.6598510223434;2323.1288266899915;2333.101755824003;2333.7316208635884;2345.882705864717;7470.551766893949;7633.694652213905;8502.702405415843;8700.477971321741;8822.735317940473;4846.491118432549;5008.724520151391;5009.974950125263;5102.974485127587;5106.259592359087;3267.9183020424443;3372.4435468147726;3444.569428821397;3451.6893479586956;3461.809600471973;1096.9286996345213;1126.4818183635537;1139.732162941708;1160.0603511155991;1160.211056481592;12019.57931472403;12028.578999735055;12035.277883327028;12049.698757531045;12142;4476.529964353696;4588.931166032512;4722.173619616553;4732.5977291929885;4777.355057067321;10249.077583017564;10256.641017564349;10259.179265658879;10261.846072308772;10266.384016959006;5312.654677445954;5322.3879253885525;5337.119375303031;5355.866103347447;5367.75845086972;4792.329073929653;4924.3105965164905;4929.3345398371475;4933.605311575066;4933.6299777516115;4076.2051399977313;4110.938335924963;4142.523609784913;4197.958020419756;4245.596668316505;1347.9865201348057;1358.4158811711059;1359.1709057474932;1361.006465280351;1370.5134677189653;881.96809732613;943.8301105800884;951.4909523404945;963.4123184857232;988.1501908358806;5691.117876729114;5716.428357164334;5721.856953576111;5771.971140144273;6105.908411373833;433.3348310343607;435.3404592043057;438.3402978517375;447.33570647588306;452.1386758225585;3900.9804950975063;4018.2967980603275;4041.6766658667234;4051.453053837753;4186.811593478301;10501.632442864464;11054.115670746482;11059.668209953714;11088.112950963903;11093.168012399103;11058.447077646086;11064.557417703201;11066.446677666085;11067.280626759235;11072.501737421835;8047.637856296481;8077.111517733045;8135.227153420534;8153.429259951747;8229.218224268685;5664.405237450009;5803.129530570448;5814.156947242542;5826.94173058264;5997.940020599738;9961.452120133463;10017.799644006933;10057.446840423847;10111.292209545556;10324.174066074545;9003.954980225057;9312.44125352481;9317.86023209666;9608.711738647851;10289.176865850564;7869.606519674108;8007.1592482788465;8010.759677209693;8022.237887400687;8025.759227223192;8336.333093352638;8355.12271211514;8391.496510209407;8398.286145677686;8405.327573794204;7727.227277272228;7757.844843102994;7776.961115194388;7799.1420943697285;7819.178986206366;7448.73929412468;7546.849063018599;7638.618069096634;7641.808954776175;7722.498037627536;6936.237013928565;7174.748883789042;7218.530795498275;7222.96388518054;7246.492745507764;4202.306619407886;4816.662833601615;4838.540338667821;4949.975250123726;5187.533122019036;5185.403678577012;5208.114620514598;5218.139007063792;5300.284461597789;5385.676859388449 06:45:27 INFO - PROCESS | 26762 | |4;prototype.html;321.3465952563122;325.55886773422026;331.98008119512843;339.53822800990656;345.44555987639836;5176.145935920574;5284.445133261005;5327.760250788714;5342.813001544947;5370.033393989081;5192.974035129819;5194.298769666094;5209.192575150858;5249.370075590934;5328.706921119347;4161.708680392374;4165.187788381267;4168.187203495319;4172.102997855461;4184;3839.6736277416453;4070.287699652564;4131.917361652765;4164.916701665965;4165.416841642173;4078.1232035112444;4080.8571699990507;4082.101937573729;4084.65280451162;4085.734427262233;4078.4086307485395;4088.7342322748927;4098.446709694183;4101.395613785253;4110.17796440713;3565.839537220805;3646.398344273192;3647.6534729200685;3650.926981460356;3653.214558869829;5076.390833100015;5083;5092.592592592584;5126.384833819928;5155.484451554852;4607.907841843178;4608.608268297199;4613.423322084739;4614.538546145359;4646.651501137429;5194.68831870089;5307.522322990911;5380.762424642354;5405.486478784509;5429.375621803503;3888.727789054767;3902.2195560887935;3936.921261574781;3955.6044395560502;3956.485656864618;5479.4520547945285;5636.351819540764;5660.60375773696;5699.064981534513;5769.07694768835;3871.825767840454;3910.511186101737;3914.5693973662874;3914.667253283446;4015.9397609035886;3997.92004159921;4041.6160464755803;4051.1897620476025;4060.2285565742413;4088.2436749200897;4464.15181115587;4485.865424037219;4587.655925805511;4667.603253723403;4692.577668009895;5409.83770486886;5414;5439.972800136054;5448.809291674773;5449.373322068013;1333.759923213831;1361.3465536542515;1366.419271809477;1369.869862363074;1385.4665953607935;899.6311512279934;924.5654542365078;928.4197376639601;938.8357037518407;944.3767113704922;4660.953390466119;4664.4869064403565;4672.159011377917;4721.929171062437;4724.622030237572;4869.975650121727;4925.631062142211;4926.487491379422;4966.602671786249;4985.825496107619;4497.2804351304285;4680.882977925579;4688.320193571981;4731.905361892743;4733.905321893543;2600.54490464168;2621.7808718945403;2622.4230669252734;2627.7240889706304;2637.8681065946625;5032.823851165265;5063.8480845574695;5112.897742045213;5123.38519377677;5141.460146684619;4840.757962101951;5074;5077.949220507821;5095.388553373619;5128.948710512847;5030.597552195815;5030.748462576857;5043.899122017612;5077.365329333833;5092.28707980883;4918.786546054218;4954.479779623088;4957.776900039435;4973.502649735142;4978.253262010728;4521.344405061313;4876.487968763229;4889.7507146712915;4917.631177661761;5281.260623512788;5581.469760372758;5606.495415412633;5761.740721667619;5777.0179069557435;5789.652620842763;4007.6192761687594;4008.5590585036207;4017.276890159799;4029.9395509066803;4049.635532802062;5448;5672.404397538199;5713.143028545752;5713.885722285448;5722.99695106726;5519.779208831763;5527.50252477279;5528.9723551381985;5530.751116199861;5535.723213839373;5445.373782014961;5474.753636086306;5505.825199046434;5600.411956744646;5609.186667933209;5280.867978300574;5498.835034948958;5528.655163625948;5550.500454959073;5558.194061860927;5596.38439771633;5599.300087489064;5605.915911261416;5606.719664016701;5690.260266165372;5198.9740051299505;5474.698891561003;5484.506394424521;5485.341758988947;5487.807926722546 06:45:27 INFO - PROCESS | 26762 | |5;yui.html;714.8212946763309;739.305053249945;774.0696563704845;815.6737305077971;839.223718060794;15143.848561514382;16133.515994520183;16181.433649822258;16318.265678044487;16429.26068326925;13680.931595342034;13738.79391809121;13770.518031868887;14600;14807.703845923075;806.8547661420956;822.1203312455675;825;825.0553116681397;825.4222044568826;669.7153709673394;675.1594265139908;685.4824607421415;687.340153452686;816.302061737215;832.0348395860802;853.3727710133078;856.4861083350003;856.6145234644404;857.4126723194601;838.304207507767;839.5466448118009;840.0801122770526;840.3193413335188;840.6301227459898;502.16640376974414;609.7256234694405;611.1932249430753;611.3641812514979;611.6635850282371;11843.58547450835;12410.007199424024;12416.441260143225;14670.853291467056;15033.849661503355;14184.29078546079;14747.77878331826;16148.59628509285;16161.515154545383;16325.918370408192;11800.586979455764;11844.763104737944;12043.93978030113;12200.877991220063;12239.32683702396;3769.4534292527705;3787.5833658297565;3864.4589757433982;3872.3223435898603;3887.241987812378;84172.57913710453;85981;87647.56176219079;87901.56049219776;90826;11990.460429280703;11997.485556919131;12084.154109212446;12396.070294727931;12933.54732584355;23666;23704.881475592512;23719.644205336936;23753.881230593735;23957.28128156158;8593.312534997185;8704.390692651581;8910.55447227636;8941.642334306685;9186.632534698538;4541.9545804542195;4577.977110114428;4579.610733087725;4597.310403439444;4696.2486002239475;3453.5165076889257;3544.2557063017057;3552.0942159749097;3555.1289933966345;3577.5349204603754;592.6236839606795;601.4887345756159;626.7399029402746;669.1434963247052;673.2627772589008;711.6512908674712;711.7757906259511;713.2225873797586;725.1298441869777;756.5347311403557;9720.368176068532;9769.413835169913;10176.643817466353;10268.386197281343;10597.41714205726;598.3717097048082;736.7752835385259;740.1377395334483;740.6741033945046;751.3921544363321;4599.632029437637;4900.779464924087;4924.162892308244;4938.38270216223;4943.134951383494;76869;77783.61108194423;112461;115015;119041;94746;98285.50857245667;98487;99242;101451;82986;91039;91864;94123.52938235401;94200.52899735457;9386.15524602789;10093.545790439595;10240.290792080696;10315.638952636622;10479.266451348638;46199.53800462087;46510.302345464166;48728.5127148724;51784.74107629438;53540.46459535355;49707.502924970286;51431.97136057333;64244;69749.65125174342;74495.62752186421;13388.330583470983;13687.19381753399;13703.657408564866;13738.175709457464;13849.16904985704;14904.403823847359;15131.394744210109;15148.16685082331;15232.847671523143;15424.46014389491;13394.330283485982;14558.344874480463;15141.242937853283;15479.613009674847;15592.454264100703;13456.25990570528;13572.253526056162;13970.930145349208;14221.928890355483;14625;13177.209367437985;13285.468581256642;13299;13353.66615834612;13809.447622095004;11106.833397499206;11202.83195752048;11480.712982175512;12054.939725301318;12444.502219911103;12103.697407564881;12261.26432414058;12329.843192236147;12502.499900003899;12545 06:45:27 INFO - PROCESS | 26762 | __end_tp_report 06:45:27 INFO - PROCESS | 26762 | __start_cc_report 06:45:27 INFO - PROCESS | 26762 | _x_x_mozilla_cycle_collect,1077 06:45:27 INFO - PROCESS | 26762 | __end_cc_report 06:45:27 INFO - PROCESS | 26762 | __startTimestamp1474119927457__endTimestamp 06:45:27 INFO - PROCESS | 26762 | 06:45:27 INFO - PROCESS | 26762 | ------- Summary: start ------- 06:45:27 INFO - PROCESS | 26762 | Number of tests: 6 06:45:27 INFO - PROCESS | 26762 | 06:45:27 INFO - PROCESS | 26762 | [#0] dojo.html Cycles:165 Average:5258.90 Median:5484.59 stddev:583.46 (10.6%) stddev-sans-first:558.13 06:45:27 INFO - PROCESS | 26762 | Values: 3017.7 3024.6 3038.0 3060.0 3087.2 5153.7 5244.5 5260.2 5297.1 5335.2 5264.1 5284.3 5306.9 5314.2 5329.9 4706.7 4756.6 4790.9 4791.5 4857.7 4872.6 4902.2 4921.8 4923.3 4940.8 4683.2 4702.3 4818.9 4826.7 4872.5 4844.4 4904.3 4929.5 4944.6 4956.5 4794.8 4816.5 4821.2 4836.6 4883.7 5122.7 5314.6 5324.4 5327.3 5338.5 5528.8 5604.4 5640.7 5641.1 5738.6 5654.2 5671.5 5676.1 5678.0 5679.7 5313.6 5323.6 5323.9 5327.6 5378.5 5505.4 5548.9 5685.6 5691.6 5755.6 5491.5 5534.1 5534.9 5545.5 5600.0 5583.3 5625.6 5630.8 5634.8 5636.9 5484.2 5514.5 5605.7 5635.1 5644.6 5727.6 5728.6 5728.7 5762.3 5794.4 4419.4 4419.5 4419.9 4463.6 4472.1 3832.3 3852.5 3902.0 3924.5 3999.7 5317.4 5355.7 5356.7 5398.6 5422.7 5647.9 5669.8 5681.6 5693.2 5696.1 5169.7 5178.3 5372.3 5387.6 5443.5 4801.4 4828.7 4868.2 4883.9 4934.7 5855.5 5860.7 5864.7 5915.7 5926.7 5681.2 5687.0 5690.6 5694.1 5696.8 5608.2 5621.9 5748.5 5843.0 5850.0 5554.7 5572.9 5584.5 5598.6 5655.4 5680.3 5688.8 5695.0 5695.9 5700.1 5320.8 5430.9 5548.2 5557.6 5563.1 5484.6 5500.8 5527.1 5535.7 5601.2 5514.6 5558.3 5560.7 5561.2 5565.7 5264.8 5305.2 5557.6 5563.9 5576.9 5452.2 5466.8 5493.5 5502.6 5564.8 06:45:27 INFO - PROCESS | 26762 | 06:45:27 INFO - PROCESS | 26762 | [#1] ext.html Cycles:180 Average:53068.30 Median:43040.07 stddev:98957.93 (229.9%) stddev-sans-first:99157.00 06:45:27 INFO - PROCESS | 26762 | Values: 559.9 616.2 618.5 628.3 629.2 33616.8 45187.8 46256.8 46395.0 49481.5 46434.3 46481.0 46804.8 46839.8 46906.8 9055.0 9179.6 9197.4 9211.3 9214.9 8988.7 9028.0 9116.5 9118.0 9122.3 9090.5 9123.1 9127.6 9136.2 9142.6 9071.4 9117.7 9128.8 9142.9 9158.5 8983.9 9014.0 9024.8 9044.4 9055.0 44051.3 44317.6 44345.3 44556.1 44662.8 3479.6 3723.8 3791.3 4018.7 4071.8 33538.2 34334.7 34378.5 34418.7 34442.5 4260.3 4538.6 4670.1 4676.5 4678.2 340034.0 340651.0 340853.0 341990.0 342777.0 61032.4 61524.4 61544.4 61598.3 61645.0 63004.7 63120.7 63138.4 63346.0 63358.0 17505.0 17686.5 17702.1 17732.5 17738.2 13649.7 13691.4 13730.0 13732.5 13739.2 14003.9 17811.3 18238.0 18360.6 18369.9 5395.0 6042.9 6227.0 6372.2 6476.8 978.6 981.1 982.2 991.4 1069.5 32385.4 33658.7 33746.0 33876.9 33994.8 1065.2 1069.1 1107.0 1123.0 1163.2 17535.6 17545.8 17560.1 17582.8 17667.6 163093.0 164320.2 165040.0 165190.0 165327.0 59666.7 59984.4 60015.7 60023.1 60125.7 1256.6 1272.0 1322.6 1368.2 1389.0 31210.5 33764.7 34855.0 36291.6 36403.1 58268.4 58426.1 58450.7 58550.0 58559.7 516816.0 520833.0 520847.0 523116.0 523263.0 28357.7 29698.6 29863.0 29932.4 29949.0 49427.5 49462.0 50260.0 50453.2 50879.7 41324.2 41769.6 42488.6 43075.4 43571.6 41903.2 42532.4 42564.4 42787.4 43004.8 40855.0 41870.4 42266.4 42315.8 42434.8 34776.0 36966.0 37806.6 38321.4 38545.4 28627.6 28674.6 28785.3 28824.7 28959.9 06:45:27 INFO - PROCESS | 26762 | 06:45:27 INFO - PROCESS | 26762 | [#2] jquery.html Cycles:180 Average:28450.56 Median:2978.12 stddev:78922.00 (2650.1%) stddev-sans-first:79115.25 06:45:27 INFO - PROCESS | 26762 | Values: 377.5 392.6 402.0 404.3 422.9 13078.6 14827.6 18948.6 19731.0 19870.7 18001.7 18714.0 18870.6 18874.5 18915.1 5573.5 5622.9 5866.4 5874.9 5875.6 5654.9 5784.5 5792.6 5794.4 5795.5 5595.6 5678.5 5743.9 5754.4 5756.2 5669.9 5706.0 5710.6 5742.3 5755.4 4972.5 5087.9 5140.1 5163.4 5171.2 22383.8 22419.4 22426.2 22578.5 22597.5 2486.9 2801.6 2910.2 3046.0 3109.8 13604.8 14422.2 14446.9 14628.9 14646.3 1921.3 1940.1 1998.0 1998.8 2069.7 81365.0 81677.0 82070.6 84152.6 84327.6 10157.3 10267.7 10443.0 10598.8 10790.4 14232.0 14336.1 14455.6 14470.3 14476.0 8232.6 8302.3 8460.7 8462.4 8479.7 8020.6 8043.9 8102.8 8296.5 8299.2 6246.4 6390.6 6433.9 6489.2 6616.6 1853.8 1882.2 1893.6 1915.6 1963.8 914.8 954.4 978.5 1017.2 1052.7 19182.6 19543.7 19621.1 19657.3 19770.5 997.8 1002.4 1008.1 1018.4 1056.8 10484.6 10928.0 10953.0 11116.6 11151.2 472274.0 479092.0 479528.0 480290.0 481548.0 82492.0 82843.6 83720.6 88099.6 100724.5 451.8 469.6 470.1 1018.6 1074.2 9983.5 10417.5 10648.4 10700.9 10739.4 41557.6 41862.4 41892.0 42267.0 42452.0 55682.7 57272.4 57392.0 57461.7 58279.7 8072.5 8262.6 8429.1 8508.6 8620.0 12006.9 12548.6 13159.3 13261.7 13282.9 11327.6 11614.7 11635.9 11987.0 12003.7 11347.6 11634.5 11659.9 11796.9 11854.8 11937.3 11960.3 12103.9 12258.0 12295.3 8324.8 8400.2 8446.1 8658.7 8701.2 10320.6 11540.2 11608.4 11628.5 12029.6 06:45:27 INFO - PROCESS | 26762 | 06:45:27 INFO - PROCESS | 26762 | [#3] mootools.html Cycles:180 Average:5739.65 Median:4784.84 stddev:3258.83 (68.1%) stddev-sans-first:3251.02 06:45:27 INFO - PROCESS | 26762 | Values: 1317.2 1448.3 1463.1 1577.6 1732.2 7604.2 8409.0 8615.1 8618.2 8742.6 8601.1 8663.0 8671.9 8673.2 8675.5 2413.8 2421.8 2440.0 2440.5 2441.4 2321.6 2345.0 2369.5 2372.5 2372.7 2327.7 2331.0 2350.6 2367.8 2370.6 2319.9 2328.4 2336.3 2338.9 2366.4 2267.7 2323.1 2333.1 2333.7 2345.9 7470.6 7633.7 8502.7 8700.5 8822.7 4846.5 5008.7 5010.0 5103.0 5106.3 3267.9 3372.4 3444.6 3451.7 3461.8 1096.9 1126.5 1139.7 1160.1 1160.2 12019.6 12028.6 12035.3 12049.7 12142.0 4476.5 4588.9 4722.2 4732.6 4777.4 10249.1 10256.6 10259.2 10261.8 10266.4 5312.7 5322.4 5337.1 5355.9 5367.8 4792.3 4924.3 4929.3 4933.6 4933.6 4076.2 4110.9 4142.5 4198.0 4245.6 1348.0 1358.4 1359.2 1361.0 1370.5 882.0 943.8 951.5 963.4 988.2 5691.1 5716.4 5721.9 5772.0 6105.9 433.3 435.3 438.3 447.3 452.1 3901.0 4018.3 4041.7 4051.5 4186.8 10501.6 11054.1 11059.7 11088.1 11093.2 11058.4 11064.6 11066.4 11067.3 11072.5 8047.6 8077.1 8135.2 8153.4 8229.2 5664.4 5803.1 5814.2 5826.9 5997.9 9961.5 10017.8 10057.4 10111.3 10324.2 9004.0 9312.4 9317.9 9608.7 10289.2 7869.6 8007.2 8010.8 8022.2 8025.8 8336.3 8355.1 8391.5 8398.3 8405.3 7727.2 7757.8 7777.0 7799.1 7819.2 7448.7 7546.8 7638.6 7641.8 7722.5 6936.2 7174.7 7218.5 7223.0 7246.5 4202.3 4816.7 4838.5 4950.0 5187.5 5185.4 5208.1 5218.1 5300.3 5385.7 06:45:27 INFO - PROCESS | 26762 | 06:45:27 INFO - PROCESS | 26762 | [#4] prototype.html Cycles:180 Average:4465.80 Median:4962.19 stddev:1295.95 (26.1%) stddev-sans-first:1261.70 06:45:27 INFO - PROCESS | 26762 | Values: 321.3 325.6 332.0 339.5 345.4 5176.1 5284.4 5327.8 5342.8 5370.0 5193.0 5194.3 5209.2 5249.4 5328.7 4161.7 4165.2 4168.2 4172.1 4184.0 3839.7 4070.3 4131.9 4164.9 4165.4 4078.1 4080.9 4082.1 4084.7 4085.7 4078.4 4088.7 4098.4 4101.4 4110.2 3565.8 3646.4 3647.7 3650.9 3653.2 5076.4 5083.0 5092.6 5126.4 5155.5 4607.9 4608.6 4613.4 4614.5 4646.7 5194.7 5307.5 5380.8 5405.5 5429.4 3888.7 3902.2 3936.9 3955.6 3956.5 5479.5 5636.4 5660.6 5699.1 5769.1 3871.8 3910.5 3914.6 3914.7 4015.9 3997.9 4041.6 4051.2 4060.2 4088.2 4464.2 4485.9 4587.7 4667.6 4692.6 5409.8 5414.0 5440.0 5448.8 5449.4 1333.8 1361.3 1366.4 1369.9 1385.5 899.6 924.6 928.4 938.8 944.4 4661.0 4664.5 4672.2 4721.9 4724.6 4870.0 4925.6 4926.5 4966.6 4985.8 4497.3 4680.9 4688.3 4731.9 4733.9 2600.5 2621.8 2622.4 2627.7 2637.9 5032.8 5063.8 5112.9 5123.4 5141.5 4840.8 5074.0 5077.9 5095.4 5128.9 5030.6 5030.7 5043.9 5077.4 5092.3 4918.8 4954.5 4957.8 4973.5 4978.3 4521.3 4876.5 4889.8 4917.6 5281.3 5581.5 5606.5 5761.7 5777.0 5789.7 4007.6 4008.6 4017.3 4029.9 4049.6 5448.0 5672.4 5713.1 5713.9 5723.0 5519.8 5527.5 5529.0 5530.8 5535.7 5445.4 5474.8 5505.8 5600.4 5609.2 5280.9 5498.8 5528.7 5550.5 5558.2 5596.4 5599.3 5605.9 5606.7 5690.3 5199.0 5474.7 5484.5 5485.3 5487.8 06:45:27 INFO - PROCESS | 26762 | 06:45:27 INFO - PROCESS | 26762 | [#5] yui.html Cycles:180 Average:20631.06 Median:4214.60 stddev:29161.30 (691.9%) stddev-sans-first:29204.76 06:45:27 INFO - PROCESS | 26762 | Values: 714.8 739.3 774.1 815.7 839.2 15143.8 16133.5 16181.4 16318.3 16429.3 13680.9 13738.8 13770.5 14600.0 14807.7 806.9 822.1 825.0 825.1 825.4 669.7 675.2 685.5 687.3 816.3 832.0 853.4 856.5 856.6 857.4 838.3 839.5 840.1 840.3 840.6 502.2 609.7 611.2 611.4 611.7 11843.6 12410.0 12416.4 14670.9 15033.8 14184.3 14747.8 16148.6 16161.5 16325.9 11800.6 11844.8 12043.9 12200.9 12239.3 3769.5 3787.6 3864.5 3872.3 3887.2 84172.6 85981.0 87647.6 87901.6 90826.0 11990.5 11997.5 12084.2 12396.1 12933.5 23666.0 23704.9 23719.6 23753.9 23957.3 8593.3 8704.4 8910.6 8941.6 9186.6 4542.0 4578.0 4579.6 4597.3 4696.2 3453.5 3544.3 3552.1 3555.1 3577.5 592.6 601.5 626.7 669.1 673.3 711.7 711.8 713.2 725.1 756.5 9720.4 9769.4 10176.6 10268.4 10597.4 598.4 736.8 740.1 740.7 751.4 4599.6 4900.8 4924.2 4938.4 4943.1 76869.0 77783.6 112461.0 115015.0 119041.0 94746.0 98285.5 98487.0 99242.0 101451.0 82986.0 91039.0 91864.0 94123.5 94200.5 9386.2 10093.5 10240.3 10315.6 10479.3 46199.5 46510.3 48728.5 51784.7 53540.5 49707.5 51432.0 64244.0 69749.7 74495.6 13388.3 13687.2 13703.7 13738.2 13849.2 14904.4 15131.4 15148.2 15232.8 15424.5 13394.3 14558.3 15141.2 15479.6 15592.5 13456.3 13572.3 13970.9 14221.9 14625.0 13177.2 13285.5 13299.0 13353.7 13809.4 11106.8 11202.8 11480.7 12054.9 12444.5 12103.7 12261.3 12329.8 12502.5 12545.0 06:45:27 INFO - PROCESS | 26762 | -------- Summary: end -------- 06:45:27 INFO - PROCESS | 26762 | 06:45:28 INFO - TEST-INFO | 26762: exit 0 06:45:28 INFO - TEST-OK | dromaeo_css | took 1115107ms 06:45:28 INFO - TEST-START | kraken 06:45:28 INFO - operating with platform_type : linux_ 06:45:28 INFO - Initialising browser for kraken test... 06:45:28 INFO - TEST-INFO | started process 27069 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1xA_ZF/profile http://localhost:54954/getInfo.html) 06:45:32 INFO - PROCESS | 27069 | __metrics Screen width/height:1600/1200 06:45:32 INFO - PROCESS | 27069 | colorDepth:24 06:45:32 INFO - PROCESS | 27069 | Browser inner width/height: 1024/697 06:45:32 INFO - PROCESS | 27069 | __metrics 06:45:32 INFO - PROCESS | 27069 | 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" 06:45:32 INFO - PROCESS | 27069 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:45:33 INFO - PROCESS | 27069 | console.error: PushService: 06:45:33 INFO - PROCESS | 27069 | stateChangeProcessEnqueue: Error transitioning state 06:45:33 INFO - PROCESS | 27069 | UnknownError 06:45:34 INFO - TEST-INFO | 27069: exit 0 06:45:34 INFO - Browser initialized. 06:45:34 INFO - Running cycle 1/1 for kraken test... 06:45:34 INFO - TEST-INFO | started process 27151 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1xA_ZF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:45:46 INFO - PROCESS | 27151 | RSS: Main: 173342720 06:45:46 INFO - PROCESS | 27151 | 06:45:48 INFO - PROCESS | 27151 | Cycle 1(1): loaded http://localhost:54954/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54954/tests/kraken/driver.html?testName=audio-beat-detection) 06:45:49 INFO - PROCESS | 27151 | RSS: Main: 240918528 06:45:49 INFO - PROCESS | 27151 | 06:45:52 INFO - PROCESS | 27151 | Cycle 1(1): loaded http://localhost:54954/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54954/tests/kraken/driver.html?testName=audio-dft) 06:45:52 INFO - PROCESS | 27151 | RSS: Main: 217145344 06:45:52 INFO - PROCESS | 27151 | 06:45:55 INFO - PROCESS | 27151 | Cycle 1(1): loaded http://localhost:54954/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54954/tests/kraken/driver.html?testName=audio-fft) 06:45:56 INFO - PROCESS | 27151 | RSS: Main: 298176512 06:45:56 INFO - PROCESS | 27151 | 06:45:58 INFO - PROCESS | 27151 | Cycle 1(1): loaded http://localhost:54954/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54954/tests/kraken/driver.html?testName=audio-oscillator) 06:45:59 INFO - PROCESS | 27151 | RSS: Main: 207732736 06:45:59 INFO - PROCESS | 27151 | 06:46:01 INFO - PROCESS | 27151 | Cycle 1(1): loaded http://localhost:54954/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54954/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:46:01 INFO - PROCESS | 27151 | RSS: Main: 212496384 06:46:01 INFO - PROCESS | 27151 | 06:46:04 INFO - PROCESS | 27151 | Cycle 1(1): loaded http://localhost:54954/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54954/tests/kraken/driver.html?testName=imaging-darkroom) 06:46:05 INFO - PROCESS | 27151 | RSS: Main: 249831424 06:46:05 INFO - PROCESS | 27151 | 06:46:08 INFO - PROCESS | 27151 | Cycle 1(1): loaded http://localhost:54954/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54954/tests/kraken/driver.html?testName=imaging-desaturate) 06:46:09 INFO - PROCESS | 27151 | RSS: Main: 247996416 06:46:09 INFO - PROCESS | 27151 | 06:46:12 INFO - PROCESS | 27151 | Cycle 1(1): loaded http://localhost:54954/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54954/tests/kraken/driver.html?testName=json-parse-financial) 06:46:12 INFO - PROCESS | 27151 | RSS: Main: 247869440 06:46:12 INFO - PROCESS | 27151 | 06:46:14 INFO - PROCESS | 27151 | Cycle 1(1): loaded http://localhost:54954/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54954/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:46:15 INFO - PROCESS | 27151 | RSS: Main: 210358272 06:46:15 INFO - PROCESS | 27151 | 06:46:16 INFO - PROCESS | 27151 | Cycle 1(1): loaded http://localhost:54954/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54954/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:46:17 INFO - PROCESS | 27151 | RSS: Main: 217202688 06:46:17 INFO - PROCESS | 27151 | 06:46:19 INFO - PROCESS | 27151 | Cycle 1(1): loaded http://localhost:54954/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54954/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:46:20 INFO - PROCESS | 27151 | RSS: Main: 222441472 06:46:20 INFO - PROCESS | 27151 | 06:46:22 INFO - PROCESS | 27151 | Cycle 1(1): loaded http://localhost:54954/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54954/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:46:23 INFO - PROCESS | 27151 | RSS: Main: 223539200 06:46:23 INFO - PROCESS | 27151 | 06:46:26 INFO - PROCESS | 27151 | Cycle 1(1): loaded http://localhost:54954/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54954/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:46:26 INFO - PROCESS | 27151 | RSS: Main: 221073408 06:46:26 INFO - PROCESS | 27151 | 06:46:28 INFO - PROCESS | 27151 | Cycle 1(1): loaded http://localhost:54954/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54954/tests/kraken/driver.html?testName=ai-astar) 06:46:28 INFO - PROCESS | 27151 | RSS: Main: 272248832 06:46:28 INFO - PROCESS | 27151 | 06:46:28 INFO - PROCESS | 27151 | __start_tp_report 06:46:28 INFO - PROCESS | 27151 | _x_x_mozilla_page_load 06:46:28 INFO - PROCESS | 27151 | _x_x_mozilla_page_load_details 06:46:28 INFO - PROCESS | 27151 | |i|pagename|runs| 06:46:28 INFO - PROCESS | 27151 | |0;ai-astar;110;114;113;111;112;115;112;112;113;112 06:46:28 INFO - PROCESS | 27151 | |1;audio-beat-detection;143;122;121;161;129;120;119;119;147;122 06:46:28 INFO - PROCESS | 27151 | |2;audio-dft;160;160;160;159;161;160;162;162;160;161 06:46:28 INFO - PROCESS | 27151 | |3;audio-fft;75;75;77;106;77;76;76;77;98;78 06:46:28 INFO - PROCESS | 27151 | |4;audio-oscillator;83;82;111;82;82;82;109;81;81;118 06:46:28 INFO - PROCESS | 27151 | |5;imaging-gaussian-blur;119;117;116;116;116;117;117;117;117;117 06:46:28 INFO - PROCESS | 27151 | |6;imaging-darkroom;146;145;145;145;145;145;145;145;145;145 06:46:28 INFO - PROCESS | 27151 | |7;imaging-desaturate;95;95;95;96;95;95;96;96;95;95 06:46:28 INFO - PROCESS | 27151 | |8;json-parse-financial;52;50;49;51;50;51;51;51;52;51 06:46:28 INFO - PROCESS | 27151 | |9;json-stringify-tinderbox;61;62;61;61;62;61;60;60;60;62 06:46:28 INFO - PROCESS | 27151 | |10;stanford-crypto-aes;78;78;78;78;77;78;83;78;79;77 06:46:28 INFO - PROCESS | 27151 | |11;stanford-crypto-ccm;125;125;132;129;131;140;122;129;172;125 06:46:28 INFO - PROCESS | 27151 | |12;stanford-crypto-pbkdf2;166;167;165;172;172;165;182;172;170;167 06:46:28 INFO - PROCESS | 27151 | |13;stanford-crypto-sha256-iterative;66;65;67;70;64;66;72;68;65;65 06:46:28 INFO - PROCESS | 27151 | __end_tp_report 06:46:28 INFO - PROCESS | 27151 | __start_cc_report 06:46:28 INFO - PROCESS | 27151 | _x_x_mozilla_cycle_collect,1636 06:46:28 INFO - PROCESS | 27151 | __end_cc_report 06:46:28 INFO - PROCESS | 27151 | __startTimestamp1474119988663__endTimestamp 06:46:28 INFO - PROCESS | 27151 | 06:46:28 INFO - PROCESS | 27151 | ------- Summary: start ------- 06:46:28 INFO - PROCESS | 27151 | Number of tests: 14 06:46:28 INFO - PROCESS | 27151 | 06:46:28 INFO - PROCESS | 27151 | [#0] ai-astar Cycles:10 Average:112.40 Median:112.50 stddev:1.43 (1.3%) stddev-sans-first:1.22 06:46:28 INFO - PROCESS | 27151 | Values: 110.0 114.0 113.0 111.0 112.0 115.0 112.0 112.0 113.0 112.0 06:46:28 INFO - PROCESS | 27151 | 06:46:28 INFO - PROCESS | 27151 | [#1] audio-beat-detection Cycles:10 Average:130.30 Median:125.50 stddev:14.80 (11.8%) stddev-sans-first:14.96 06:46:28 INFO - PROCESS | 27151 | Values: 143.0 122.0 121.0 161.0 129.0 120.0 119.0 119.0 147.0 122.0 06:46:28 INFO - PROCESS | 27151 | 06:46:28 INFO - PROCESS | 27151 | [#2] audio-dft Cycles:10 Average:160.50 Median:160.50 stddev:0.97 (0.6%) stddev-sans-first:1.01 06:46:28 INFO - PROCESS | 27151 | Values: 160.0 160.0 160.0 159.0 161.0 160.0 162.0 162.0 160.0 161.0 06:46:28 INFO - PROCESS | 27151 | 06:46:28 INFO - PROCESS | 27151 | [#3] audio-fft Cycles:10 Average:81.50 Median:77.00 stddev:11.01 (14.3%) stddev-sans-first:11.42 06:46:28 INFO - PROCESS | 27151 | Values: 75.0 75.0 77.0 106.0 77.0 76.0 76.0 77.0 98.0 78.0 06:46:28 INFO - PROCESS | 27151 | 06:46:28 INFO - PROCESS | 27151 | [#4] audio-oscillator Cycles:10 Average:91.10 Median:82.00 stddev:15.06 (18.4%) stddev-sans-first:15.68 06:46:28 INFO - PROCESS | 27151 | Values: 83.0 82.0 111.0 82.0 82.0 82.0 109.0 81.0 81.0 118.0 06:46:28 INFO - PROCESS | 27151 | 06:46:28 INFO - PROCESS | 27151 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.88 (0.7%) stddev-sans-first:0.50 06:46:28 INFO - PROCESS | 27151 | Values: 119.0 117.0 116.0 116.0 116.0 117.0 117.0 117.0 117.0 117.0 06:46:28 INFO - PROCESS | 27151 | 06:46:28 INFO - PROCESS | 27151 | [#6] imaging-darkroom Cycles:10 Average:145.10 Median:145.00 stddev:0.32 (0.2%) stddev-sans-first:0.00 06:46:28 INFO - PROCESS | 27151 | Values: 146.0 145.0 145.0 145.0 145.0 145.0 145.0 145.0 145.0 145.0 06:46:28 INFO - PROCESS | 27151 | 06:46:28 INFO - PROCESS | 27151 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 06:46:28 INFO - PROCESS | 27151 | Values: 95.0 95.0 95.0 96.0 95.0 95.0 96.0 96.0 95.0 95.0 06:46:28 INFO - PROCESS | 27151 | 06:46:28 INFO - PROCESS | 27151 | [#8] json-parse-financial Cycles:10 Average:50.80 Median:51.00 stddev:0.92 (1.8%) stddev-sans-first:0.87 06:46:28 INFO - PROCESS | 27151 | Values: 52.0 50.0 49.0 51.0 50.0 51.0 51.0 51.0 52.0 51.0 06:46:28 INFO - PROCESS | 27151 | 06:46:28 INFO - PROCESS | 27151 | [#9] json-stringify-tinderbox Cycles:10 Average:61.00 Median:61.00 stddev:0.82 (1.3%) stddev-sans-first:0.87 06:46:28 INFO - PROCESS | 27151 | Values: 61.0 62.0 61.0 61.0 62.0 61.0 60.0 60.0 60.0 62.0 06:46:28 INFO - PROCESS | 27151 | 06:46:28 INFO - PROCESS | 27151 | [#10] stanford-crypto-aes Cycles:10 Average:78.40 Median:78.00 stddev:1.71 (2.2%) stddev-sans-first:1.81 06:46:28 INFO - PROCESS | 27151 | Values: 78.0 78.0 78.0 78.0 77.0 78.0 83.0 78.0 79.0 77.0 06:46:28 INFO - PROCESS | 27151 | 06:46:28 INFO - PROCESS | 27151 | [#11] stanford-crypto-ccm Cycles:10 Average:133.00 Median:130.00 stddev:14.61 (11.2%) stddev-sans-first:15.20 06:46:28 INFO - PROCESS | 27151 | Values: 125.0 125.0 132.0 129.0 131.0 140.0 122.0 129.0 172.0 125.0 06:46:28 INFO - PROCESS | 27151 | 06:46:28 INFO - PROCESS | 27151 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.80 Median:171.00 stddev:5.16 (3.0%) stddev-sans-first:5.29 06:46:28 INFO - PROCESS | 27151 | Values: 166.0 167.0 165.0 172.0 172.0 165.0 182.0 172.0 170.0 167.0 06:46:28 INFO - PROCESS | 27151 | 06:46:28 INFO - PROCESS | 27151 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.80 Median:66.50 stddev:2.53 (3.8%) stddev-sans-first:2.67 06:46:28 INFO - PROCESS | 27151 | Values: 66.0 65.0 67.0 70.0 64.0 66.0 72.0 68.0 65.0 65.0 06:46:28 INFO - PROCESS | 27151 | -------- Summary: end -------- 06:46:28 INFO - PROCESS | 27151 | 06:46:29 INFO - TEST-INFO | 27151: exit 0 06:46:29 INFO - TEST-OK | kraken | took 61572ms 06:46:29 INFO - SUITE-END | took 1176s 06:46:30 INFO - Completed test suite (00:19:37) 06:46:30 INFO - javascript benchmark 06:46:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3017.668056513783, 3024.5916801231833, 3038.012645890085, 3060.0, 3087.1664650544353, 5153.742312884359, 5244.501772331632, 5260.184671375938, 5297.125974214255, 5335.226392173133, 5264.131418315973, 5284.2866213061225, 5306.867328316785, 5314.229436731681, 5329.893402131965, 4706.670533062687, 4756.595689366391, 4790.880227994293, 4791.496892826255, 4857.684250523723, 4872.585830204437, 4902.215645496722, 4921.778519966592, 4923.310736496894, 4940.802367905279, 4683.227267500842, 4702.31816386622, 4818.903621927542, 4826.662133650646, 4872.512748725135, 4844.394450693663, 4904.264360345941, 4929.482404347527, 4944.579710724593, 4956.479569645207, 4794.808207671689, 4816.470188279286, 4821.204501257288, 4836.588889944359, 4883.658143929926, 5122.667026643257, 5314.601404894648, 5324.41431442541, 5327.331635050593, 5338.466153384669, 5528.75120619573, 5604.355499117612, 5640.689762063085, 5641.097424412074, 5738.598298119134, 5654.236678048452, 5671.489565939044, 5676.120201368794, 5678.0, 5679.744411501451, 5313.601479889024, 5323.574114070865, 5323.893522129574, 5327.600429967729, 5378.489043540857, 5505.449455054462, 5548.917960997566, 5685.6304340218585, 5691.573132015115, 5755.568332375088, 5491.533219676372, 5534.086875665551, 5534.861628459321, 5545.500904918576, 5600.0, 5583.302087239095, 5625.578081643893, 5630.774769009276, 5634.830955071355, 5636.887262254732, 5484.2047903053835, 5514.503694667499, 5605.663660180402, 5635.098384258499, 5644.604877658607, 5727.570432217601, 5728.598998070179, 5728.656280623176, 5762.337331206965, 5794.391588883191, 4419.381286619876, 4419.469663640384, 4419.911601767946, 4463.598276155161, 4472.08322293929, 3832.329342365075, 3852.4991751072744, 3902.024493876531, 3924.47019652349, 3999.700022498266, 5317.441668624816, 5355.705436201047, 5356.65181763192, 5398.595105367113, 5422.728863556807, 5647.943520564824, 5669.801556945569, 5681.630694004876, 5693.231413759089, 5696.060150075274, 5169.663971841818, 5178.300929374486, 5372.301600791872, 5387.622866399314, 5443.482869127427, 4801.423829140525, 4828.7102773833685, 4868.245421959531, 4883.926741098886, 4934.7285899275885, 5855.531557475477, 5860.706964651665, 5864.677442740605, 5915.704214789243, 5926.733297001645, 5681.176229446624, 5687.0, 5690.630109042899, 5694.088945768657, 5696.829095127153, 5608.158776183605, 5621.887562248814, 5748.540116790563, 5843.006688862988, 5849.970750146222, 5554.694491802989, 5572.944270557405, 5584.46947539983, 5598.608097433141, 5655.4344565543115, 5680.318361796611, 5688.77244910199, 5695.031844586513, 5695.857603559944, 5700.144978253295, 5320.787168513371, 5430.864228394164, 5548.167774833646, 5557.583181261502, 5563.109902415594, 5484.588655850747, 5500.752466138953, 5527.060399731975, 5535.6955367455175, 5601.215829783752, 5514.5864060194845, 5558.305211848519, 5560.666360018412, 5561.193626923992, 5565.666060036411, 5264.815731449381, 5305.204219367126, 5557.583181261502, 5563.916541251965, 5576.860578485408, 5452.209429632601, 5466.753996070266, 5493.533049690821, 5502.559795216293, 5564.8330550083565], "value": 5220.171357581653, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [559.9160125981102, 616.2450997528032, 618.4681174190196, 628.2774808969688, 629.2103410220172, 33616.83191584045, 45187.77406112969, 46256.76871615637, 46394.999999999956, 49481.505184948226, 46434.303485447745, 46481.0, 46804.76597617016, 46839.76580117095, 46906.76546617262, 9054.999999999984, 9179.632814687438, 9197.448153110834, 9211.263098952102, 9214.907850921505, 8988.730338089836, 9027.95486022569, 9116.498592577405, 9118.0, 9122.315826313019, 9090.50002249876, 9123.133302336268, 9127.634894604207, 9136.223421009223, 9142.588583513758, 9071.410358326688, 9117.680881169194, 9128.771780705501, 9142.862857057149, 9158.450492970376, 8983.910160898373, 9013.954930225307, 9024.77438064047, 9044.3668943174, 9055.0, 44051.33922991158, 44317.55682443167, 44345.334819977725, 44556.10887782226, 44662.77668611669, 3479.6138827773593, 3723.7765734056043, 3791.298609757204, 4018.718689691723, 4071.816768245436, 33538.1615459613, 34334.656653433645, 34378.48432273518, 34418.655813441794, 34442.483362749575, 4260.261817166649, 4538.568835960578, 4670.1126785910565, 4676.485586585472, 4678.181318269323, 340034.0, 340651.0, 340853.0, 341989.9999999975, 342777.0, 61032.38967610356, 61524.38475615276, 61544.384556154764, 61598.296323698356, 61645.0, 63004.68497657483, 63120.68439657864, 63138.36861631325, 63346.0, 63358.0, 17505.0, 17686.469405917844, 17702.114894255235, 17732.468025959242, 17738.201780919888, 13649.658758530917, 13691.383887725076, 13730.0, 13732.45070197201, 13739.24434156131, 14003.929980350236, 17811.287548498174, 18238.0, 18360.632787344177, 18369.908150459163, 5395.032731997386, 6042.909356359658, 6226.968865155645, 6372.235331760218, 6476.838079048061, 978.5596481583174, 981.0532835813474, 982.2044144243185, 991.4051569058652, 1069.534752382716, 32385.35229295401, 33658.66341336555, 33746.0, 33876.91787564466, 33994.83002584971, 1065.2383545764815, 1069.1019543583272, 1107.0092267420614, 1123.0229700160912, 1163.2497039409702, 17535.649287014443, 17545.82454175442, 17560.121993900255, 17582.824171758373, 17667.55831104207, 163093.0, 164320.17839910724, 165040.0, 165190.0, 165327.0, 59666.701666492256, 59984.40015599875, 60015.699921500986, 60023.09965350523, 60125.69937150374, 1256.6104507602672, 1271.9809202861964, 1322.550332886823, 1368.1790925444654, 1389.0346209384381, 31210.531842021934, 33764.66235337615, 34855.0, 36291.637083628826, 36403.08558344589, 58268.4173158263, 58426.12360814506, 58450.707746461, 58550.0, 58559.707201465426, 516816.0, 520833.0, 520847.0, 523116.0, 523263.0, 28357.716422836333, 29698.55452168176, 29863.0, 29932.401351973273, 29949.0, 49427.50572494229, 49462.0, 50260.0, 50453.24320135127, 50879.74560127176, 41324.1735165301, 41769.58230417657, 42488.57511424846, 43075.353869692604, 43571.56428435675, 41903.21951804729, 42532.36201456919, 42564.44613323211, 42787.358189627805, 43004.78497607617, 40855.0, 41870.371944421466, 42266.36600450934, 42315.78842105893, 42434.787826060674, 34776.0, 36966.0, 37806.62193378141, 38321.42517862179, 38545.421818673305, 28627.570586440805, 28674.56988145221, 28785.280367990967, 28824.711752883042, 28959.85520072386], "value": 20060.480464945966, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [377.52243412083703, 392.56033242768086, 401.963823255907, 404.2783631218275, 422.934445161, 13078.60764177075, 14827.555173344803, 18948.62102757946, 19731.0, 19870.70193947092, 18001.729974050366, 18714.0, 18870.622587548274, 18874.528136796587, 18915.148818303176, 5573.526250268722, 5622.859428514299, 5866.413358664142, 5874.911876321838, 5875.559333050017, 5654.9151762723595, 5784.479396854283, 5792.623479473842, 5794.449527294901, 5795.536357091422, 5595.552355811526, 5678.472700418137, 5743.908657355089, 5754.367019627837, 5756.222909907151, 5669.9433005669825, 5706.0, 5710.600257981943, 5742.310922689262, 5755.366909639936, 4972.5276098770555, 5087.923681144785, 5140.074786538422, 5163.3545806774155, 5171.172612382038, 22383.776162238493, 22419.439514012116, 22426.215082472034, 22578.54842903133, 22597.54804903909, 2486.8880900359527, 2801.5937689034977, 2910.2287893708185, 3046.0136130239275, 3109.8445077746023, 13604.795928061087, 14422.206778627171, 14446.855531444657, 14628.853711462962, 14646.290436269217, 1921.269917431367, 1940.1075505267452, 1997.950051248716, 1998.7501562304712, 2069.658506346451, 81364.9999999994, 81677.0, 82070.58964705198, 84152.57923710342, 84327.5783621078, 10157.339772914736, 10267.691969240936, 10443.0, 10598.788024239471, 10790.352578845295, 14232.0, 14336.139831610137, 14455.566333009816, 14470.27648617565, 14476.032413355237, 8232.588370581567, 8302.294304984143, 8460.74617761456, 8462.449940753955, 8479.70321038773, 8020.639071241808, 8043.879341809878, 8102.837943241102, 8296.460730052528, 8299.211574900375, 6246.437820596168, 6390.648514331757, 6433.935660643334, 6489.156409666713, 6616.569922954993, 1853.8238867307584, 1882.2282864025683, 1893.5644801695687, 1915.5594213330733, 1963.783983761785, 914.8033172867865, 954.4034978138664, 978.5205249427871, 1017.1760873692333, 1052.726291164303, 19182.616347672967, 19543.706844397344, 19621.117049732795, 19657.311994080137, 19770.497853092485, 997.8253805583994, 1002.3685078400707, 1008.1128606825946, 1018.3686114608842, 1056.7727938493108, 10484.580616775243, 10928.01647851697, 10953.014228719454, 11116.555337786398, 11151.219414640897, 472274.0, 479092.0, 479528.0, 480290.0, 481548.0, 82492.0, 82843.5857820707, 83720.58139709262, 88099.55950220207, 100724.49637751764, 451.84185535063125, 469.6477641768673, 470.05049799404327, 1018.5874720738113, 1074.167520171873, 9983.500824958868, 10417.47912604382, 10648.414337211218, 10700.946495267473, 10739.409332486788, 41557.58442415537, 41862.37206441845, 41892.0, 42267.0, 42452.0, 55682.72158639181, 57272.427275728376, 57391.98256122199, 57461.712691436274, 58279.708601456725, 8072.475289106189, 8262.628181731956, 8429.072801991653, 8508.617112230088, 8620.0, 12006.879931200576, 12548.560800371943, 13159.342032898125, 13261.66845828862, 13282.867171328164, 11327.603533876274, 11614.709632258922, 11635.88364116348, 11987.0, 12003.699907502032, 11347.602833900773, 11634.476448560004, 11659.941700291443, 11796.882031179579, 11854.822177667515, 11937.28376297425, 11960.342181180118, 12103.878961210276, 12257.95807356349, 12295.262284262973, 8324.750257492286, 8400.201980811813, 8446.070932197576, 8658.740237792876, 8701.216890479887, 10320.587176512856, 11540.192186546861, 11608.361540115375, 11628.476718547516, 12029.578964736193], "value": 9128.39291189631, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1317.22283852527, 1448.2758620689658, 1463.1294379843991, 1577.5661693034415, 1732.2118436111562, 7604.163542010381, 8409.0, 8615.052344242144, 8618.181272779091, 8742.562871856398, 8601.139886011395, 8663.047064822864, 8671.913280867206, 8673.219410253076, 8675.47947123173, 2413.8189635777344, 2421.789105447276, 2440.0, 2440.4752978109705, 2441.377448750566, 2321.6401457774073, 2344.9564943600103, 2369.5379401016808, 2372.50177462733, 2372.738998710149, 2327.6857624220684, 2330.9743712766413, 2350.5651454480976, 2367.763223677636, 2370.6444033394955, 2319.9072037118494, 2328.4178955261186, 2336.2757545161053, 2338.918137865175, 2366.4438856868624, 2267.6598510223434, 2323.1288266899915, 2333.101755824003, 2333.7316208635884, 2345.882705864717, 7470.551766893949, 7633.694652213905, 8502.702405415843, 8700.477971321741, 8822.735317940473, 4846.491118432549, 5008.724520151391, 5009.974950125263, 5102.974485127587, 5106.259592359087, 3267.9183020424443, 3372.4435468147726, 3444.569428821397, 3451.6893479586956, 3461.809600471973, 1096.9286996345213, 1126.4818183635537, 1139.732162941708, 1160.0603511155991, 1160.211056481592, 12019.57931472403, 12028.578999735055, 12035.277883327028, 12049.698757531045, 12142.0, 4476.529964353696, 4588.931166032512, 4722.173619616553, 4732.5977291929885, 4777.355057067321, 10249.077583017564, 10256.641017564349, 10259.179265658879, 10261.846072308772, 10266.384016959006, 5312.654677445954, 5322.3879253885525, 5337.119375303031, 5355.866103347447, 5367.75845086972, 4792.329073929653, 4924.3105965164905, 4929.3345398371475, 4933.605311575066, 4933.6299777516115, 4076.2051399977313, 4110.938335924963, 4142.523609784913, 4197.958020419756, 4245.596668316505, 1347.9865201348057, 1358.4158811711059, 1359.1709057474932, 1361.006465280351, 1370.5134677189653, 881.96809732613, 943.8301105800884, 951.4909523404945, 963.4123184857232, 988.1501908358806, 5691.117876729114, 5716.428357164334, 5721.856953576111, 5771.971140144273, 6105.908411373833, 433.3348310343607, 435.3404592043057, 438.3402978517375, 447.33570647588306, 452.1386758225585, 3900.9804950975063, 4018.2967980603275, 4041.6766658667234, 4051.453053837753, 4186.811593478301, 10501.632442864464, 11054.115670746482, 11059.668209953714, 11088.112950963903, 11093.168012399103, 11058.447077646086, 11064.557417703201, 11066.446677666085, 11067.280626759235, 11072.501737421835, 8047.637856296481, 8077.111517733045, 8135.227153420534, 8153.429259951747, 8229.218224268685, 5664.405237450009, 5803.129530570448, 5814.156947242542, 5826.94173058264, 5997.940020599738, 9961.452120133463, 10017.799644006933, 10057.446840423847, 10111.292209545556, 10324.174066074545, 9003.954980225057, 9312.44125352481, 9317.86023209666, 9608.711738647851, 10289.176865850564, 7869.606519674108, 8007.1592482788465, 8010.759677209693, 8022.237887400687, 8025.759227223192, 8336.333093352638, 8355.12271211514, 8391.496510209407, 8398.286145677686, 8405.327573794204, 7727.227277272228, 7757.844843102994, 7776.961115194388, 7799.1420943697285, 7819.178986206366, 7448.73929412468, 7546.849063018599, 7638.618069096634, 7641.808954776175, 7722.498037627536, 6936.237013928565, 7174.748883789042, 7218.530795498275, 7222.96388518054, 7246.492745507764, 4202.306619407886, 4816.662833601615, 4838.540338667821, 4949.975250123726, 5187.533122019036, 5185.403678577012, 5208.114620514598, 5218.139007063792, 5300.284461597789, 5385.676859388449], "value": 4531.095601783952, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [321.3465952563122, 325.55886773422026, 331.98008119512843, 339.53822800990656, 345.44555987639836, 5176.145935920574, 5284.445133261005, 5327.760250788714, 5342.813001544947, 5370.033393989081, 5192.974035129819, 5194.298769666094, 5209.192575150858, 5249.370075590934, 5328.706921119347, 4161.708680392374, 4165.187788381267, 4168.187203495319, 4172.102997855461, 4184.0, 3839.6736277416453, 4070.287699652564, 4131.917361652765, 4164.916701665965, 4165.416841642173, 4078.1232035112444, 4080.8571699990507, 4082.101937573729, 4084.65280451162, 4085.734427262233, 4078.4086307485395, 4088.7342322748927, 4098.446709694183, 4101.395613785253, 4110.17796440713, 3565.839537220805, 3646.398344273192, 3647.6534729200685, 3650.926981460356, 3653.214558869829, 5076.390833100015, 5083.0, 5092.592592592584, 5126.384833819928, 5155.484451554852, 4607.907841843178, 4608.608268297199, 4613.423322084739, 4614.538546145359, 4646.651501137429, 5194.68831870089, 5307.522322990911, 5380.762424642354, 5405.486478784509, 5429.375621803503, 3888.727789054767, 3902.2195560887935, 3936.921261574781, 3955.6044395560502, 3956.485656864618, 5479.4520547945285, 5636.351819540764, 5660.60375773696, 5699.064981534513, 5769.07694768835, 3871.825767840454, 3910.511186101737, 3914.5693973662874, 3914.667253283446, 4015.9397609035886, 3997.92004159921, 4041.6160464755803, 4051.1897620476025, 4060.2285565742413, 4088.2436749200897, 4464.15181115587, 4485.865424037219, 4587.655925805511, 4667.603253723403, 4692.577668009895, 5409.83770486886, 5414.0, 5439.972800136054, 5448.809291674773, 5449.373322068013, 1333.759923213831, 1361.3465536542515, 1366.419271809477, 1369.869862363074, 1385.4665953607935, 899.6311512279934, 924.5654542365078, 928.4197376639601, 938.8357037518407, 944.3767113704922, 4660.953390466119, 4664.4869064403565, 4672.159011377917, 4721.929171062437, 4724.622030237572, 4869.975650121727, 4925.631062142211, 4926.487491379422, 4966.602671786249, 4985.825496107619, 4497.2804351304285, 4680.882977925579, 4688.320193571981, 4731.905361892743, 4733.905321893543, 2600.54490464168, 2621.7808718945403, 2622.4230669252734, 2627.7240889706304, 2637.8681065946625, 5032.823851165265, 5063.8480845574695, 5112.897742045213, 5123.38519377677, 5141.460146684619, 4840.757962101951, 5074.0, 5077.949220507821, 5095.388553373619, 5128.948710512847, 5030.597552195815, 5030.748462576857, 5043.899122017612, 5077.365329333833, 5092.28707980883, 4918.786546054218, 4954.479779623088, 4957.776900039435, 4973.502649735142, 4978.253262010728, 4521.344405061313, 4876.487968763229, 4889.7507146712915, 4917.631177661761, 5281.260623512788, 5581.469760372758, 5606.495415412633, 5761.740721667619, 5777.0179069557435, 5789.652620842763, 4007.6192761687594, 4008.5590585036207, 4017.276890159799, 4029.9395509066803, 4049.635532802062, 5448.0, 5672.404397538199, 5713.143028545752, 5713.885722285448, 5722.99695106726, 5519.779208831763, 5527.50252477279, 5528.9723551381985, 5530.751116199861, 5535.723213839373, 5445.373782014961, 5474.753636086306, 5505.825199046434, 5600.411956744646, 5609.186667933209, 5280.867978300574, 5498.835034948958, 5528.655163625948, 5550.500454959073, 5558.194061860927, 5596.38439771633, 5599.300087489064, 5605.915911261416, 5606.719664016701, 5690.260266165372, 5198.9740051299505, 5474.698891561003, 5484.506394424521, 5485.341758988947, 5487.807926722546], "value": 4057.60062931822, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [714.8212946763309, 739.305053249945, 774.0696563704845, 815.6737305077971, 839.223718060794, 15143.848561514382, 16133.515994520183, 16181.433649822258, 16318.265678044487, 16429.26068326925, 13680.931595342034, 13738.79391809121, 13770.518031868887, 14600.0, 14807.703845923075, 806.8547661420956, 822.1203312455675, 825.0, 825.0553116681397, 825.4222044568826, 669.7153709673394, 675.1594265139908, 685.4824607421415, 687.340153452686, 816.302061737215, 832.0348395860802, 853.3727710133078, 856.4861083350003, 856.6145234644404, 857.4126723194601, 838.304207507767, 839.5466448118009, 840.0801122770526, 840.3193413335188, 840.6301227459898, 502.16640376974414, 609.7256234694405, 611.1932249430753, 611.3641812514979, 611.6635850282371, 11843.58547450835, 12410.007199424024, 12416.441260143225, 14670.853291467056, 15033.849661503355, 14184.29078546079, 14747.77878331826, 16148.59628509285, 16161.515154545383, 16325.918370408192, 11800.586979455764, 11844.763104737944, 12043.93978030113, 12200.877991220063, 12239.32683702396, 3769.4534292527705, 3787.5833658297565, 3864.4589757433982, 3872.3223435898603, 3887.241987812378, 84172.57913710453, 85981.0, 87647.56176219079, 87901.56049219776, 90826.0, 11990.460429280703, 11997.485556919131, 12084.154109212446, 12396.070294727931, 12933.54732584355, 23666.0, 23704.881475592512, 23719.644205336936, 23753.881230593735, 23957.28128156158, 8593.312534997185, 8704.390692651581, 8910.55447227636, 8941.642334306685, 9186.632534698538, 4541.9545804542195, 4577.977110114428, 4579.610733087725, 4597.310403439444, 4696.2486002239475, 3453.5165076889257, 3544.2557063017057, 3552.0942159749097, 3555.1289933966345, 3577.5349204603754, 592.6236839606795, 601.4887345756159, 626.7399029402746, 669.1434963247052, 673.2627772589008, 711.6512908674712, 711.7757906259511, 713.2225873797586, 725.1298441869777, 756.5347311403557, 9720.368176068532, 9769.413835169913, 10176.643817466353, 10268.386197281343, 10597.41714205726, 598.3717097048082, 736.7752835385259, 740.1377395334483, 740.6741033945046, 751.3921544363321, 4599.632029437637, 4900.779464924087, 4924.162892308244, 4938.38270216223, 4943.134951383494, 76869.0, 77783.61108194423, 112461.0, 115015.0, 119041.0, 94746.0, 98285.50857245667, 98487.0, 99242.0, 101451.0, 82986.0, 91039.0, 91864.0, 94123.52938235401, 94200.52899735457, 9386.15524602789, 10093.545790439595, 10240.290792080696, 10315.638952636622, 10479.266451348638, 46199.53800462087, 46510.302345464166, 48728.5127148724, 51784.74107629438, 53540.46459535355, 49707.502924970286, 51431.97136057333, 64244.0, 69749.65125174342, 74495.62752186421, 13388.330583470983, 13687.19381753399, 13703.657408564866, 13738.175709457464, 13849.16904985704, 14904.403823847359, 15131.394744210109, 15148.16685082331, 15232.847671523143, 15424.46014389491, 13394.330283485982, 14558.344874480463, 15141.242937853283, 15479.613009674847, 15592.454264100703, 13456.25990570528, 13572.253526056162, 13970.930145349208, 14221.928890355483, 14625.0, 13177.209367437985, 13285.468581256642, 13299.0, 13353.66615834612, 13809.447622095004, 11106.833397499206, 11202.83195752048, 11480.712982175512, 12054.939725301318, 12444.502219911103, 12103.697407564881, 12261.26432414058, 12329.843192236147, 12502.499900003899, 12545.0], "value": 7617.924731038546, "unit": "score"}], "name": "dromaeo_css", "value": 7152.5834558219, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 114.0, 113.0, 111.0, 112.0, 115.0, 112.0, 112.0, 113.0, 112.0], "value": 112.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 122.0, 121.0, 161.0, 129.0, 120.0, 119.0, 119.0, 147.0, 122.0], "value": 130.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 160.0, 159.0, 161.0, 160.0, 162.0, 162.0, 160.0, 161.0], "value": 160.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 77.0, 106.0, 77.0, 76.0, 76.0, 77.0, 98.0, 78.0], "value": 81.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 111.0, 82.0, 82.0, 82.0, 109.0, 81.0, 81.0, 118.0], "value": 91.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 117.0, 116.0, 116.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 145.0, 145.0, 145.0, 145.0, 145.0, 145.0, 145.0, 145.0], "value": 145.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 96.0, 96.0, 95.0, 95.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 50.0, 49.0, 51.0, 50.0, 51.0, 51.0, 51.0, 52.0, 51.0], "value": 50.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 61.0, 61.0, 62.0, 61.0, 60.0, 60.0, 60.0, 62.0], "value": 61.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 78.0, 78.0, 78.0, 77.0, 78.0, 83.0, 78.0, 79.0, 77.0], "value": 78.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 125.0, 132.0, 129.0, 131.0, 140.0, 122.0, 129.0, 172.0, 125.0], "value": 133.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 167.0, 165.0, 172.0, 172.0, 165.0, 182.0, 172.0, 170.0, 167.0], "value": 169.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 67.0, 70.0, 64.0, 66.0, 72.0, 68.0, 65.0, 65.0], "value": 66.8, "unit": "score"}], "name": "kraken", "value": 1492.9, "alertThreshold": 2.0}]} 06:46:30 INFO - Return code: 0 06:46:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:46:30 INFO - # TBPL SUCCESS # 06:46:30 INFO - Running post-action listener: _resource_record_post_action 06:46:30 INFO - [mozharness: 2016-09-17 13:46:30.472712Z] Finished run-tests step (success) 06:46:30 INFO - Running post-run listener: _resource_record_post_run 06:46:30 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 4096; Write bytes: 410640384; Read time: 20; Write time: 311716 06:46:30 INFO - TinderboxPrint: CPU usage
13.1% 06:46:30 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 20 06:46:30 INFO - TinderboxPrint: I/O write bytes / time
410,640,384 / 311,716 06:46:30 INFO - TinderboxPrint: CPU idle
8,274.2 (86.8%) 06:46:30 INFO - TinderboxPrint: CPU user
1,201.1 (12.6%) 06:46:30 INFO - TinderboxPrint: Swap in / out
0 / 0 06:46:30 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 119750656; Read time: 0; Write time: 74956 06:46:30 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 0; Write bytes: 289865728; Read time: 0; Write time: 236732 06:46:31 INFO - Running post-run listener: _upload_blobber_files 06:46:31 INFO - Blob upload gear active. 06:46:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:46:31 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 06:46:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:46:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:46:31 INFO - (blobuploader) - INFO - Open directory for files ... 06:46:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:46:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:46:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:46:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:46:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:46:32 INFO - (blobuploader) - INFO - Done attempting. 06:46:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:46:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:46:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:46:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:46:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:46:33 INFO - (blobuploader) - INFO - Done attempting. 06:46:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:46:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:46:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:46:36 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:46:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:46:36 INFO - (blobuploader) - INFO - Done attempting. 06:46:36 INFO - (blobuploader) - INFO - Iteration through files over. 06:46:36 INFO - Return code: 0 06:46:36 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:46:36 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:46:36 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/4973e70ff25f62ed7c24e086505e06579c27d06afc5e219d43cf5d29d5b2af9ea49c5aee085684a3596918e18c60e9878d7fbe6a96f8955729a169ed79f5fc43", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d0e38c4f4edea19b2b824ef50c6a9065bd0e7b2ddcf27e36018775568e6a85f43f0e9d5ea35dba5f8502c64feaaf03e6f1ee0182d47b1e9694ba89db5da073ac", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/8fcb11c13dc8e3d64d8916e51ebf7337e5476675fc4bb13f2f035d7faa6ed3c76ed0e1571b50bfc75f9b54226d566419fbfb1e432482c4265690ee29ecb7a036"} 06:46:36 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:46:36 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:46:36 INFO - Contents: 06:46:36 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/4973e70ff25f62ed7c24e086505e06579c27d06afc5e219d43cf5d29d5b2af9ea49c5aee085684a3596918e18c60e9878d7fbe6a96f8955729a169ed79f5fc43", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d0e38c4f4edea19b2b824ef50c6a9065bd0e7b2ddcf27e36018775568e6a85f43f0e9d5ea35dba5f8502c64feaaf03e6f1ee0182d47b1e9694ba89db5da073ac", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/8fcb11c13dc8e3d64d8916e51ebf7337e5476675fc4bb13f2f035d7faa6ed3c76ed0e1571b50bfc75f9b54226d566419fbfb1e432482c4265690ee29ecb7a036"} 06:46:36 INFO - Running post-run listener: copy_logs_to_upload_dir 06:46:36 INFO - Copying logs to upload dir... 06:46:36 INFO - mkdir: /builds/slave/test/build/upload/logs 06:46:36 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1237.283885 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-09-17 06:46:36.310120) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-17 06:46:36.315411) ========= 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=62c568580acbfdaac3eec7be000001dc-1474114211.156946-886130276 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/4973e70ff25f62ed7c24e086505e06579c27d06afc5e219d43cf5d29d5b2af9ea49c5aee085684a3596918e18c60e9878d7fbe6a96f8955729a169ed79f5fc43", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d0e38c4f4edea19b2b824ef50c6a9065bd0e7b2ddcf27e36018775568e6a85f43f0e9d5ea35dba5f8502c64feaaf03e6f1ee0182d47b1e9694ba89db5da073ac", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/8fcb11c13dc8e3d64d8916e51ebf7337e5476675fc4bb13f2f035d7faa6ed3c76ed0e1571b50bfc75f9b54226d566419fbfb1e432482c4265690ee29ecb7a036"} build_url:https://queue.taskcluster.net/v1/task/UG5r-Cb5QfW1wupiktEfmg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011248 build_url: 'https://queue.taskcluster.net/v1/task/UG5r-Cb5QfW1wupiktEfmg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/4973e70ff25f62ed7c24e086505e06579c27d06afc5e219d43cf5d29d5b2af9ea49c5aee085684a3596918e18c60e9878d7fbe6a96f8955729a169ed79f5fc43", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d0e38c4f4edea19b2b824ef50c6a9065bd0e7b2ddcf27e36018775568e6a85f43f0e9d5ea35dba5f8502c64feaaf03e6f1ee0182d47b1e9694ba89db5da073ac", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/8fcb11c13dc8e3d64d8916e51ebf7337e5476675fc4bb13f2f035d7faa6ed3c76ed0e1571b50bfc75f9b54226d566419fbfb1e432482c4265690ee29ecb7a036"}' ========= master_lag: 0.35 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-17 06:46:36.680070) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 06:46:36.680445) ========= 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=62c568580acbfdaac3eec7be000001dc-1474114211.156946-886130276 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004501 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 06:46:36.760738) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-17 06:46:36.761086) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-17 06:46:36.761404) ========= ========= Total master_lag: 0.58 =========