builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-068 starttime: 1474252509.3 results: success (0) buildid: 20160918184618 builduid: e661361069904989a0385202371e0892 revision: 1164e024099e710c1cfce2377dea4163cdcaa8bc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-18 19:35:09.301218) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-18 19:35:09.301826) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-18 19:35:09.302337) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-18 19:35:09.325892) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-18 19:35:09.326207) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1474242289.533299-77868688 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-18 19:35:10-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.1M=0s 2016-09-18 19:35:11 (46.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.171030 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-18 19:35:09.515400) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-18 19:35:09.515761) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1474242289.533299-77868688 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019227 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-18 19:35:09.569433) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-18 19:35:09.570292) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 1164e024099e710c1cfce2377dea4163cdcaa8bc --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 1164e024099e710c1cfce2377dea4163cdcaa8bc --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=97facb8ba2ac28944cc45e6b000001d4-1474242289.533299-77868688 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-18 19:35:11,252 truncating revision to first 12 chars 2016-09-18 19:35:11,252 Setting DEBUG logging. 2016-09-18 19:35:11,252 attempt 1/10 2016-09-18 19:35:11,252 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/1164e024099e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-18 19:35:11,782 unpacking tar archive at: fx-team-1164e024099e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.851116 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-18 19:35:10.436354) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-18 19:35:10.436765) ========= script_repo_revision: 1164e024099e710c1cfce2377dea4163cdcaa8bc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-18 19:35:10.437257) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-18 19:35:10.437575) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-18 19:35:10.446599) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2016-09-18 19:35:10.446950) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=97facb8ba2ac28944cc45e6b000001d4-1474242289.533299-77868688 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:35:12 INFO - MultiFileLogger online at 20160918 19:35:12 in /builds/slave/test 19:35:12 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 19:35:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:35:12 INFO - {'append_to_log': False, 19:35:12 INFO - 'base_work_dir': '/builds/slave/test', 19:35:12 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 19:35:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:35:12 INFO - 'branch': 'Fx-Team-Non-PGO', 19:35:12 INFO - 'buildbot_json_path': 'buildprops.json', 19:35:12 INFO - 'config_files': ('talos/linux_config.py',), 19:35:12 INFO - 'default_actions': ('clobber', 19:35:12 INFO - 'read-buildbot-config', 19:35:12 INFO - 'download-and-extract', 19:35:12 INFO - 'populate-webroot', 19:35:12 INFO - 'create-virtualenv', 19:35:12 INFO - 'install', 19:35:12 INFO - 'run-tests'), 19:35:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:35:12 INFO - 'download_minidump_stackwalk': True, 19:35:12 INFO - 'download_symbols': 'ondemand', 19:35:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:35:12 INFO - 'tooltool.py': '/tools/tooltool.py', 19:35:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:35:12 INFO - '/tools/misc-python/virtualenv.py')}, 19:35:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:35:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:35:12 INFO - 'installer_path': 'installer.exe', 19:35:12 INFO - 'log_level': 'info', 19:35:12 INFO - 'log_name': 'talos', 19:35:12 INFO - 'log_to_console': True, 19:35:12 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:35:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:35:12 INFO - 'opt_config_files': (), 19:35:12 INFO - 'pip_index': False, 19:35:12 INFO - 'sps_profile': False, 19:35:12 INFO - 'sps_profile_interval': 0, 19:35:12 INFO - 'suite': 'dromaeojs', 19:35:12 INFO - 'system_bits': '32', 19:35:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:35:12 INFO - 'title': 'talos-linux64-ix-068', 19:35:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:35:12 INFO - 'use_talos_json': True, 19:35:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:35:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:35:12 INFO - 'work_dir': 'build'} 19:35:12 INFO - [mozharness: 2016-09-19 02:35:12.216980Z] Running clobber step. 19:35:12 INFO - Running pre-action listener: _resource_record_pre_action 19:35:12 INFO - Running main action method: clobber 19:35:12 INFO - rmtree: /builds/slave/test/build 19:35:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:35:12 INFO - Running post-action listener: _resource_record_post_action 19:35:12 INFO - [mozharness: 2016-09-19 02:35:12.676300Z] Finished clobber step (success) 19:35:12 INFO - [mozharness: 2016-09-19 02:35:12.676401Z] Running read-buildbot-config step. 19:35:12 INFO - Running pre-action listener: _resource_record_pre_action 19:35:12 INFO - Running main action method: read_buildbot_config 19:35:12 INFO - Using buildbot properties: 19:35:12 INFO - { 19:35:12 INFO - "project": "", 19:35:12 INFO - "product": "firefox", 19:35:12 INFO - "script_repo_revision": "production", 19:35:12 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 19:35:12 INFO - "repository": "", 19:35:12 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 19:35:12 INFO - "buildid": "20160918184618", 19:35:12 INFO - "pgo_build": "False", 19:35:12 INFO - "basedir": "/builds/slave/test", 19:35:12 INFO - "buildnumber": 3332, 19:35:12 INFO - "slavename": "talos-linux64-ix-068", 19:35:12 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 19:35:12 INFO - "platform": "ubuntu64_hw", 19:35:12 INFO - "scheduler": "tests-fx-team-linux64-talos", 19:35:12 INFO - "branch": "fx-team", 19:35:12 INFO - "repo_path": "integration/fx-team", 19:35:12 INFO - "revision": "1164e024099e710c1cfce2377dea4163cdcaa8bc", 19:35:12 INFO - "stage_platform": "linux64", 19:35:12 INFO - "builduid": "e661361069904989a0385202371e0892", 19:35:12 INFO - "slavebuilddir": "test" 19:35:12 INFO - } 19:35:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/JrzmoW4DRC2Nk-cWFrRkzA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 19:35:12 INFO - Running post-action listener: _resource_record_post_action 19:35:12 INFO - [mozharness: 2016-09-19 02:35:12.678851Z] Finished read-buildbot-config step (success) 19:35:12 INFO - [mozharness: 2016-09-19 02:35:12.678940Z] Running download-and-extract step. 19:35:12 INFO - Running pre-action listener: _resource_record_pre_action 19:35:12 INFO - Running main action method: download_and_extract 19:35:12 INFO - mkdir: /builds/slave/test/build/tests 19:35: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')]} 19:35:12 INFO - trying https://queue.taskcluster.net/v1/task/JrzmoW4DRC2Nk-cWFrRkzA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 19:35:12 INFO - Downloading https://queue.taskcluster.net/v1/task/JrzmoW4DRC2Nk-cWFrRkzA/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 19:35:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JrzmoW4DRC2Nk-cWFrRkzA/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 19:35:13 INFO - Downloaded 1361 bytes. 19:35:13 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 19:35:13 INFO - Using the following test package requirements: 19:35:13 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 19:35:13 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:35:13 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 19:35:13 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:35:13 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 19:35:13 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:35:13 INFO - u'jsshell-linux-x86_64.zip'], 19:35:13 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:35:13 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 19:35:13 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 19:35:13 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:35:13 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 19:35:13 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:35:13 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 19:35:13 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:35:13 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 19:35:13 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:35:13 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 19:35:13 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 19:35:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JrzmoW4DRC2Nk-cWFrRkzA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 19:35:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JrzmoW4DRC2Nk-cWFrRkzA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 19:35:13 INFO - Fetch https://queue.taskcluster.net/v1/task/JrzmoW4DRC2Nk-cWFrRkzA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 19:35:14 INFO - Expected file size: 20365610 19:35:18 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 19:35:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JrzmoW4DRC2Nk-cWFrRkzA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 19:35:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JrzmoW4DRC2Nk-cWFrRkzA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 19:35:18 INFO - Fetch https://queue.taskcluster.net/v1/task/JrzmoW4DRC2Nk-cWFrRkzA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 19:35:19 INFO - Expected file size: 20365610 19:35:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JrzmoW4DRC2Nk-cWFrRkzA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 19:35:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JrzmoW4DRC2Nk-cWFrRkzA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 19:35:24 INFO - Fetch https://queue.taskcluster.net/v1/task/JrzmoW4DRC2Nk-cWFrRkzA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip into memory 19:35:25 INFO - Expected file size: 14414059 19:35:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:35:26 INFO - trying https://queue.taskcluster.net/v1/task/JrzmoW4DRC2Nk-cWFrRkzA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 19:35:26 INFO - Downloading https://queue.taskcluster.net/v1/task/JrzmoW4DRC2Nk-cWFrRkzA/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 19:35:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JrzmoW4DRC2Nk-cWFrRkzA/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 19:35:28 INFO - Downloaded 55603929 bytes. 19:35:28 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JrzmoW4DRC2Nk-cWFrRkzA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 19:35:28 INFO - mkdir: /builds/slave/test/properties 19:35:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:35:28 INFO - Writing to file /builds/slave/test/properties/build_url 19:35:28 INFO - Contents: 19:35:28 INFO - build_url:https://queue.taskcluster.net/v1/task/JrzmoW4DRC2Nk-cWFrRkzA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 19:35:28 INFO - Running post-action listener: _resource_record_post_action 19:35:28 INFO - Running post-action listener: set_extra_try_arguments 19:35:28 INFO - [mozharness: 2016-09-19 02:35:28.984637Z] Finished download-and-extract step (success) 19:35:28 INFO - [mozharness: 2016-09-19 02:35:28.984863Z] Running populate-webroot step. 19:35:28 INFO - Running pre-action listener: _resource_record_pre_action 19:35:28 INFO - Running main action method: populate_webroot 19:35:29 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:35:29 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:35:29 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:35:29 INFO - '--tppagecycles', 19:35:29 INFO - '7'], 19:35:29 INFO - 'tests': ['tsvgm']}}, 19:35:29 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:35:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:35:29 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:35:29 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:35:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:35:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:35:29 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:35:29 INFO - 'talos_options': ['--disable-e10s'], 19:35:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:35:29 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:35:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:35:29 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:35:29 INFO - 'talos_options': ['--disable-e10s'], 19:35:29 INFO - 'tests': ['damp', 'tps']}, 19:35:29 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:35:29 INFO - 'tests': ['damp', 'tps']}, 19:35:29 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:35:29 INFO - 'tests': ['dromaeo_dom']}, 19:35:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:35:29 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:35:29 INFO - 'tests': ['basic_compositor_video']}, 19:35:29 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:35:29 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:35:29 INFO - 'tests': ['a11yr', 19:35:29 INFO - 'ts_paint', 19:35:29 INFO - 'tpaint', 19:35:29 INFO - 'sessionrestore', 19:35:29 INFO - 'sessionrestore_no_auto_restore', 19:35:29 INFO - 'tabpaint']}, 19:35:29 INFO - 'other-e10s': {'tests': ['a11yr', 19:35:29 INFO - 'ts_paint', 19:35:29 INFO - 'tpaint', 19:35:29 INFO - 'sessionrestore', 19:35:29 INFO - 'sessionrestore_no_auto_restore', 19:35:29 INFO - 'tabpaint']}, 19:35:29 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:35:29 INFO - 'ts_paint', 19:35:29 INFO - 'tpaint', 19:35:29 INFO - 'sessionrestore', 19:35:29 INFO - 'sessionrestore_no_auto_restore', 19:35:29 INFO - 'tabpaint']}, 19:35:29 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:35:29 INFO - 'ts_paint', 19:35:29 INFO - 'tpaint', 19:35:29 INFO - 'sessionrestore', 19:35:29 INFO - 'sessionrestore_no_auto_restore', 19:35:29 INFO - 'tabpaint']}, 19:35:29 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:35:29 INFO - 'tests': ['a11yr', 19:35:29 INFO - 'ts_paint', 19:35:29 INFO - 'tpaint', 19:35:29 INFO - 'sessionrestore', 19:35:29 INFO - 'sessionrestore_no_auto_restore', 19:35:29 INFO - 'tabpaint']}, 19:35:29 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:35:29 INFO - 'tests': ['a11yr', 19:35:29 INFO - 'ts_paint', 19:35:29 INFO - 'tpaint', 19:35:29 INFO - 'sessionrestore', 19:35:29 INFO - 'sessionrestore_no_auto_restore', 19:35:29 INFO - 'tabpaint']}, 19:35:29 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:35:29 INFO - 'tests': ['tsvgx', 19:35:29 INFO - 'tsvgr_opacity', 19:35:29 INFO - 'tart', 19:35:29 INFO - 'tscrollx', 19:35:29 INFO - 'cart']}, 19:35:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:35:29 INFO - 'tsvgr_opacity', 19:35:29 INFO - 'tart', 19:35:29 INFO - 'tscrollx', 19:35:29 INFO - 'cart']}, 19:35:29 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:35:29 INFO - 'talos_options': ['--disable-e10s'], 19:35:29 INFO - 'tests': ['tp5o']}, 19:35:29 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:35:29 INFO - 'tests': ['tp5o']}, 19:35:29 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:35:29 INFO - 'talos_options': ['--disable-e10s', 19:35:29 INFO - '--xperf_path', 19:35:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:35:29 INFO - 'tests': ['tp5n']}, 19:35:29 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:35:29 INFO - 'talos_options': ['--xperf_path', 19:35:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:35:29 INFO - 'tests': ['tp5n']}}, 19:35:29 INFO - 'talos.zip': {'path': '', 19:35:29 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:35:29 INFO - Running post-action listener: _resource_record_post_action 19:35:29 INFO - [mozharness: 2016-09-19 02:35:29.009232Z] Finished populate-webroot step (success) 19:35:29 INFO - [mozharness: 2016-09-19 02:35:29.009319Z] Running create-virtualenv step. 19:35:29 INFO - Running pre-action listener: _resource_record_pre_action 19:35:29 INFO - Running main action method: create_virtualenv 19:35:29 INFO - Creating virtualenv /builds/slave/test/build/venv 19:35:29 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 19:35:29 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:35:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:35:29 INFO - Using real prefix '/usr' 19:35:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:35:29 INFO - Installing distribute.............................................................................................................................................................................................done. 19:35:31 INFO - Installing pip.................done. 19:35:31 INFO - Return code: 0 19:35:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:35:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:35:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:35:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:35:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:35:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:35:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:35:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe15d53af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29b38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a80ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a7df60>, '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 0x2a7ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a7f1d0>, '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': '97facb8ba2ac28944cc45e6b000001d4-1474242289.533299-77868688', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:35:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 19:35:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 19:35:31 INFO - Using env: {'DISPLAY': ':0', 19:35:31 INFO - 'HOME': '/home/cltbld', 19:35:31 INFO - 'LANG': 'en_US.UTF-8', 19:35:31 INFO - 'LANGUAGE': 'en_US:en', 19:35:31 INFO - 'LOGNAME': 'cltbld', 19:35:31 INFO - 'MAIL': '/var/mail/cltbld', 19:35:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:35:31 INFO - 'MOZ_NO_REMOTE': '1', 19:35:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:35:31 INFO - 'NO_EM_RESTART': '1', 19:35:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:35:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:35:31 INFO - 'PWD': '/builds/slave/test', 19:35:31 INFO - 'SHELL': '/bin/bash', 19:35:31 INFO - 'SHLVL': '1', 19:35:31 INFO - 'TERM': 'linux', 19:35:31 INFO - 'TMOUT': '86400', 19:35:31 INFO - 'USER': 'cltbld', 19:35:31 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1474242289.533299-77868688', 19:35:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:35:31 INFO - '_': '/tools/buildbot/bin/python'} 19:35:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:35:31 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 19:35:31 INFO - Cleaning up... 19:35:31 INFO - Return code: 0 19:35:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:35:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:35:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:35:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:35:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:35:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:35:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:35:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe15d53af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29b38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a80ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a7df60>, '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 0x2a7ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a7f1d0>, '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': '97facb8ba2ac28944cc45e6b000001d4-1474242289.533299-77868688', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:35:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 19:35:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 19:35:31 INFO - Using env: {'DISPLAY': ':0', 19:35:31 INFO - 'HOME': '/home/cltbld', 19:35:31 INFO - 'LANG': 'en_US.UTF-8', 19:35:31 INFO - 'LANGUAGE': 'en_US:en', 19:35:31 INFO - 'LOGNAME': 'cltbld', 19:35:31 INFO - 'MAIL': '/var/mail/cltbld', 19:35:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:35:31 INFO - 'MOZ_NO_REMOTE': '1', 19:35:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:35:31 INFO - 'NO_EM_RESTART': '1', 19:35:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:35:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:35:31 INFO - 'PWD': '/builds/slave/test', 19:35:31 INFO - 'SHELL': '/bin/bash', 19:35:31 INFO - 'SHLVL': '1', 19:35:31 INFO - 'TERM': 'linux', 19:35:31 INFO - 'TMOUT': '86400', 19:35:31 INFO - 'USER': 'cltbld', 19:35:31 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1474242289.533299-77868688', 19:35:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:35:31 INFO - '_': '/tools/buildbot/bin/python'} 19:35:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:35:32 INFO - Downloading/unpacking psutil>=3.1.1 19:35: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 19:35: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 19:35: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 19:35: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 19:35: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 19:35: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 19:35:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:35:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:35:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:35:34 INFO - Installing collected packages: psutil 19:35:34 INFO - Running setup.py install for psutil 19:35:34 INFO - building 'psutil._psutil_linux' extension 19:35:34 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 19:35:34 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 19:35:34 INFO - building 'psutil._psutil_posix' extension 19:35:34 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 19:35:34 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 19:35:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:35:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:35:34 INFO - Successfully installed psutil 19:35:34 INFO - Cleaning up... 19:35:34 INFO - Return code: 0 19:35:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:35:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:35:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:35:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:35:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:35:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:35:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:35:34 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 0x7fe15d53af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29b38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a80ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a7df60>, '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 0x2a7ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a7f1d0>, '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': '97facb8ba2ac28944cc45e6b000001d4-1474242289.533299-77868688', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:35:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 19:35:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 19:35:34 INFO - Using env: {'DISPLAY': ':0', 19:35:34 INFO - 'HOME': '/home/cltbld', 19:35:34 INFO - 'LANG': 'en_US.UTF-8', 19:35:34 INFO - 'LANGUAGE': 'en_US:en', 19:35:34 INFO - 'LOGNAME': 'cltbld', 19:35:34 INFO - 'MAIL': '/var/mail/cltbld', 19:35:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:35:34 INFO - 'MOZ_NO_REMOTE': '1', 19:35:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:35:34 INFO - 'NO_EM_RESTART': '1', 19:35:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:35:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:35:34 INFO - 'PWD': '/builds/slave/test', 19:35:34 INFO - 'SHELL': '/bin/bash', 19:35:34 INFO - 'SHLVL': '1', 19:35:34 INFO - 'TERM': 'linux', 19:35:34 INFO - 'TMOUT': '86400', 19:35:34 INFO - 'USER': 'cltbld', 19:35:34 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1474242289.533299-77868688', 19:35:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:35:34 INFO - '_': '/tools/buildbot/bin/python'} 19:35:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:35:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:35:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:35:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:35:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:35:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:35:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:35:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:35:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:35:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:35:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:35:36 INFO - Installing collected packages: mozsystemmonitor 19:35:36 INFO - Running setup.py install for mozsystemmonitor 19:35:36 INFO - Successfully installed mozsystemmonitor 19:35:36 INFO - Cleaning up... 19:35:36 INFO - Return code: 0 19:35:36 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:35:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:35:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:35:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:35:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:35:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:35:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:35:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fe15d53af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29b38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a80ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a7df60>, '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 0x2a7ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a7f1d0>, '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': '97facb8ba2ac28944cc45e6b000001d4-1474242289.533299-77868688', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:35:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 19:35:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 19:35:36 INFO - Using env: {'DISPLAY': ':0', 19:35:36 INFO - 'HOME': '/home/cltbld', 19:35:36 INFO - 'LANG': 'en_US.UTF-8', 19:35:36 INFO - 'LANGUAGE': 'en_US:en', 19:35:36 INFO - 'LOGNAME': 'cltbld', 19:35:36 INFO - 'MAIL': '/var/mail/cltbld', 19:35:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:35:36 INFO - 'MOZ_NO_REMOTE': '1', 19:35:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:35:36 INFO - 'NO_EM_RESTART': '1', 19:35:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:35:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:35:36 INFO - 'PWD': '/builds/slave/test', 19:35:36 INFO - 'SHELL': '/bin/bash', 19:35:36 INFO - 'SHLVL': '1', 19:35:36 INFO - 'TERM': 'linux', 19:35:36 INFO - 'TMOUT': '86400', 19:35:36 INFO - 'USER': 'cltbld', 19:35:36 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1474242289.533299-77868688', 19:35:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:35:36 INFO - '_': '/tools/buildbot/bin/python'} 19:35:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:35:37 INFO - Downloading/unpacking jsonschema==2.5.1 19:35:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:35:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:35:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:35:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:35:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:35:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:35:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:35:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:35:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:35:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:35:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:35:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:35:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:35:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:35:39 INFO - Downloading functools32-3.2.3-2.tar.gz 19:35:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:35:39 INFO - warning: no files found matching '*.txt' 19:35:39 INFO - no previously-included directories found matching 'build' 19:35:39 INFO - no previously-included directories found matching 'dist' 19:35:39 INFO - no previously-included directories found matching '.git*' 19:35:39 INFO - Installing collected packages: jsonschema, functools32 19:35:39 INFO - Running setup.py install for functools32 19:35:39 INFO - warning: no files found matching '*.txt' 19:35:39 INFO - no previously-included directories found matching 'build' 19:35:39 INFO - no previously-included directories found matching 'dist' 19:35:39 INFO - no previously-included directories found matching '.git*' 19:35:39 INFO - Successfully installed jsonschema functools32 19:35:39 INFO - Cleaning up... 19:35:39 INFO - Return code: 0 19:35:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:35:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:35:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:35:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:35:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:35:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:35:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:35:39 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 0x7fe15d53af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29b38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a80ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a7df60>, '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 0x2a7ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a7f1d0>, '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': '97facb8ba2ac28944cc45e6b000001d4-1474242289.533299-77868688', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:35:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 19:35:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 19:35:39 INFO - Using env: {'DISPLAY': ':0', 19:35:39 INFO - 'HOME': '/home/cltbld', 19:35:39 INFO - 'LANG': 'en_US.UTF-8', 19:35:39 INFO - 'LANGUAGE': 'en_US:en', 19:35:39 INFO - 'LOGNAME': 'cltbld', 19:35:39 INFO - 'MAIL': '/var/mail/cltbld', 19:35:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:35:39 INFO - 'MOZ_NO_REMOTE': '1', 19:35:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:35:39 INFO - 'NO_EM_RESTART': '1', 19:35:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:35:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:35:39 INFO - 'PWD': '/builds/slave/test', 19:35:39 INFO - 'SHELL': '/bin/bash', 19:35:39 INFO - 'SHLVL': '1', 19:35:39 INFO - 'TERM': 'linux', 19:35:39 INFO - 'TMOUT': '86400', 19:35:39 INFO - 'USER': 'cltbld', 19:35:39 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1474242289.533299-77868688', 19:35:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:35:39 INFO - '_': '/tools/buildbot/bin/python'} 19:35:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:35:39 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:35:39 INFO - Cleaning up... 19:35:39 INFO - Return code: 0 19:35:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:35:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:35:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:35:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:35:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:35:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:35:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:35:39 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 0x7fe15d53af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29b38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a80ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a7df60>, '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 0x2a7ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a7f1d0>, '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': '97facb8ba2ac28944cc45e6b000001d4-1474242289.533299-77868688', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:35:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:35:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 19:35:39 INFO - Using env: {'DISPLAY': ':0', 19:35:39 INFO - 'HOME': '/home/cltbld', 19:35:39 INFO - 'LANG': 'en_US.UTF-8', 19:35:39 INFO - 'LANGUAGE': 'en_US:en', 19:35:39 INFO - 'LOGNAME': 'cltbld', 19:35:39 INFO - 'MAIL': '/var/mail/cltbld', 19:35:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:35:39 INFO - 'MOZ_NO_REMOTE': '1', 19:35:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:35:39 INFO - 'NO_EM_RESTART': '1', 19:35:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:35:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:35:39 INFO - 'PWD': '/builds/slave/test', 19:35:39 INFO - 'SHELL': '/bin/bash', 19:35:39 INFO - 'SHLVL': '1', 19:35:39 INFO - 'TERM': 'linux', 19:35:39 INFO - 'TMOUT': '86400', 19:35:39 INFO - 'USER': 'cltbld', 19:35:39 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1474242289.533299-77868688', 19:35:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:35:39 INFO - '_': '/tools/buildbot/bin/python'} 19:35:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:35:39 INFO - Downloading/unpacking blobuploader==1.2.4 19:35:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:35:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:35:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:35:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:35:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:35:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:35:41 INFO - Downloading blobuploader-1.2.4.tar.gz 19:35:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:35:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:35:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:35:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:35:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:35:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:35:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:35:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:35:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:35:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:35:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:35:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:35:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:35:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:35:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:35:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:35:42 INFO - Downloading docopt-0.6.1.tar.gz 19:35:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:35:42 INFO - Installing collected packages: blobuploader, requests, docopt 19:35:42 INFO - Running setup.py install for blobuploader 19:35:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:35:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:35:42 INFO - Running setup.py install for requests 19:35:42 INFO - Running setup.py install for docopt 19:35:43 INFO - Successfully installed blobuploader requests docopt 19:35:43 INFO - Cleaning up... 19:35:43 INFO - Return code: 0 19:35:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:35:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:35:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:35:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:35:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:35:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:35:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:35:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe15d53af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29b38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a80ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a7df60>, '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 0x2a7ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a7f1d0>, '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': '97facb8ba2ac28944cc45e6b000001d4-1474242289.533299-77868688', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:35:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:35:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:35:43 INFO - Using env: {'DISPLAY': ':0', 19:35:43 INFO - 'HOME': '/home/cltbld', 19:35:43 INFO - 'LANG': 'en_US.UTF-8', 19:35:43 INFO - 'LANGUAGE': 'en_US:en', 19:35:43 INFO - 'LOGNAME': 'cltbld', 19:35:43 INFO - 'MAIL': '/var/mail/cltbld', 19:35:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:35:43 INFO - 'MOZ_NO_REMOTE': '1', 19:35:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:35:43 INFO - 'NO_EM_RESTART': '1', 19:35:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:35:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:35:43 INFO - 'PWD': '/builds/slave/test', 19:35:43 INFO - 'SHELL': '/bin/bash', 19:35:43 INFO - 'SHLVL': '1', 19:35:43 INFO - 'TERM': 'linux', 19:35:43 INFO - 'TMOUT': '86400', 19:35:43 INFO - 'USER': 'cltbld', 19:35:43 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1474242289.533299-77868688', 19:35:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:35:43 INFO - '_': '/tools/buildbot/bin/python'} 19:35:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:35:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:35:43 INFO - Running setup.py (path:/tmp/pip-okDWAr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:35:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:35:43 INFO - Running setup.py (path:/tmp/pip-ENWmLb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:35:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:35:43 INFO - Running setup.py (path:/tmp/pip-pu2jWi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:35:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:35:43 INFO - Running setup.py (path:/tmp/pip-SS9arn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:35:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:35:43 INFO - Running setup.py (path:/tmp/pip-n4l4QI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:35:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:35:43 INFO - Running setup.py (path:/tmp/pip-2CikgV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:35:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:35:43 INFO - Running setup.py (path:/tmp/pip-hfuaap-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:35:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:35:43 INFO - Running setup.py (path:/tmp/pip-G5NDRk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:35:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:35:43 INFO - Running setup.py (path:/tmp/pip-LbAcv5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:35:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:35:43 INFO - Running setup.py (path:/tmp/pip-nPe3uP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:35:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:35:44 INFO - Running setup.py (path:/tmp/pip-uah99l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:35:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:35:44 INFO - Running setup.py (path:/tmp/pip-DjIGEV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:35:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:35:44 INFO - Running setup.py (path:/tmp/pip-5Xl5kn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:35:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:35:44 INFO - Running setup.py (path:/tmp/pip-OmRApS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:35:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:35:44 INFO - Running setup.py (path:/tmp/pip-fiV0kD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:35:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:35:44 INFO - Running setup.py (path:/tmp/pip-znmlqV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:35:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:35:44 INFO - Running setup.py (path:/tmp/pip-qo6SnA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:35:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:35:44 INFO - Running setup.py install for manifestparser 19:35:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:35:44 INFO - Running setup.py install for mozcrash 19:35:44 INFO - Running setup.py install for mozdebug 19:35:44 INFO - Running setup.py install for mozdevice 19:35:44 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:35:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:35:44 INFO - Running setup.py install for mozfile 19:35:45 INFO - Running setup.py install for mozhttpd 19:35:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:35:45 INFO - Running setup.py install for mozinfo 19:35:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:35:45 INFO - Running setup.py install for mozInstall 19:35:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:35:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:35:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:35:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:35:45 INFO - Running setup.py install for mozleak 19:35:45 INFO - Running setup.py install for mozlog 19:35:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:35:45 INFO - Running setup.py install for moznetwork 19:35:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:35:45 INFO - Running setup.py install for mozprocess 19:35:45 INFO - Running setup.py install for mozprofile 19:35:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:35:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:35:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:35:45 INFO - Running setup.py install for mozrunner 19:35:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:35:46 INFO - Running setup.py install for mozscreenshot 19:35:46 INFO - Running setup.py install for moztest 19:35:46 INFO - Running setup.py install for mozversion 19:35:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:35:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:35:46 INFO - Cleaning up... 19:35:46 INFO - Return code: 0 19:35:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:35:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:35:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:35:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:35:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:35:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:35:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:35:46 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 0x7fe15d53af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29b38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a80ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a7df60>, '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 0x2a7ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a7f1d0>, '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': '97facb8ba2ac28944cc45e6b000001d4-1474242289.533299-77868688', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:35:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:35:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:35:46 INFO - Using env: {'DISPLAY': ':0', 19:35:46 INFO - 'HOME': '/home/cltbld', 19:35:46 INFO - 'LANG': 'en_US.UTF-8', 19:35:46 INFO - 'LANGUAGE': 'en_US:en', 19:35:46 INFO - 'LOGNAME': 'cltbld', 19:35:46 INFO - 'MAIL': '/var/mail/cltbld', 19:35:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:35:46 INFO - 'MOZ_NO_REMOTE': '1', 19:35:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:35:46 INFO - 'NO_EM_RESTART': '1', 19:35:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:35:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:35:46 INFO - 'PWD': '/builds/slave/test', 19:35:46 INFO - 'SHELL': '/bin/bash', 19:35:46 INFO - 'SHLVL': '1', 19:35:46 INFO - 'TERM': 'linux', 19:35:46 INFO - 'TMOUT': '86400', 19:35:46 INFO - 'USER': 'cltbld', 19:35:46 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1474242289.533299-77868688', 19:35:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:35:46 INFO - '_': '/tools/buildbot/bin/python'} 19:35:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:35:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:35:46 INFO - Running setup.py (path:/tmp/pip-DqC5zF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:35:46 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 19:35:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:35:46 INFO - Running setup.py (path:/tmp/pip-Hks_Vl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:35:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:35:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:35:46 INFO - Running setup.py (path:/tmp/pip-2EmIa5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:35:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 19:35:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:35:46 INFO - Running setup.py (path:/tmp/pip-TskrdI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:35:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:35:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:35:46 INFO - Running setup.py (path:/tmp/pip-ImktH8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:35:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 19:35:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:35:46 INFO - Running setup.py (path:/tmp/pip-qK66Ga-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:35:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 19:35:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:35:47 INFO - Running setup.py (path:/tmp/pip-xEYX1g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:35:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 19:35:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:35:47 INFO - Running setup.py (path:/tmp/pip-BQkmfr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:35:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 19:35:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:35:47 INFO - Running setup.py (path:/tmp/pip-ulAVH1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:35:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 19:35:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:35:47 INFO - Running setup.py (path:/tmp/pip-VTt4oO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:35:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:35:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:35:47 INFO - Running setup.py (path:/tmp/pip-B2zMwg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:35:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 19:35:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:35:47 INFO - Running setup.py (path:/tmp/pip-6PYHMo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:35:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 19:35:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:35:47 INFO - Running setup.py (path:/tmp/pip-gEGuk8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:35:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 19:35:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:35:47 INFO - Running setup.py (path:/tmp/pip-3zlMkO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:35:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 19:35:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:35:47 INFO - Running setup.py (path:/tmp/pip-iGFG4e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:35:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 19:35:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:35:47 INFO - Running setup.py (path:/tmp/pip-URVIIv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:35:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 19:35:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:35:47 INFO - Running setup.py (path:/tmp/pip-FLjNOS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:35:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 19:35:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:35:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:35:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 19:35:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:35:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:35:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:35:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:35:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:35:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:35:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:35:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:35:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:35:49 INFO - Downloading blessings-1.6.tar.gz 19:35:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:35:49 INFO - Installing collected packages: blessings 19:35:49 INFO - Running setup.py install for blessings 19:35:49 INFO - Successfully installed blessings 19:35:49 INFO - Cleaning up... 19:35:49 INFO - Return code: 0 19:35:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:35:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:35:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:35:50 INFO - Reading from file tmpfile_stdout 19:35:50 INFO - Current package versions: 19:35:50 INFO - argparse == 1.2.1 19:35:50 INFO - blessings == 1.6 19:35:50 INFO - blobuploader == 1.2.4 19:35:50 INFO - docopt == 0.6.1 19:35:50 INFO - functools32 == 3.2.3-2 19:35:50 INFO - jsonschema == 2.5.1 19:35:50 INFO - manifestparser == 1.1 19:35:50 INFO - mozInstall == 1.12 19:35:50 INFO - mozcrash == 0.17 19:35:50 INFO - mozdebug == 0.1 19:35:50 INFO - mozdevice == 0.48 19:35:50 INFO - mozfile == 1.2 19:35:50 INFO - mozhttpd == 0.7 19:35:50 INFO - mozinfo == 0.9 19:35:50 INFO - mozleak == 0.1 19:35:50 INFO - mozlog == 3.4 19:35:50 INFO - moznetwork == 0.27 19:35:50 INFO - mozprocess == 0.23 19:35:50 INFO - mozprofile == 0.28 19:35:50 INFO - mozrunner == 6.12 19:35:50 INFO - mozscreenshot == 0.1 19:35:50 INFO - mozsystemmonitor == 0.3 19:35:50 INFO - moztest == 0.7 19:35:50 INFO - mozversion == 1.4 19:35:50 INFO - psutil == 3.1.1 19:35:50 INFO - requests == 1.2.3 19:35:50 INFO - wsgiref == 0.1.2 19:35:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:35:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:35:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:35:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:35:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:35:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:35:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:35:50 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 0x7fe15d53af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29b38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a80ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a7df60>, '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 0x2a7ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a7f1d0>, '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': '97facb8ba2ac28944cc45e6b000001d4-1474242289.533299-77868688', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:35:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 19:35:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:35:50 INFO - Using env: {'DISPLAY': ':0', 19:35:50 INFO - 'HOME': '/home/cltbld', 19:35:50 INFO - 'LANG': 'en_US.UTF-8', 19:35:50 INFO - 'LANGUAGE': 'en_US:en', 19:35:50 INFO - 'LOGNAME': 'cltbld', 19:35:50 INFO - 'MAIL': '/var/mail/cltbld', 19:35:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:35:50 INFO - 'MOZ_NO_REMOTE': '1', 19:35:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:35:50 INFO - 'NO_EM_RESTART': '1', 19:35:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:35:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:35:50 INFO - 'PWD': '/builds/slave/test', 19:35:50 INFO - 'SHELL': '/bin/bash', 19:35:50 INFO - 'SHLVL': '1', 19:35:50 INFO - 'TERM': 'linux', 19:35:50 INFO - 'TMOUT': '86400', 19:35:50 INFO - 'USER': 'cltbld', 19:35:50 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1474242289.533299-77868688', 19:35:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:35:50 INFO - '_': '/tools/buildbot/bin/python'} 19:35:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:35:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:35:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 19:35:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 19:35:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:35:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 19:35:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 19:35:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 19:35:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 19:35:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 19:35:50 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:35:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:35:50 INFO - Cleaning up... 19:35:50 INFO - Return code: 0 19:35:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:35:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:35:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:35:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:35:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:35:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:35:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:35:50 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 0x7fe15d53af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29b38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a80ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a7df60>, '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 0x2a7ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a7f1d0>, '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': '97facb8ba2ac28944cc45e6b000001d4-1474242289.533299-77868688', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:35:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 19:35:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 19:35:50 INFO - Using env: {'DISPLAY': ':0', 19:35:50 INFO - 'HOME': '/home/cltbld', 19:35:50 INFO - 'LANG': 'en_US.UTF-8', 19:35:50 INFO - 'LANGUAGE': 'en_US:en', 19:35:50 INFO - 'LOGNAME': 'cltbld', 19:35:50 INFO - 'MAIL': '/var/mail/cltbld', 19:35:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:35:50 INFO - 'MOZ_NO_REMOTE': '1', 19:35:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:35:50 INFO - 'NO_EM_RESTART': '1', 19:35:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:35:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:35:50 INFO - 'PWD': '/builds/slave/test', 19:35:50 INFO - 'SHELL': '/bin/bash', 19:35:50 INFO - 'SHLVL': '1', 19:35:50 INFO - 'TERM': 'linux', 19:35:50 INFO - 'TMOUT': '86400', 19:35:50 INFO - 'USER': 'cltbld', 19:35:50 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1474242289.533299-77868688', 19:35:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:35:50 INFO - '_': '/tools/buildbot/bin/python'} 19:35:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:35:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:35:50 INFO - Cleaning up... 19:35:50 INFO - Return code: 0 19:35:50 INFO - Running post-action listener: _resource_record_post_action 19:35:50 INFO - Running post-action listener: _start_resource_monitoring 19:35:50 INFO - Starting resource monitoring. 19:35:50 INFO - [mozharness: 2016-09-19 02:35:50.658897Z] Finished create-virtualenv step (success) 19:35:50 INFO - [mozharness: 2016-09-19 02:35:50.659355Z] Running install step. 19:35:50 INFO - Running pre-action listener: _resource_record_pre_action 19:35:50 INFO - Running main action method: install 19:35:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:35:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:35:50 INFO - Reading from file tmpfile_stdout 19:35:50 INFO - Detecting whether we're running mozinstall >=1.0... 19:35:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:35:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:35:50 INFO - Reading from file tmpfile_stdout 19:35:50 INFO - Output received: 19:35:50 INFO - Usage: mozinstall [options] installer 19:35:50 INFO - Options: 19:35:50 INFO - -h, --help show this help message and exit 19:35:50 INFO - -d DEST, --destination=DEST 19:35:50 INFO - Directory to install application into. [default: 19:35:50 INFO - "/builds/slave/test"] 19:35:50 INFO - --app=APP Application being installed. [default: firefox] 19:35:50 INFO - mkdir: /builds/slave/test/build/application 19:35:50 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'] 19:35:50 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 19:36:07 INFO - Reading from file tmpfile_stdout 19:36:07 INFO - Output received: 19:36:07 INFO - /builds/slave/test/build/application/firefox/firefox 19:36:07 INFO - Running post-action listener: _resource_record_post_action 19:36:07 INFO - [mozharness: 2016-09-19 02:36:07.017279Z] Finished install step (success) 19:36:07 INFO - [mozharness: 2016-09-19 02:36:07.017608Z] Running run-tests step. 19:36:07 INFO - Running pre-action listener: _resource_record_pre_action 19:36:07 INFO - Running main action method: run_tests 19:36:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:36:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:36:07 INFO - Python 2.7.3 19:36:07 INFO - Return code: 0 19:36:07 INFO - grabbing minidump binary from tooltool 19:36:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:36:07 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a7df60>, '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 0x2a7ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a7f1d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:36:07 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 19:36:07 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 19:36:07 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 19:36:07 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:36:07 INFO - Return code: 0 19:36:07 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:36:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:36:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:36:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:36:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:36:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JrzmoW4DRC2Nk-cWFrRkzA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-068', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 19:36:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/JrzmoW4DRC2Nk-cWFrRkzA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-068 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 19:36:07 INFO - Using env: {'DISPLAY': ':0', 19:36:07 INFO - 'HOME': '/home/cltbld', 19:36:07 INFO - 'LANG': 'en_US.UTF-8', 19:36:07 INFO - 'LANGUAGE': 'en_US:en', 19:36:07 INFO - 'LOGNAME': 'cltbld', 19:36:07 INFO - 'MAIL': '/var/mail/cltbld', 19:36:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:36:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:36:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:36:07 INFO - 'MOZ_NO_REMOTE': '1', 19:36:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:36:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:36:07 INFO - 'NO_EM_RESTART': '1', 19:36:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:36:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:36:07 INFO - 'PWD': '/builds/slave/test', 19:36:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:36:07 INFO - 'SHELL': '/bin/bash', 19:36:07 INFO - 'SHLVL': '1', 19:36:07 INFO - 'TERM': 'linux', 19:36:07 INFO - 'TMOUT': '86400', 19:36:07 INFO - 'USER': 'cltbld', 19:36:07 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1474242289.533299-77868688', 19:36:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:36:07 INFO - '_': '/tools/buildbot/bin/python'} 19:36:07 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JrzmoW4DRC2Nk-cWFrRkzA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-068', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 19:36:07 INFO - mozversion application_buildid: 20160918184618 19:36:07 INFO - mozversion application_changeset: 1164e024099e710c1cfce2377dea4163cdcaa8bc 19:36:07 INFO - mozversion application_display_name: Nightly 19:36:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:36:07 INFO - mozversion application_name: Firefox 19:36:07 INFO - mozversion application_remotingname: firefox 19:36:07 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 19:36:07 INFO - mozversion application_vendor: Mozilla 19:36:07 INFO - mozversion application_version: 51.0a1 19:36:07 INFO - mozversion platform_buildid: 20160918184618 19:36:07 INFO - mozversion platform_changeset: 1164e024099e710c1cfce2377dea4163cdcaa8bc 19:36:07 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 19:36:07 INFO - mozversion platform_version: 51.0a1 19:36:07 INFO - using testdate: 1474252567 19:36:07 INFO - actual date: 1474252567 19:36:07 INFO - starting webserver on 'localhost:49718' 19:36:07 INFO - SUITE-START | Running 2 tests 19:36:07 INFO - TEST-START | dromaeo_css 19:36:07 INFO - operating with platform_type : linux_ 19:36:07 INFO - Initialising browser for dromaeo_css test... 19:36:07 INFO - TEST-INFO | started process 21348 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpszvURp/profile http://localhost:49718/getInfo.html) 19:36:11 INFO - PROCESS | 21348 | __metrics Screen width/height:1600/1200 19:36:11 INFO - PROCESS | 21348 | colorDepth:24 19:36:11 INFO - PROCESS | 21348 | Browser inner width/height: 1024/697 19:36:11 INFO - PROCESS | 21348 | __metrics 19:36:12 INFO - PROCESS | 21348 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:36:12 INFO - PROCESS | 21348 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:36:13 INFO - PROCESS | 21348 | console.error: PushService: 19:36:13 INFO - PROCESS | 21348 | stateChangeProcessEnqueue: Error transitioning state 19:36:13 INFO - PROCESS | 21348 | UnknownError 19:36:13 INFO - TEST-INFO | 21348: exit 0 19:36:13 INFO - Browser initialized. 19:36:13 INFO - Running cycle 1/1 for dromaeo_css test... 19:36:13 INFO - TEST-INFO | started process 21430 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpszvURp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:36:26 INFO - PROCESS | 21430 | RSS: Main: 174211072 19:36:26 INFO - PROCESS | 21430 | 19:36:37 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.05007 seconds. 19:36:48 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 19:36:51 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 19:37:10 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 19:37:16 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.0334 seconds. 19:37:17 INFO - PROCESS | 21430 | 1474252637717 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 19:37:18 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.01676 seconds. 19:37:39 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 19:37:51 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.06672 seconds. 19:37:55 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.01675 seconds. 19:37:57 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.01675 seconds. 19:37:59 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.16675 seconds. 19:38:02 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 19:38:10 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.0334 seconds. 19:38:19 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.68345 seconds. 19:38:30 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 19:38:32 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00009 seconds. 19:38:38 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 19:38:49 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 19:38:56 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.05004 seconds. 19:39:14 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 19:39:20 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.03342 seconds. 19:39:22 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 19:39:30 INFO - PROCESS | 21430 | Cycle 1(1): loaded http://localhost:49718/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49718/tests/dromaeo/cssquery-ext.html) 19:39:31 INFO - PROCESS | 21430 | RSS: Main: 287539200 19:39:31 INFO - PROCESS | 21430 | 19:39:36 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.06672 seconds. 19:39:42 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.0334 seconds. 19:39:48 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 19:39:52 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 19:40:00 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 19:40:14 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:40:26 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.01675 seconds. 19:40:32 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 19:40:42 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 19:40:45 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00008 seconds. 19:41:10 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 19:41:20 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 19:41:23 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 19:41:34 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 19:41:35 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 19:41:46 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00009 seconds. 19:41:57 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 19:41:59 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.01677 seconds. 19:42:01 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 19:42:25 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 19:42:33 INFO - PROCESS | 21430 | Cycle 1(1): loaded http://localhost:49718/tests/dromaeo/cssquery-ext.html (next: http://localhost:49718/tests/dromaeo/cssquery-jquery.html) 19:42:34 INFO - PROCESS | 21430 | RSS: Main: 276869120 19:42:34 INFO - PROCESS | 21430 | 19:42:37 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.01675 seconds. 19:42:48 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 19:43:11 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 19:43:21 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.0001 seconds. 19:43:23 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 19:43:31 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 19:43:37 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 19:43:41 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 19:43:45 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 19:43:49 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 19:43:59 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 19:44:01 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 19:44:10 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.01677 seconds. 19:44:19 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.01677 seconds. 19:44:34 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 19:44:56 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 19:44:58 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 19:45:00 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 19:45:02 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:45:04 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 19:45:12 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 19:45:14 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 19:45:16 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 19:45:18 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 19:45:24 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.0001 seconds. 19:45:26 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 19:45:32 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 19:45:34 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 19:45:37 INFO - PROCESS | 21430 | Cycle 1(1): loaded http://localhost:49718/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49718/tests/dromaeo/cssquery-mootools.html) 19:45:37 INFO - PROCESS | 21430 | RSS: Main: 219041792 19:45:37 INFO - PROCESS | 21430 | 19:45:41 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.0334 seconds. 19:45:45 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 19:46:06 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 19:46:08 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 19:46:12 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 19:46:16 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00012 seconds. 19:46:22 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 19:46:34 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 19:46:39 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 19:46:42 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 19:46:44 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 19:46:46 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 19:46:54 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 19:46:56 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 19:47:07 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 19:47:23 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 19:47:25 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:47:33 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 19:47:35 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 19:47:38 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 19:47:45 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 19:47:55 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:47:59 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 19:48:02 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 19:48:05 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 19:48:15 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 19:48:19 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 19:48:23 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 19:48:25 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 19:48:27 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 19:48:39 INFO - PROCESS | 21430 | Cycle 1(1): loaded http://localhost:49718/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49718/tests/dromaeo/cssquery-prototype.html) 19:48:40 INFO - PROCESS | 21430 | RSS: Main: 219492352 19:48:40 INFO - PROCESS | 21430 | 19:48:46 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.1001 seconds. 19:48:49 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 19:48:51 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 19:48:57 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 19:48:59 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 19:49:13 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 19:49:22 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 19:49:24 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 19:49:25 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:49:29 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 19:49:41 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 19:49:42 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00009 seconds. 19:49:51 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 19:49:54 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 19:50:20 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 19:50:30 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:50:38 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 19:50:47 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00012 seconds. 19:50:59 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00011 seconds. 19:51:28 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 19:51:43 INFO - PROCESS | 21430 | Cycle 1(1): loaded http://localhost:49718/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49718/tests/dromaeo/cssquery-yui.html) 19:51:44 INFO - PROCESS | 21430 | RSS: Main: 222756864 19:51:44 INFO - PROCESS | 21430 | 19:51:47 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.0334 seconds. 19:52:00 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 19:52:04 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 19:52:08 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 19:52:10 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00008 seconds. 19:52:14 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 19:52:17 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 19:52:19 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:52:38 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.01675 seconds. 19:52:47 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 19:52:55 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 19:53:05 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 19:53:07 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00011 seconds. 19:53:11 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 19:53:29 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 19:53:38 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00009 seconds. 19:53:50 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 19:53:54 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 19:53:56 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 19:53:58 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 19:54:16 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 19:54:43 INFO - PROCESS | 21430 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:54:47 INFO - PROCESS | 21430 | Cycle 1(1): loaded http://localhost:49718/tests/dromaeo/cssquery-yui.html (next: http://localhost:49718/tests/dromaeo/cssquery-dojo.html) 19:54:47 INFO - PROCESS | 21430 | RSS: Main: 309858304 19:54:47 INFO - PROCESS | 21430 | 19:54:47 INFO - PROCESS | 21430 | __start_tp_report 19:54:47 INFO - PROCESS | 21430 | _x_x_mozilla_page_load 19:54:47 INFO - PROCESS | 21430 | _x_x_mozilla_page_load_details 19:54:47 INFO - PROCESS | 21430 | |i|pagename|runs| 19:54:47 INFO - PROCESS | 21430 | |0;dojo.html;2560.7055188653303;2587.9353016174605;2608.126277696972;2609.921702348929;2633.7497937695925;4602.07958408318;4693.601043911263;4714.504976977415;4755.096531658983;4757.024809913973;4703.129920964615;4716;4722;4722.150012997659;4776.307435421862;4286.849960251392;4316.179925814085;4323.891902702426;4337.371081193225;4411.18393097278;4379.277419225824;4428.911421771562;4433.179861725591;4459.487158976711;4512.142692888341;4225.15496900618;4291.6566674665955;4392.143532011259;4425.601695847373;4453.4433195850515;4318.4170137031415;4328.069465064995;4338.262495375762;4338.826446942118;4351.608355248011;4276.5295817460365;4306.569343065668;4310.374995625648;4314.374415709704;4370.934435983463;4675.7895894684825;4705.5764981151515;4717.221658426355;4751.762411879392;4774.021325628238;5035.748212589356;5037.596992240612;5037.848864534069;5044.243363495469;5099.08216521026;4899.730514821684;4980.726060066695;5026.773795179226;5061.873453163664;5131.050755610187;4705.129551033036;4728.456227533878;4731.408573928259;4775.856724298312;4793.520647935178;5130.076586214517;5179.326687530673;5183.922241166386;5190.325257716549;5249.632525723163;4759.929015971379;4815.95184048155;4880.877978050577;4893.975530122327;4952.08386448501;4949.678270912379;4989.825356112518;5000.824971126066;5015.14742493777;5073.974630126826;4981.427135879348;5006.374203224597;5012.3233363495465;5014.949850501448;5075.771590278372;4806.423229212517;5001.799928002839;5012.899742005139;5013.899722005613;5075.746212689424;4229.873103806891;4287.206866729665;4355.5208927018;4405.933910991338;4407.0524837160165;3814.9427758583643;3817.0457385653585;3819.1788765415013;3864.652181303696;3867.535895692479;4435.512093669694;4437.511873693891;4437.644988400984;4482.350059241392;4496.4154659894;4900.509949005071;4952.4304704959395;5079.060373830853;5111.693298402108;5136.794528218829;4414.359917811964;4416.668749843775;4451.465824101128;4454.042380888124;4469.888252793706;4627.3521706961055;4628.120657075145;4641;4644.7213167210075;4697;4604.746738929391;4642.025174713348;4788.952110478851;4819.662623616383;4823.1800593898415;5101.234814777813;5149.330587023663;5151.871203219799;5174.870628234325;5215.29593504872;4797.472278049481;4829.058333624909;4838.8790280241865;4840.1529732296;4898.608111351155;4916.139675556692;4993.500649934977;5074.340335756328;5080.441151473409;5142.588592912483;5027.396712394536;5044.1172794761505;5071.54356107952;5076.593872490118;5151.459096794782;4924.679895806761;4940.184869496637;4941.50584941503;4942.308076869169;5004.324416203766;4805.663603547718;4924.729139897197;4931.0630980113665;4933.457319694902;4993.17612593918;4788.712677239377;4843.757812109451;4879.072976134523;4882.048000639841;4928.334674818853;4947.406311242674;4951.356323677899;4954.380702412199;4964.528369804862;4977.377827771528;4735.810567577259;4775.2359622460235;4784.258439941815;4853.708777473363;4859.902801943871 19:54:47 INFO - PROCESS | 21430 | |1;ext.html;565.0761005755588;565.3554947360009;573.2833957553058;574.7844558290641;576.9538436925045;28398;37671.81164094179;38685.99999999996;38949.8052509737;43527.12945741083;39423.60576394235;39791.204175916464;39841.40237896434;39862.202755944934;39932.20135597294;7593;7725.111612164589;7728.033995750531;7734.729284475045;7734.806629834271;7585.582792946386;7655.387568994495;7666.271704188093;7671.53970761756;7674.501157424777;7695.307422331989;7735.994320738296;7745.380369570449;7748.070231572219;7750.728724494644;7654.617269136549;7696.230376962315;7698.230176982313;7712.652930618079;7717.421193410517;7605;7666.3866890648615;7671.53970761756;7671.6164191790185;7673.846523069507;36924.630753692385;37297.813510932545;37348.81325593382;37379.81310093432;37464;2911.6651585067566;2919.194322191813;2969.5545668149734;2980.3294258791598;3047.4514587374265;28245.435091298263;28398.716012839814;28531.714682853115;28920.42159156826;29455;3479.5998460176897;3513.350030244387;3519.665631765004;3560.0743806610208;3578.6063533011347;253466;253731;253742;254089;255524;51565.742171288905;51863.481365185864;52029.73985130126;52033.7398313006;52058.21912671313;32404.83797580997;33350.332993339995;33361.83319083389;35219.82390088033;35241.82379088088;13437.932810336082;13581;13607.455701772016;13610.863891361158;13617;10096.798064038678;10183.898161018295;10185.33795303303;10185.74535636615;10189.133923616428;17423;17739.645207096044;18015.207642815385;18028.098595070194;18053.458396248134;6550.6069635822005;6553.377429144319;6561.901571476432;6574.342565743483;6830.658467076626;743.4832791210057;846.7205822078699;891.7369376034086;935.2891802230354;943.4905151218265;25878.6118208227;27847.30381740473;28337.71662283392;28443.146705598865;28465.71534284672;744.5383862005591;918.4581097152712;922.6586163119689;930.8650245714333;992.6029588164791;13614.795778063337;14355.282235888373;14483.348249328805;14643.92678036603;14663.34014969329;382018;383466;383684;384180;384338;49190.75404622954;49432.50567494279;49441.75279123581;49452.258216126786;49740;998.7066748560632;1045.5974449836715;1147.1740346950016;1167.3171194851107;1200.6278053803348;25732.87133564395;29151.75268451614;29181.416371672873;29244.7075529242;29479.557806633347;41454.378184326655;41896.581034189265;41984.38171801039;41993.370099449145;41996;291872;293400;293446;293589.4936284879;294009;24731.62902556499;25527.489450211262;25645.615315769905;25645.743542564334;25690.100846470283;40921.59078409178;41022.38466423066;41143.588564113976;41277.38083928804;41849.58150418457;35186;35262;35424.46863297001;35523;36139.27721445504;34845.303093938484;34897.65102348944;35212.823935881184;35265.47101793424;35444;34911;34995.825020874734;35256.64743352534;35389.74510225346;36294.455583166804;30121.69878301189;32163.839180804884;32349.51475727819;32433;32850.01449956505;24327.27018189457;24737.134200302902;24742.876285619175;24893.253202403957;25177.622335665346 19:54:47 INFO - PROCESS | 21430 | |2;jquery.html;339.86395504255756;364.8850612057202;371.27415901911763;374.53183520599254;388.5861557441325;12345.50617975281;13172.736545269088;14471;16287.51137465878;17088.060156691376;15819.683606327866;16291.429799957004;16358.427455039076;16958.915205423986;17276.568085797862;4302.354646802981;4525.434320709911;4577.038821847416;4584.954150458503;4588.587027167554;3413.2832105257926;4631.402166252654;4698.506656801038;4699.859004229861;4704.059188162364;3572.7320450966154;4993.825216117437;5219.921701174467;5249.265102885599;5307.2569840222395;5199.317022137568;5274.8417547473255;5300.204969254604;5314.362276526822;5314.441983591744;5173.456787037345;5189.1697328427645;5205.583553315726;5218.817341393071;5221.216817477371;12067.275963442135;12132;12143.453544590515;12193.573224937085;12221.388930553438;1810.1944634637591;1828.396629112403;1857.7584913961098;1883.6138591588692;2383.1778036577352;8535.914640853574;8728.472517309508;8775.341849361259;8878.955605221998;9449.622015119387;1619.3765400320844;1723.2676112652198;1750.1161913233864;1774.5829730013436;1774.9112544372858;46537.90579423934;59459.70270148665;62565.68717156431;67747.66126169387;73280.26719732788;9695.709128726008;9926.823416506648;10033.899661003443;10096.495175241209;10288.897111028793;14110.929445352707;14114.082584631966;14122.576322710334;14124.576262712135;14131;7072.646367681596;7104.822379440556;7252.782416527512;7336.853262934818;7385.556866588022;6532.41208291256;6591.077249185118;6652.268250492442;6660.300668429745;6834.419074378634;4228.957710422918;4278.336857787048;4333.479982402132;4374.956250437455;4391.802368893407;1688.5525335786026;1749;1751.7810273715786;1830.3044842959857;1873.9156737946826;762.6529928882345;800.1598321762126;833.9041010283895;929.4748467116058;934.2199263614957;16039.03765774034;16463.588410289598;16676;16919.407820726214;17503.29986800514;813.6785969542116;839.630562552475;852.1265702654705;857.1342943626981;879.5426378283257;8811;9398.859017114748;9585.952070239604;9602.90397096034;9800.26498012652;372764;374198;381902;383649;385222;55159;80473.19526804774;80945;81503.59248203722;82040.58979705063;841.5581819544788;842.6081871929705;883.8585826267766;888.1429420609014;892.3040028777564;11145.387003714874;14028.649283767987;15114.470993515175;15264.23678816077;15509.689806204038;36106.45840312345;36217.81891090528;36316.82099074064;36613;36928.26143477169;43940.560594393646;46777;47126.528734712214;47154;49150.262746059554;6525;6750.32496750321;6801.727930882709;7276.781696549112;7641.42689298316;11172.88827111751;11418.486168122621;11553.537858485566;11884.405779711153;12138.271703697807;9994.300398972002;10109;10183.287169898036;10386.948065259625;10562.419066951392;9822.214222862296;10114.696559103239;10115.79768404613;10141.188704903738;10248.282620216512;10059.29584929048;10262;10534.578616855546;10552.208584356358;10667.786644266916;6920.826979325557;6963.9303606963285;7065.258147894397;7153.535020223669;7311.8537629248185;8238.340932725248;10365.326253793479;10367.896321036995;10424.791504170025;10989.890101098887 19:54:47 INFO - PROCESS | 21430 | |3;mootools.html;1319.4656164253477;1363.9045266831322;1380.751464736347;1624.9350025998947;1645.8518733314006;6739.1239138912;6946.131733533309;7324.340809327159;7954.0852801927795;7964.60176991151;7244.384227340683;7464.626768661572;7470.0662417197855;7712.7686169414865;7722.768316950486;2159.1795117855195;2182.9126834926583;2187.267265466071;2188.4200686817926;2193.5393567350875;1795.973060404095;1848.5378655336167;1983.4446355020546;2005.8194762471376;2077.3767869639123;2023.5447024419534;2065.4733043073993;2069.5653912678354;2075.3773867839664;2076.190285788544;2102.5584627228377;2102.7581828089815;2107.6943843142813;2116.1958455786703;2153.8061574458375;1973.842092632586;1980.1881228696166;1980.7326010988477;1983.1274239334646;1983.4049785064567;7725.49784264021;7882.605869706549;7892.960535197345;7896.328812050982;7896.486728362638;4372.060007098457;4508.030773383705;4510.2783554631105;4515.232410490192;4516.503184649686;2600.9349766255996;2648.1128821844704;2739.1371717908796;2774.6254255675426;2790.1629511146575;1030.5156576409076;1037.4294138224022;1052.3317693264833;1061.495789499989;1079.0773888325448;10614.25700200994;10622.25644204906;10634.20243481742;10648.148148148208;10665.84001239982;4061.3704875744306;4184.748915065105;4296.591823776736;4297.462817147856;4303.182395344875;8780.912190878138;8836.55817209137;8844.778880528038;9237.769055773526;9258.490783006864;4470.798814053375;4500.617447516932;4509.210888094571;4513.819447222074;4517.254652982221;3926.332523471017;3981.5023122109737;4101.938470922939;4105.97947010263;4115.42384066231;3537.699295559855;3553.964460355415;3628.5101511296166;3641.5083963665097;3675.8162091895297;985.4678473624163;989.085096285947;1147.1740346950182;1155.4511606986614;1203.0796440722854;667.9413130188608;669.4276393683427;688.6763221286087;785.7328508307089;787.7006737439851;4449.710768800018;4541.75020373876;4670.743109129031;4765.833195838199;5031.924521132186;438.0800319329446;439.7625282347561;440.4472387154101;443.79807187729506;583.5594126434514;3001.5947847040807;3084.1981084918084;3094.628644562667;3159.225989632555;3252.349530093991;9000.954995225113;9091.499967501852;9432.575534100983;9437.622495100257;9521.476318802532;9671.371360861522;9673.081057299545;9685.079917407835;9685.661001865043;9693.369930954465;7006.614636195059;7178.066851309401;7183.281671832775;7196.280371962866;7208.747693830691;4746.905061898812;4796.424429068534;4841.540053694894;4850.345203397496;4887.022595480989;8320.583970801315;8444.493330400195;8489.320854331761;8586.871196932176;8683.652653893774;8482.321414286966;8639.438436501607;8795.648174072965;8802.471851688919;9047.954760226157;6623.205215374186;6738.393544581011;6745.752035873379;6757.898631520433;6794.524383293122;7222.349988501061;7258.45561582894;7259.05632267823;7276.272372762682;7290.635468226674;6512.153420055362;6696.899546506904;6806.897896531655;6853.0405743195;6911.723531058902;6399.328070552525;6420.454261387835;6424.775132870327;6489.707963141765;6510.73957041713;5862.355140934579;5922.7927022554;5923.318818335774;5932.822015339547;5937.554683398676;4186.353857695805;4279.97860010698;4360.454943132108;4399.032212913154;4453.665975051819;4304.784760762012;4376.4967028790825;4429.512753597037;4482;4485.282354823213 19:54:47 INFO - PROCESS | 21430 | |4;prototype.html;286.69896608561004;297.4126100950623;299.6763495424941;302.17355532618296;302.59301239832416;4892.412910450751;4895.926561101582;4904.871879467724;4905;4910.2143657014885;4776.259679749644;4823.445303790065;4857.951420485794;4858.206531244531;4868.221084626461;3773.6603705666485;3777.357849165635;3777.6788972937334;3782.621737826209;3810.0093975566415;3525.5240542526685;3832.770033797981;3839.0978120141745;3856.421536769479;3858.305505009097;3697.870574529892;3735.6451137141926;3736.8878933632036;3743.794091324976;3750.343689854278;3711;3717.583570679053;3721.6650501455;3722.9255414891823;3726.850925962958;3072.646645635758;3136.780425370225;3149.3543823516125;3247.1719711473424;3284.8193349365774;4695.6852081417255;4710.670253082286;4715.491042866284;4727.479977202505;4735.171345014609;4253.617174454314;4258.657738243985;4259.531451540328;4259.8083086261195;4260.957390426118;4768.189407800647;4815.277708343741;4847.175980083394;5049.924251136236;5066.771995260222;3547.946780798264;3572.214112895159;3572.7856328620105;3580.480830279595;3580.964190358115;5242.344706911636;5271.499207575274;5276.419593844674;5300.761465734051;5318.521333080042;3444.7244220462303;3639.5996440391536;3648.1244501320025;3654.470101835219;3655.592596850232;3691.9815400922826;3692.486080706897;3696.4309804686022;3707.3882809336155;3717.8512859485318;4222.556631553704;4230.2562232424525;4243.130158317537;4244.596763307481;4259.7231179973205;4912.077313998166;5038.64729468941;5053.1157047516535;5061.190209566452;5093.745312734349;1260.5084017233287;1267.33464930912;1282.9800308754564;1292.4442489729317;1296.2157894473896;779.2597032818845;812.1188510466093;824.9175082491703;826.7478419082117;833.633201391398;4017.4733601231346;4029.0531725044593;4037.4347591337237;4046.4132700758814;4062.390641403813;4597.149527337453;4617.792199351037;4642.305558471134;4644.19026488976;4650.953535454563;4001.279769641434;4009.6591789698205;4012.9598704012587;4027.7986100695434;4038.232735780251;2312.4450131968206;2427.1626288930293;2436.0621160853198;2448.8653124078346;2456.385903524119;4373.387725718402;4668.97665511677;4758.452777930513;4813.205821039559;4915.238138088602;5335.146376579729;5343.278657381282;5349.277847490617;5365.490278423544;5395.140575494994;4940.357753492023;4948.579370753526;4956.578690811321;4962.230854217602;4976.129177393942;4977.800887964441;5145.3568303962;5165.457626949266;5184.066867963803;5235.188545775411;5126;5131.79472821083;5139.92290115656;5146.974265128651;5163.148080566814;4788.928166077581;4907.141250281115;4935.358403407534;4937.407511098691;4937.790436216488;3648.7628304160144;3655.83013435698;3691.187938653492;3698.0200246935124;3702.1299994500732;5239.790408383775;5249.448807875118;5250.474952504872;5256.737163141904;5257.185136303879;5063.822766203165;5070.706969722844;5076.289319495348;5081.212412076134;5095.77069031887;4837.4920633332995;5041.2438134280155;5110.9233361500355;5113.3097031900215;5118.49004543668;4999.900001999867;5013.473585273494;5015.874603134805;5023.824166154167;5039.899202016012;4976.253561965734;5142.76857541419;5157.226416037624;5183.896322073613;5195.29863468442;4973.726445045557;4976.975115124401;5023.6985780853265;5038.2190760432195;5038.622103342337 19:54:47 INFO - PROCESS | 21430 | |5;yui.html;587.5475883569654;587.8647910980475;613.3682307223559;646.1245013007374;657.441175001249;12130.636080917575;13120.868791312085;13530;13553.93223033886;13648.11287266327;12876.613701588967;13215.933920330384;13217.933910330436;13227.537036203736;13275.203487790739;605.2827399531569;752.4131177681418;773.0298475413348;773.0414286285022;777.0752804163056;603.9486643635296;753.1075675324757;764.9961750191269;779.7621725373758;785.8114052627357;813.662330132994;816.2735165702529;816.481534225768;816.5304949653943;816.824382757707;631.9684015799191;812.2811311988874;812.2851890336525;812.3947658994028;812.8780682897583;477.3198192575566;578.7366748129633;582.1093726598311;592;599.9880002399927;12809.679758006125;12834.871651283462;12836.679083022906;12848.614541563767;12856.935715321457;11824.822627660593;12013.918747312784;12563.811542826865;12663.366831658472;13196.076274660778;9477.763055923588;9518.239710483951;9782.51087445625;9850.9507452463;12018.098642601839;3999.780615373875;4041.5352234493116;4114.5679703630985;4122.6083522065355;4129.401236820644;63840.04239936451;65318.67340663314;65374.673126634065;65431.345686543;66708;9657.275704322205;10034.397936123856;10168.373390107483;10406.739831504121;10607.84088238677;18881.527961801065;20379.184832606825;20405.183792648426;20442.693359599616;20479.38561843147;6909.550879192836;7035.401990830735;7040.36636702689;7417.295356941082;7490;3587.65917237862;3729.701623870138;3743.644353786386;3774.339490589136;3785.356489396809;2804.509210888127;2875.3386721053857;2894.8407837569134;2899.217211352965;2908.6800451950694;490.1055573578234;496.1936852614501;499.28602098998783;502.93461849960005;511.5421697580719;435.505701029334;469.40854523300203;477.84708893153856;481.9783109760069;494.84412410090704;7343.596102214323;7398.593077380688;7494.1381741099385;7511.3615342696485;7814.648340824677;457.89926216232374;462.1588708550406;477.82320541399906;492.1117383123425;499.21623051809036;3373.1229880231317;3442.5008373786163;3501.2122272488486;3503.737219708532;3719.367707489733;55935;95191;96086;101748.49125754472;101774.4911275439;51827.74086129545;82456.17543824605;88503.55748221217;89675.55162224278;89821.10178898257;75812.62093689496;79752;80024.59987700141;80301.59849200716;80893.59553202314;7089.255628158969;7350.338469537767;7358.632068396666;7436.516626419266;7499.737509187153;46451.76774116108;47872;47942.760286199744;49082.75458622684;49594.50405496043;61756.38243617507;68022.91862488266;68194.65902670653;68903;69196.30803692101;9161.31290153228;9321.860172097287;9502.524873756423;9513.667021654486;9690.224782017563;8914.910850891667;9415.246780257427;9558.85661715061;9615.951920240354;9855.35940163887;9029.23251523628;9115.353723815695;9243.907560924305;9387.295952803704;9512.429254244767;8931.910680893368;9114;9180.862287065565;9510.572024259063;9776.706698799047;8644.740657780276;8658.913410866062;8817.16236957488;8906.109389061043;9512.571934262836;7747.777461283727;7837.6081195941115;7878.56667883249;7925.564093974886;8006.919930800617;7724.227577242231;7795;8010;8054.073781515201;9248.583813728264 19:54:47 INFO - PROCESS | 21430 | __end_tp_report 19:54:47 INFO - PROCESS | 21430 | __start_cc_report 19:54:47 INFO - PROCESS | 21430 | _x_x_mozilla_cycle_collect,988 19:54:47 INFO - PROCESS | 21430 | __end_cc_report 19:54:47 INFO - PROCESS | 21430 | __startTimestamp1474253687532__endTimestamp 19:54:47 INFO - PROCESS | 21430 | 19:54:47 INFO - PROCESS | 21430 | ------- Summary: start ------- 19:54:47 INFO - PROCESS | 21430 | Number of tests: 6 19:54:47 INFO - PROCESS | 21430 | 19:54:47 INFO - PROCESS | 21430 | [#0] dojo.html Cycles:165 Average:4682.29 Median:4793.52 stddev:483.57 (10.1%) stddev-sans-first:455.52 19:54:47 INFO - PROCESS | 21430 | Values: 2560.7 2587.9 2608.1 2609.9 2633.7 4602.1 4693.6 4714.5 4755.1 4757.0 4703.1 4716.0 4722.0 4722.2 4776.3 4286.8 4316.2 4323.9 4337.4 4411.2 4379.3 4428.9 4433.2 4459.5 4512.1 4225.2 4291.7 4392.1 4425.6 4453.4 4318.4 4328.1 4338.3 4338.8 4351.6 4276.5 4306.6 4310.4 4314.4 4370.9 4675.8 4705.6 4717.2 4751.8 4774.0 5035.7 5037.6 5037.8 5044.2 5099.1 4899.7 4980.7 5026.8 5061.9 5131.1 4705.1 4728.5 4731.4 4775.9 4793.5 5130.1 5179.3 5183.9 5190.3 5249.6 4759.9 4816.0 4880.9 4894.0 4952.1 4949.7 4989.8 5000.8 5015.1 5074.0 4981.4 5006.4 5012.3 5014.9 5075.8 4806.4 5001.8 5012.9 5013.9 5075.7 4229.9 4287.2 4355.5 4405.9 4407.1 3814.9 3817.0 3819.2 3864.7 3867.5 4435.5 4437.5 4437.6 4482.4 4496.4 4900.5 4952.4 5079.1 5111.7 5136.8 4414.4 4416.7 4451.5 4454.0 4469.9 4627.4 4628.1 4641.0 4644.7 4697.0 4604.7 4642.0 4789.0 4819.7 4823.2 5101.2 5149.3 5151.9 5174.9 5215.3 4797.5 4829.1 4838.9 4840.2 4898.6 4916.1 4993.5 5074.3 5080.4 5142.6 5027.4 5044.1 5071.5 5076.6 5151.5 4924.7 4940.2 4941.5 4942.3 5004.3 4805.7 4924.7 4931.1 4933.5 4993.2 4788.7 4843.8 4879.1 4882.0 4928.3 4947.4 4951.4 4954.4 4964.5 4977.4 4735.8 4775.2 4784.3 4853.7 4859.9 19:54:47 INFO - PROCESS | 21430 | 19:54:47 INFO - PROCESS | 21430 | [#1] ext.html Cycles:180 Average:45778.56 Median:19541.54 stddev:82894.81 (424.2%) stddev-sans-first:83057.84 19:54:47 INFO - PROCESS | 21430 | Values: 565.1 565.4 573.3 574.8 577.0 28398.0 37671.8 38686.0 38949.8 43527.1 39423.6 39791.2 39841.4 39862.2 39932.2 7593.0 7725.1 7728.0 7734.7 7734.8 7585.6 7655.4 7666.3 7671.5 7674.5 7695.3 7736.0 7745.4 7748.1 7750.7 7654.6 7696.2 7698.2 7712.7 7717.4 7605.0 7666.4 7671.5 7671.6 7673.8 36924.6 37297.8 37348.8 37379.8 37464.0 2911.7 2919.2 2969.6 2980.3 3047.5 28245.4 28398.7 28531.7 28920.4 29455.0 3479.6 3513.4 3519.7 3560.1 3578.6 253466.0 253731.0 253742.0 254089.0 255524.0 51565.7 51863.5 52029.7 52033.7 52058.2 32404.8 33350.3 33361.8 35219.8 35241.8 13437.9 13581.0 13607.5 13610.9 13617.0 10096.8 10183.9 10185.3 10185.7 10189.1 17423.0 17739.6 18015.2 18028.1 18053.5 6550.6 6553.4 6561.9 6574.3 6830.7 743.5 846.7 891.7 935.3 943.5 25878.6 27847.3 28337.7 28443.1 28465.7 744.5 918.5 922.7 930.9 992.6 13614.8 14355.3 14483.3 14643.9 14663.3 382018.0 383466.0 383684.0 384180.0 384338.0 49190.8 49432.5 49441.8 49452.3 49740.0 998.7 1045.6 1147.2 1167.3 1200.6 25732.9 29151.8 29181.4 29244.7 29479.6 41454.4 41896.6 41984.4 41993.4 41996.0 291872.0 293400.0 293446.0 293589.5 294009.0 24731.6 25527.5 25645.6 25645.7 25690.1 40921.6 41022.4 41143.6 41277.4 41849.6 35186.0 35262.0 35424.5 35523.0 36139.3 34845.3 34897.7 35212.8 35265.5 35444.0 34911.0 34995.8 35256.6 35389.7 36294.5 30121.7 32163.8 32349.5 32433.0 32850.0 24327.3 24737.1 24742.9 24893.3 25177.6 19:54:47 INFO - PROCESS | 21430 | 19:54:47 INFO - PROCESS | 21430 | [#2] jquery.html Cycles:180 Average:23192.78 Median:4354.22 stddev:62654.57 (1438.9%) stddev-sans-first:62806.84 19:54:47 INFO - PROCESS | 21430 | Values: 339.9 364.9 371.3 374.5 388.6 12345.5 13172.7 14471.0 16287.5 17088.1 15819.7 16291.4 16358.4 16958.9 17276.6 4302.4 4525.4 4577.0 4585.0 4588.6 3413.3 4631.4 4698.5 4699.9 4704.1 3572.7 4993.8 5219.9 5249.3 5307.3 5199.3 5274.8 5300.2 5314.4 5314.4 5173.5 5189.2 5205.6 5218.8 5221.2 12067.3 12132.0 12143.5 12193.6 12221.4 1810.2 1828.4 1857.8 1883.6 2383.2 8535.9 8728.5 8775.3 8879.0 9449.6 1619.4 1723.3 1750.1 1774.6 1774.9 46537.9 59459.7 62565.7 67747.7 73280.3 9695.7 9926.8 10033.9 10096.5 10288.9 14110.9 14114.1 14122.6 14124.6 14131.0 7072.6 7104.8 7252.8 7336.9 7385.6 6532.4 6591.1 6652.3 6660.3 6834.4 4229.0 4278.3 4333.5 4375.0 4391.8 1688.6 1749.0 1751.8 1830.3 1873.9 762.7 800.2 833.9 929.5 934.2 16039.0 16463.6 16676.0 16919.4 17503.3 813.7 839.6 852.1 857.1 879.5 8811.0 9398.9 9586.0 9602.9 9800.3 372764.0 374198.0 381902.0 383649.0 385222.0 55159.0 80473.2 80945.0 81503.6 82040.6 841.6 842.6 883.9 888.1 892.3 11145.4 14028.6 15114.5 15264.2 15509.7 36106.5 36217.8 36316.8 36613.0 36928.3 43940.6 46777.0 47126.5 47154.0 49150.3 6525.0 6750.3 6801.7 7276.8 7641.4 11172.9 11418.5 11553.5 11884.4 12138.3 9994.3 10109.0 10183.3 10386.9 10562.4 9822.2 10114.7 10115.8 10141.2 10248.3 10059.3 10262.0 10534.6 10552.2 10667.8 6920.8 6963.9 7065.3 7153.5 7311.9 8238.3 10365.3 10367.9 10424.8 10989.9 19:54:47 INFO - PROCESS | 21430 | 19:54:47 INFO - PROCESS | 21430 | [#3] mootools.html Cycles:180 Average:4970.99 Median:4515.87 stddev:2861.79 (63.4%) stddev-sans-first:2856.67 19:54:47 INFO - PROCESS | 21430 | Values: 1319.5 1363.9 1380.8 1624.9 1645.9 6739.1 6946.1 7324.3 7954.1 7964.6 7244.4 7464.6 7470.1 7712.8 7722.8 2159.2 2182.9 2187.3 2188.4 2193.5 1796.0 1848.5 1983.4 2005.8 2077.4 2023.5 2065.5 2069.6 2075.4 2076.2 2102.6 2102.8 2107.7 2116.2 2153.8 1973.8 1980.2 1980.7 1983.1 1983.4 7725.5 7882.6 7893.0 7896.3 7896.5 4372.1 4508.0 4510.3 4515.2 4516.5 2600.9 2648.1 2739.1 2774.6 2790.2 1030.5 1037.4 1052.3 1061.5 1079.1 10614.3 10622.3 10634.2 10648.1 10665.8 4061.4 4184.7 4296.6 4297.5 4303.2 8780.9 8836.6 8844.8 9237.8 9258.5 4470.8 4500.6 4509.2 4513.8 4517.3 3926.3 3981.5 4101.9 4106.0 4115.4 3537.7 3554.0 3628.5 3641.5 3675.8 985.5 989.1 1147.2 1155.5 1203.1 667.9 669.4 688.7 785.7 787.7 4449.7 4541.8 4670.7 4765.8 5031.9 438.1 439.8 440.4 443.8 583.6 3001.6 3084.2 3094.6 3159.2 3252.3 9001.0 9091.5 9432.6 9437.6 9521.5 9671.4 9673.1 9685.1 9685.7 9693.4 7006.6 7178.1 7183.3 7196.3 7208.7 4746.9 4796.4 4841.5 4850.3 4887.0 8320.6 8444.5 8489.3 8586.9 8683.7 8482.3 8639.4 8795.6 8802.5 9048.0 6623.2 6738.4 6745.8 6757.9 6794.5 7222.3 7258.5 7259.1 7276.3 7290.6 6512.2 6696.9 6806.9 6853.0 6911.7 6399.3 6420.5 6424.8 6489.7 6510.7 5862.4 5922.8 5923.3 5932.8 5937.6 4186.4 4280.0 4360.5 4399.0 4453.7 4304.8 4376.5 4429.5 4482.0 4485.3 19:54:47 INFO - PROCESS | 21430 | 19:54:47 INFO - PROCESS | 21430 | [#4] prototype.html Cycles:180 Average:4143.56 Median:4731.33 stddev:1233.83 (26.1%) stddev-sans-first:1202.85 19:54:47 INFO - PROCESS | 21430 | Values: 286.7 297.4 299.7 302.2 302.6 4892.4 4895.9 4904.9 4905.0 4910.2 4776.3 4823.4 4858.0 4858.2 4868.2 3773.7 3777.4 3777.7 3782.6 3810.0 3525.5 3832.8 3839.1 3856.4 3858.3 3697.9 3735.6 3736.9 3743.8 3750.3 3711.0 3717.6 3721.7 3722.9 3726.9 3072.6 3136.8 3149.4 3247.2 3284.8 4695.7 4710.7 4715.5 4727.5 4735.2 4253.6 4258.7 4259.5 4259.8 4261.0 4768.2 4815.3 4847.2 5049.9 5066.8 3547.9 3572.2 3572.8 3580.5 3581.0 5242.3 5271.5 5276.4 5300.8 5318.5 3444.7 3639.6 3648.1 3654.5 3655.6 3692.0 3692.5 3696.4 3707.4 3717.9 4222.6 4230.3 4243.1 4244.6 4259.7 4912.1 5038.6 5053.1 5061.2 5093.7 1260.5 1267.3 1283.0 1292.4 1296.2 779.3 812.1 824.9 826.7 833.6 4017.5 4029.1 4037.4 4046.4 4062.4 4597.1 4617.8 4642.3 4644.2 4651.0 4001.3 4009.7 4013.0 4027.8 4038.2 2312.4 2427.2 2436.1 2448.9 2456.4 4373.4 4669.0 4758.5 4813.2 4915.2 5335.1 5343.3 5349.3 5365.5 5395.1 4940.4 4948.6 4956.6 4962.2 4976.1 4977.8 5145.4 5165.5 5184.1 5235.2 5126.0 5131.8 5139.9 5147.0 5163.1 4788.9 4907.1 4935.4 4937.4 4937.8 3648.8 3655.8 3691.2 3698.0 3702.1 5239.8 5249.4 5250.5 5256.7 5257.2 5063.8 5070.7 5076.3 5081.2 5095.8 4837.5 5041.2 5110.9 5113.3 5118.5 4999.9 5013.5 5015.9 5023.8 5039.9 4976.3 5142.8 5157.2 5183.9 5195.3 4973.7 4977.0 5023.7 5038.2 5038.6 19:54:47 INFO - PROCESS | 21430 | 19:54:47 INFO - PROCESS | 21430 | [#5] yui.html Cycles:180 Average:17420.06 Median:33044.39 stddev:25709.80 (77.8%) stddev-sans-first:25750.86 19:54:47 INFO - PROCESS | 21430 | Values: 587.5 587.9 613.4 646.1 657.4 12130.6 13120.9 13530.0 13553.9 13648.1 12876.6 13215.9 13217.9 13227.5 13275.2 605.3 752.4 773.0 773.0 777.1 603.9 753.1 765.0 779.8 785.8 813.7 816.3 816.5 816.5 816.8 632.0 812.3 812.3 812.4 812.9 477.3 578.7 582.1 592.0 600.0 12809.7 12834.9 12836.7 12848.6 12856.9 11824.8 12013.9 12563.8 12663.4 13196.1 9477.8 9518.2 9782.5 9851.0 12018.1 3999.8 4041.5 4114.6 4122.6 4129.4 63840.0 65318.7 65374.7 65431.3 66708.0 9657.3 10034.4 10168.4 10406.7 10607.8 18881.5 20379.2 20405.2 20442.7 20479.4 6909.6 7035.4 7040.4 7417.3 7490.0 3587.7 3729.7 3743.6 3774.3 3785.4 2804.5 2875.3 2894.8 2899.2 2908.7 490.1 496.2 499.3 502.9 511.5 435.5 469.4 477.8 482.0 494.8 7343.6 7398.6 7494.1 7511.4 7814.6 457.9 462.2 477.8 492.1 499.2 3373.1 3442.5 3501.2 3503.7 3719.4 55935.0 95191.0 96086.0 101748.5 101774.5 51827.7 82456.2 88503.6 89675.6 89821.1 75812.6 79752.0 80024.6 80301.6 80893.6 7089.3 7350.3 7358.6 7436.5 7499.7 46451.8 47872.0 47942.8 49082.8 49594.5 61756.4 68022.9 68194.7 68903.0 69196.3 9161.3 9321.9 9502.5 9513.7 9690.2 8914.9 9415.2 9558.9 9616.0 9855.4 9029.2 9115.4 9243.9 9387.3 9512.4 8931.9 9114.0 9180.9 9510.6 9776.7 8644.7 8658.9 8817.2 8906.1 9512.6 7747.8 7837.6 7878.6 7925.6 8006.9 7724.2 7795.0 8010.0 8054.1 9248.6 19:54:47 INFO - PROCESS | 21430 | -------- Summary: end -------- 19:54:47 INFO - PROCESS | 21430 | 19:54:48 INFO - TEST-INFO | 21430: exit 0 19:54:48 INFO - TEST-OK | dromaeo_css | took 1121278ms 19:54:48 INFO - TEST-START | kraken 19:54:48 INFO - operating with platform_type : linux_ 19:54:48 INFO - Initialising browser for kraken test... 19:54:48 INFO - TEST-INFO | started process 21737 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZrpoFi/profile http://localhost:49718/getInfo.html) 19:54:53 INFO - PROCESS | 21737 | __metrics Screen width/height:1600/1200 19:54:53 INFO - PROCESS | 21737 | colorDepth:24 19:54:53 INFO - PROCESS | 21737 | Browser inner width/height: 1024/697 19:54:53 INFO - PROCESS | 21737 | __metrics 19:54:53 INFO - PROCESS | 21737 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:54:53 INFO - PROCESS | 21737 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:54:54 INFO - PROCESS | 21737 | console.error: PushService: 19:54:54 INFO - PROCESS | 21737 | stateChangeProcessEnqueue: Error transitioning state 19:54:54 INFO - PROCESS | 21737 | UnknownError 19:54:55 INFO - TEST-INFO | 21737: exit 0 19:54:55 INFO - Browser initialized. 19:54:55 INFO - Running cycle 1/1 for kraken test... 19:54:55 INFO - TEST-INFO | started process 21818 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZrpoFi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:55:07 INFO - PROCESS | 21818 | RSS: Main: 178503680 19:55:07 INFO - PROCESS | 21818 | 19:55:10 INFO - PROCESS | 21818 | Cycle 1(1): loaded http://localhost:49718/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49718/tests/kraken/driver.html?testName=audio-beat-detection) 19:55:11 INFO - PROCESS | 21818 | RSS: Main: 246046720 19:55:11 INFO - PROCESS | 21818 | 19:55:13 INFO - PROCESS | 21818 | Cycle 1(1): loaded http://localhost:49718/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49718/tests/kraken/driver.html?testName=audio-dft) 19:55:14 INFO - PROCESS | 21818 | RSS: Main: 221990912 19:55:14 INFO - PROCESS | 21818 | 19:55:17 INFO - PROCESS | 21818 | Cycle 1(1): loaded http://localhost:49718/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49718/tests/kraken/driver.html?testName=audio-fft) 19:55:18 INFO - PROCESS | 21818 | RSS: Main: 304095232 19:55:18 INFO - PROCESS | 21818 | 19:55:20 INFO - PROCESS | 21818 | Cycle 1(1): loaded http://localhost:49718/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49718/tests/kraken/driver.html?testName=audio-oscillator) 19:55:21 INFO - PROCESS | 21818 | RSS: Main: 213696512 19:55:21 INFO - PROCESS | 21818 | 19:55:23 INFO - PROCESS | 21818 | Cycle 1(1): loaded http://localhost:49718/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49718/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:55:24 INFO - PROCESS | 21818 | RSS: Main: 217608192 19:55:24 INFO - PROCESS | 21818 | 19:55:27 INFO - PROCESS | 21818 | Cycle 1(1): loaded http://localhost:49718/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49718/tests/kraken/driver.html?testName=imaging-darkroom) 19:55:27 INFO - PROCESS | 21818 | RSS: Main: 253394944 19:55:27 INFO - PROCESS | 21818 | 19:55:31 INFO - PROCESS | 21818 | Cycle 1(1): loaded http://localhost:49718/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49718/tests/kraken/driver.html?testName=imaging-desaturate) 19:55:31 INFO - PROCESS | 21818 | RSS: Main: 248295424 19:55:31 INFO - PROCESS | 21818 | 19:55:34 INFO - PROCESS | 21818 | Cycle 1(1): loaded http://localhost:49718/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49718/tests/kraken/driver.html?testName=json-parse-financial) 19:55:35 INFO - PROCESS | 21818 | RSS: Main: 250585088 19:55:35 INFO - PROCESS | 21818 | 19:55:37 INFO - PROCESS | 21818 | Cycle 1(1): loaded http://localhost:49718/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49718/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:55:37 INFO - PROCESS | 21818 | RSS: Main: 214573056 19:55:37 INFO - PROCESS | 21818 | 19:55:39 INFO - PROCESS | 21818 | Cycle 1(1): loaded http://localhost:49718/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49718/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:55:40 INFO - PROCESS | 21818 | RSS: Main: 218001408 19:55:40 INFO - PROCESS | 21818 | 19:55:42 INFO - PROCESS | 21818 | Cycle 1(1): loaded http://localhost:49718/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49718/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:55:43 INFO - PROCESS | 21818 | RSS: Main: 222355456 19:55:43 INFO - PROCESS | 21818 | 19:55:46 INFO - PROCESS | 21818 | Cycle 1(1): loaded http://localhost:49718/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49718/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:55:46 INFO - PROCESS | 21818 | RSS: Main: 223895552 19:55:46 INFO - PROCESS | 21818 | 19:55:49 INFO - PROCESS | 21818 | Cycle 1(1): loaded http://localhost:49718/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49718/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:55:50 INFO - PROCESS | 21818 | RSS: Main: 221499392 19:55:50 INFO - PROCESS | 21818 | 19:55:52 INFO - PROCESS | 21818 | Cycle 1(1): loaded http://localhost:49718/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49718/tests/kraken/driver.html?testName=ai-astar) 19:55:52 INFO - PROCESS | 21818 | RSS: Main: 273440768 19:55:52 INFO - PROCESS | 21818 | 19:55:52 INFO - PROCESS | 21818 | __start_tp_report 19:55:52 INFO - PROCESS | 21818 | _x_x_mozilla_page_load 19:55:52 INFO - PROCESS | 21818 | _x_x_mozilla_page_load_details 19:55:52 INFO - PROCESS | 21818 | |i|pagename|runs| 19:55:52 INFO - PROCESS | 21818 | |0;ai-astar;115;117;117;118;116;115;116;117;117;115 19:55:52 INFO - PROCESS | 21818 | |1;audio-beat-detection;155;127;128;166;135;126;122;128;165;124 19:55:52 INFO - PROCESS | 21818 | |2;audio-dft;164;163;162;162;164;162;163;166;163;162 19:55:52 INFO - PROCESS | 21818 | |3;audio-fft;77;78;78;108;77;78;77;77;110;77 19:55:52 INFO - PROCESS | 21818 | |4;audio-oscillator;86;84;123;83;84;85;110;84;84;110 19:55:52 INFO - PROCESS | 21818 | |5;imaging-gaussian-blur;118;118;117;118;117;117;118;117;117;117 19:55:52 INFO - PROCESS | 21818 | |6;imaging-darkroom;149;147;146;148;147;148;148;148;147;146 19:55:52 INFO - PROCESS | 21818 | |7;imaging-desaturate;95;95;95;95;95;95;95;95;96;95 19:55:52 INFO - PROCESS | 21818 | |8;json-parse-financial;64;63;63;64;64;65;65;65;65;64 19:55:52 INFO - PROCESS | 21818 | |9;json-stringify-tinderbox;69;70;68;68;70;67;69;69;68;69 19:55:52 INFO - PROCESS | 21818 | |10;stanford-crypto-aes;83;83;87;83;89;82;93;82;82;92 19:55:52 INFO - PROCESS | 21818 | |11;stanford-crypto-ccm;143;139;142;152;136;135;137;132;175;142 19:55:52 INFO - PROCESS | 21818 | |12;stanford-crypto-pbkdf2;180;174;179;173;175;178;176;172;171;173 19:55:52 INFO - PROCESS | 21818 | |13;stanford-crypto-sha256-iterative;70;71;72;68;69;71;74;73;70;69 19:55:52 INFO - PROCESS | 21818 | __end_tp_report 19:55:52 INFO - PROCESS | 21818 | __start_cc_report 19:55:52 INFO - PROCESS | 21818 | _x_x_mozilla_cycle_collect,1973 19:55:52 INFO - PROCESS | 21818 | __end_cc_report 19:55:52 INFO - PROCESS | 21818 | __startTimestamp1474253752370__endTimestamp 19:55:52 INFO - PROCESS | 21818 | 19:55:52 INFO - PROCESS | 21818 | ------- Summary: start ------- 19:55:52 INFO - PROCESS | 21818 | Number of tests: 14 19:55:52 INFO - PROCESS | 21818 | 19:55:52 INFO - PROCESS | 21818 | [#0] ai-astar Cycles:10 Average:116.30 Median:117.00 stddev:1.06 (0.9%) stddev-sans-first:1.01 19:55:52 INFO - PROCESS | 21818 | Values: 115.0 117.0 117.0 118.0 116.0 115.0 116.0 117.0 117.0 115.0 19:55:52 INFO - PROCESS | 21818 | 19:55:52 INFO - PROCESS | 21818 | [#1] audio-beat-detection Cycles:10 Average:137.60 Median:131.50 stddev:17.40 (13.2%) stddev-sans-first:17.28 19:55:52 INFO - PROCESS | 21818 | Values: 155.0 127.0 128.0 166.0 135.0 126.0 122.0 128.0 165.0 124.0 19:55:52 INFO - PROCESS | 21818 | 19:55:52 INFO - PROCESS | 21818 | [#2] audio-dft Cycles:10 Average:163.10 Median:163.00 stddev:1.29 (0.8%) stddev-sans-first:1.32 19:55:52 INFO - PROCESS | 21818 | Values: 164.0 163.0 162.0 162.0 164.0 162.0 163.0 166.0 163.0 162.0 19:55:52 INFO - PROCESS | 21818 | 19:55:52 INFO - PROCESS | 21818 | [#3] audio-fft Cycles:10 Average:83.70 Median:77.00 stddev:13.35 (17.3%) stddev-sans-first:13.94 19:55:52 INFO - PROCESS | 21818 | Values: 77.0 78.0 78.0 108.0 77.0 78.0 77.0 77.0 110.0 77.0 19:55:52 INFO - PROCESS | 21818 | 19:55:52 INFO - PROCESS | 21818 | [#4] audio-oscillator Cycles:10 Average:93.30 Median:84.00 stddev:14.96 (17.8%) stddev-sans-first:15.63 19:55:52 INFO - PROCESS | 21818 | Values: 86.0 84.0 123.0 83.0 84.0 85.0 110.0 84.0 84.0 110.0 19:55:52 INFO - PROCESS | 21818 | 19:55:52 INFO - PROCESS | 21818 | [#5] imaging-gaussian-blur Cycles:10 Average:117.40 Median:117.50 stddev:0.52 (0.4%) stddev-sans-first:0.50 19:55:52 INFO - PROCESS | 21818 | Values: 118.0 118.0 117.0 118.0 117.0 117.0 118.0 117.0 117.0 117.0 19:55:52 INFO - PROCESS | 21818 | 19:55:52 INFO - PROCESS | 21818 | [#6] imaging-darkroom Cycles:10 Average:147.40 Median:148.00 stddev:0.97 (0.7%) stddev-sans-first:0.83 19:55:52 INFO - PROCESS | 21818 | Values: 149.0 147.0 146.0 148.0 147.0 148.0 148.0 148.0 147.0 146.0 19:55:52 INFO - PROCESS | 21818 | 19:55:52 INFO - PROCESS | 21818 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 19:55:52 INFO - PROCESS | 21818 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 96.0 95.0 19:55:52 INFO - PROCESS | 21818 | 19:55:52 INFO - PROCESS | 21818 | [#8] json-parse-financial Cycles:10 Average:64.20 Median:64.50 stddev:0.79 (1.2%) stddev-sans-first:0.83 19:55:52 INFO - PROCESS | 21818 | Values: 64.0 63.0 63.0 64.0 64.0 65.0 65.0 65.0 65.0 64.0 19:55:52 INFO - PROCESS | 21818 | 19:55:52 INFO - PROCESS | 21818 | [#9] json-stringify-tinderbox Cycles:10 Average:68.70 Median:69.00 stddev:0.95 (1.4%) stddev-sans-first:1.00 19:55:52 INFO - PROCESS | 21818 | Values: 69.0 70.0 68.0 68.0 70.0 67.0 69.0 69.0 68.0 69.0 19:55:52 INFO - PROCESS | 21818 | 19:55:52 INFO - PROCESS | 21818 | [#10] stanford-crypto-aes Cycles:10 Average:85.60 Median:85.00 stddev:4.33 (5.1%) stddev-sans-first:4.48 19:55:52 INFO - PROCESS | 21818 | Values: 83.0 83.0 87.0 83.0 89.0 82.0 93.0 82.0 82.0 92.0 19:55:52 INFO - PROCESS | 21818 | 19:55:52 INFO - PROCESS | 21818 | [#11] stanford-crypto-ccm Cycles:10 Average:143.30 Median:142.00 stddev:12.44 (8.8%) stddev-sans-first:13.19 19:55:52 INFO - PROCESS | 21818 | Values: 143.0 139.0 142.0 152.0 136.0 135.0 137.0 132.0 175.0 142.0 19:55:52 INFO - PROCESS | 21818 | 19:55:52 INFO - PROCESS | 21818 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.10 Median:175.50 stddev:3.07 (1.8%) stddev-sans-first:2.70 19:55:52 INFO - PROCESS | 21818 | Values: 180.0 174.0 179.0 173.0 175.0 178.0 176.0 172.0 171.0 173.0 19:55:52 INFO - PROCESS | 21818 | 19:55:52 INFO - PROCESS | 21818 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.70 Median:71.00 stddev:1.89 (2.7%) stddev-sans-first:1.99 19:55:52 INFO - PROCESS | 21818 | Values: 70.0 71.0 72.0 68.0 69.0 71.0 74.0 73.0 70.0 69.0 19:55:52 INFO - PROCESS | 21818 | -------- Summary: end -------- 19:55:52 INFO - PROCESS | 21818 | 19:55:53 INFO - TEST-INFO | 21818: exit 0 19:55:53 INFO - TEST-OK | kraken | took 65159ms 19:55:53 INFO - SUITE-END | took 1186s 19:55:54 INFO - Completed test suite (00:19:46) 19:55:54 INFO - javascript benchmark 19:55:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2560.7055188653303, 2587.9353016174605, 2608.126277696972, 2609.921702348929, 2633.7497937695925, 4602.07958408318, 4693.601043911263, 4714.504976977415, 4755.096531658983, 4757.024809913973, 4703.129920964615, 4716.0, 4722.0, 4722.150012997659, 4776.307435421862, 4286.849960251392, 4316.179925814085, 4323.891902702426, 4337.371081193225, 4411.18393097278, 4379.277419225824, 4428.911421771562, 4433.179861725591, 4459.487158976711, 4512.142692888341, 4225.15496900618, 4291.6566674665955, 4392.143532011259, 4425.601695847373, 4453.4433195850515, 4318.4170137031415, 4328.069465064995, 4338.262495375762, 4338.826446942118, 4351.608355248011, 4276.5295817460365, 4306.569343065668, 4310.374995625648, 4314.374415709704, 4370.934435983463, 4675.7895894684825, 4705.5764981151515, 4717.221658426355, 4751.762411879392, 4774.021325628238, 5035.748212589356, 5037.596992240612, 5037.848864534069, 5044.243363495469, 5099.08216521026, 4899.730514821684, 4980.726060066695, 5026.773795179226, 5061.873453163664, 5131.050755610187, 4705.129551033036, 4728.456227533878, 4731.408573928259, 4775.856724298312, 4793.520647935178, 5130.076586214517, 5179.326687530673, 5183.922241166386, 5190.325257716549, 5249.632525723163, 4759.929015971379, 4815.95184048155, 4880.877978050577, 4893.975530122327, 4952.08386448501, 4949.678270912379, 4989.825356112518, 5000.824971126066, 5015.14742493777, 5073.974630126826, 4981.427135879348, 5006.374203224597, 5012.3233363495465, 5014.949850501448, 5075.771590278372, 4806.423229212517, 5001.799928002839, 5012.899742005139, 5013.899722005613, 5075.746212689424, 4229.873103806891, 4287.206866729665, 4355.5208927018, 4405.933910991338, 4407.0524837160165, 3814.9427758583643, 3817.0457385653585, 3819.1788765415013, 3864.652181303696, 3867.535895692479, 4435.512093669694, 4437.511873693891, 4437.644988400984, 4482.350059241392, 4496.4154659894, 4900.509949005071, 4952.4304704959395, 5079.060373830853, 5111.693298402108, 5136.794528218829, 4414.359917811964, 4416.668749843775, 4451.465824101128, 4454.042380888124, 4469.888252793706, 4627.3521706961055, 4628.120657075145, 4641.0, 4644.7213167210075, 4697.0, 4604.746738929391, 4642.025174713348, 4788.952110478851, 4819.662623616383, 4823.1800593898415, 5101.234814777813, 5149.330587023663, 5151.871203219799, 5174.870628234325, 5215.29593504872, 4797.472278049481, 4829.058333624909, 4838.8790280241865, 4840.1529732296, 4898.608111351155, 4916.139675556692, 4993.500649934977, 5074.340335756328, 5080.441151473409, 5142.588592912483, 5027.396712394536, 5044.1172794761505, 5071.54356107952, 5076.593872490118, 5151.459096794782, 4924.679895806761, 4940.184869496637, 4941.50584941503, 4942.308076869169, 5004.324416203766, 4805.663603547718, 4924.729139897197, 4931.0630980113665, 4933.457319694902, 4993.17612593918, 4788.712677239377, 4843.757812109451, 4879.072976134523, 4882.048000639841, 4928.334674818853, 4947.406311242674, 4951.356323677899, 4954.380702412199, 4964.528369804862, 4977.377827771528, 4735.810567577259, 4775.2359622460235, 4784.258439941815, 4853.708777473363, 4859.902801943871], "value": 4651.356791450278, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [565.0761005755588, 565.3554947360009, 573.2833957553058, 574.7844558290641, 576.9538436925045, 28398.0, 37671.81164094179, 38685.99999999996, 38949.8052509737, 43527.12945741083, 39423.60576394235, 39791.204175916464, 39841.40237896434, 39862.202755944934, 39932.20135597294, 7593.0, 7725.111612164589, 7728.033995750531, 7734.729284475045, 7734.806629834271, 7585.582792946386, 7655.387568994495, 7666.271704188093, 7671.53970761756, 7674.501157424777, 7695.307422331989, 7735.994320738296, 7745.380369570449, 7748.070231572219, 7750.728724494644, 7654.617269136549, 7696.230376962315, 7698.230176982313, 7712.652930618079, 7717.421193410517, 7605.0, 7666.3866890648615, 7671.53970761756, 7671.6164191790185, 7673.846523069507, 36924.630753692385, 37297.813510932545, 37348.81325593382, 37379.81310093432, 37464.0, 2911.6651585067566, 2919.194322191813, 2969.5545668149734, 2980.3294258791598, 3047.4514587374265, 28245.435091298263, 28398.716012839814, 28531.714682853115, 28920.42159156826, 29455.0, 3479.5998460176897, 3513.350030244387, 3519.665631765004, 3560.0743806610208, 3578.6063533011347, 253466.0, 253731.0, 253742.0, 254089.0, 255524.0, 51565.742171288905, 51863.481365185864, 52029.73985130126, 52033.7398313006, 52058.21912671313, 32404.83797580997, 33350.332993339995, 33361.83319083389, 35219.82390088033, 35241.82379088088, 13437.932810336082, 13581.0, 13607.455701772016, 13610.863891361158, 13617.0, 10096.798064038678, 10183.898161018295, 10185.33795303303, 10185.74535636615, 10189.133923616428, 17423.0, 17739.645207096044, 18015.207642815385, 18028.098595070194, 18053.458396248134, 6550.6069635822005, 6553.377429144319, 6561.901571476432, 6574.342565743483, 6830.658467076626, 743.4832791210057, 846.7205822078699, 891.7369376034086, 935.2891802230354, 943.4905151218265, 25878.6118208227, 27847.30381740473, 28337.71662283392, 28443.146705598865, 28465.71534284672, 744.5383862005591, 918.4581097152712, 922.6586163119689, 930.8650245714333, 992.6029588164791, 13614.795778063337, 14355.282235888373, 14483.348249328805, 14643.92678036603, 14663.34014969329, 382018.0, 383466.0, 383684.0, 384180.0, 384338.0, 49190.75404622954, 49432.50567494279, 49441.75279123581, 49452.258216126786, 49740.0, 998.7066748560632, 1045.5974449836715, 1147.1740346950016, 1167.3171194851107, 1200.6278053803348, 25732.87133564395, 29151.75268451614, 29181.416371672873, 29244.7075529242, 29479.557806633347, 41454.378184326655, 41896.581034189265, 41984.38171801039, 41993.370099449145, 41996.0, 291872.0, 293400.0, 293446.0, 293589.4936284879, 294009.0, 24731.62902556499, 25527.489450211262, 25645.615315769905, 25645.743542564334, 25690.100846470283, 40921.59078409178, 41022.38466423066, 41143.588564113976, 41277.38083928804, 41849.58150418457, 35186.0, 35262.0, 35424.46863297001, 35523.0, 36139.27721445504, 34845.303093938484, 34897.65102348944, 35212.823935881184, 35265.47101793424, 35444.0, 34911.0, 34995.825020874734, 35256.64743352534, 35389.74510225346, 36294.455583166804, 30121.69878301189, 32163.839180804884, 32349.51475727819, 32433.0, 32850.01449956505, 24327.27018189457, 24737.134200302902, 24742.876285619175, 24893.253202403957, 25177.622335665346], "value": 16899.29995141657, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [339.86395504255756, 364.8850612057202, 371.27415901911763, 374.53183520599254, 388.5861557441325, 12345.50617975281, 13172.736545269088, 14471.0, 16287.51137465878, 17088.060156691376, 15819.683606327866, 16291.429799957004, 16358.427455039076, 16958.915205423986, 17276.568085797862, 4302.354646802981, 4525.434320709911, 4577.038821847416, 4584.954150458503, 4588.587027167554, 3413.2832105257926, 4631.402166252654, 4698.506656801038, 4699.859004229861, 4704.059188162364, 3572.7320450966154, 4993.825216117437, 5219.921701174467, 5249.265102885599, 5307.2569840222395, 5199.317022137568, 5274.8417547473255, 5300.204969254604, 5314.362276526822, 5314.441983591744, 5173.456787037345, 5189.1697328427645, 5205.583553315726, 5218.817341393071, 5221.216817477371, 12067.275963442135, 12132.0, 12143.453544590515, 12193.573224937085, 12221.388930553438, 1810.1944634637591, 1828.396629112403, 1857.7584913961098, 1883.6138591588692, 2383.1778036577352, 8535.914640853574, 8728.472517309508, 8775.341849361259, 8878.955605221998, 9449.622015119387, 1619.3765400320844, 1723.2676112652198, 1750.1161913233864, 1774.5829730013436, 1774.9112544372858, 46537.90579423934, 59459.70270148665, 62565.68717156431, 67747.66126169387, 73280.26719732788, 9695.709128726008, 9926.823416506648, 10033.899661003443, 10096.495175241209, 10288.897111028793, 14110.929445352707, 14114.082584631966, 14122.576322710334, 14124.576262712135, 14131.0, 7072.646367681596, 7104.822379440556, 7252.782416527512, 7336.853262934818, 7385.556866588022, 6532.41208291256, 6591.077249185118, 6652.268250492442, 6660.300668429745, 6834.419074378634, 4228.957710422918, 4278.336857787048, 4333.479982402132, 4374.956250437455, 4391.802368893407, 1688.5525335786026, 1749.0, 1751.7810273715786, 1830.3044842959857, 1873.9156737946826, 762.6529928882345, 800.1598321762126, 833.9041010283895, 929.4748467116058, 934.2199263614957, 16039.03765774034, 16463.588410289598, 16676.0, 16919.407820726214, 17503.29986800514, 813.6785969542116, 839.630562552475, 852.1265702654705, 857.1342943626981, 879.5426378283257, 8811.0, 9398.859017114748, 9585.952070239604, 9602.90397096034, 9800.26498012652, 372764.0, 374198.0, 381902.0, 383649.0, 385222.0, 55159.0, 80473.19526804774, 80945.0, 81503.59248203722, 82040.58979705063, 841.5581819544788, 842.6081871929705, 883.8585826267766, 888.1429420609014, 892.3040028777564, 11145.387003714874, 14028.649283767987, 15114.470993515175, 15264.23678816077, 15509.689806204038, 36106.45840312345, 36217.81891090528, 36316.82099074064, 36613.0, 36928.26143477169, 43940.560594393646, 46777.0, 47126.528734712214, 47154.0, 49150.262746059554, 6525.0, 6750.32496750321, 6801.727930882709, 7276.781696549112, 7641.42689298316, 11172.88827111751, 11418.486168122621, 11553.537858485566, 11884.405779711153, 12138.271703697807, 9994.300398972002, 10109.0, 10183.287169898036, 10386.948065259625, 10562.419066951392, 9822.214222862296, 10114.696559103239, 10115.79768404613, 10141.188704903738, 10248.282620216512, 10059.29584929048, 10262.0, 10534.578616855546, 10552.208584356358, 10667.786644266916, 6920.826979325557, 6963.9303606963285, 7065.258147894397, 7153.535020223669, 7311.8537629248185, 8238.340932725248, 10365.326253793479, 10367.896321036995, 10424.791504170025, 10989.890101098887], "value": 7787.266128158127, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1319.4656164253477, 1363.9045266831322, 1380.751464736347, 1624.9350025998947, 1645.8518733314006, 6739.1239138912, 6946.131733533309, 7324.340809327159, 7954.0852801927795, 7964.60176991151, 7244.384227340683, 7464.626768661572, 7470.0662417197855, 7712.7686169414865, 7722.768316950486, 2159.1795117855195, 2182.9126834926583, 2187.267265466071, 2188.4200686817926, 2193.5393567350875, 1795.973060404095, 1848.5378655336167, 1983.4446355020546, 2005.8194762471376, 2077.3767869639123, 2023.5447024419534, 2065.4733043073993, 2069.5653912678354, 2075.3773867839664, 2076.190285788544, 2102.5584627228377, 2102.7581828089815, 2107.6943843142813, 2116.1958455786703, 2153.8061574458375, 1973.842092632586, 1980.1881228696166, 1980.7326010988477, 1983.1274239334646, 1983.4049785064567, 7725.49784264021, 7882.605869706549, 7892.960535197345, 7896.328812050982, 7896.486728362638, 4372.060007098457, 4508.030773383705, 4510.2783554631105, 4515.232410490192, 4516.503184649686, 2600.9349766255996, 2648.1128821844704, 2739.1371717908796, 2774.6254255675426, 2790.1629511146575, 1030.5156576409076, 1037.4294138224022, 1052.3317693264833, 1061.495789499989, 1079.0773888325448, 10614.25700200994, 10622.25644204906, 10634.20243481742, 10648.148148148208, 10665.84001239982, 4061.3704875744306, 4184.748915065105, 4296.591823776736, 4297.462817147856, 4303.182395344875, 8780.912190878138, 8836.55817209137, 8844.778880528038, 9237.769055773526, 9258.490783006864, 4470.798814053375, 4500.617447516932, 4509.210888094571, 4513.819447222074, 4517.254652982221, 3926.332523471017, 3981.5023122109737, 4101.938470922939, 4105.97947010263, 4115.42384066231, 3537.699295559855, 3553.964460355415, 3628.5101511296166, 3641.5083963665097, 3675.8162091895297, 985.4678473624163, 989.085096285947, 1147.1740346950182, 1155.4511606986614, 1203.0796440722854, 667.9413130188608, 669.4276393683427, 688.6763221286087, 785.7328508307089, 787.7006737439851, 4449.710768800018, 4541.75020373876, 4670.743109129031, 4765.833195838199, 5031.924521132186, 438.0800319329446, 439.7625282347561, 440.4472387154101, 443.79807187729506, 583.5594126434514, 3001.5947847040807, 3084.1981084918084, 3094.628644562667, 3159.225989632555, 3252.349530093991, 9000.954995225113, 9091.499967501852, 9432.575534100983, 9437.622495100257, 9521.476318802532, 9671.371360861522, 9673.081057299545, 9685.079917407835, 9685.661001865043, 9693.369930954465, 7006.614636195059, 7178.066851309401, 7183.281671832775, 7196.280371962866, 7208.747693830691, 4746.905061898812, 4796.424429068534, 4841.540053694894, 4850.345203397496, 4887.022595480989, 8320.583970801315, 8444.493330400195, 8489.320854331761, 8586.871196932176, 8683.652653893774, 8482.321414286966, 8639.438436501607, 8795.648174072965, 8802.471851688919, 9047.954760226157, 6623.205215374186, 6738.393544581011, 6745.752035873379, 6757.898631520433, 6794.524383293122, 7222.349988501061, 7258.45561582894, 7259.05632267823, 7276.272372762682, 7290.635468226674, 6512.153420055362, 6696.899546506904, 6806.897896531655, 6853.0405743195, 6911.723531058902, 6399.328070552525, 6420.454261387835, 6424.775132870327, 6489.707963141765, 6510.73957041713, 5862.355140934579, 5922.7927022554, 5923.318818335774, 5932.822015339547, 5937.554683398676, 4186.353857695805, 4279.97860010698, 4360.454943132108, 4399.032212913154, 4453.665975051819, 4304.784760762012, 4376.4967028790825, 4429.512753597037, 4482.0, 4485.282354823213], "value": 3926.41985541597, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [286.69896608561004, 297.4126100950623, 299.6763495424941, 302.17355532618296, 302.59301239832416, 4892.412910450751, 4895.926561101582, 4904.871879467724, 4905.0, 4910.2143657014885, 4776.259679749644, 4823.445303790065, 4857.951420485794, 4858.206531244531, 4868.221084626461, 3773.6603705666485, 3777.357849165635, 3777.6788972937334, 3782.621737826209, 3810.0093975566415, 3525.5240542526685, 3832.770033797981, 3839.0978120141745, 3856.421536769479, 3858.305505009097, 3697.870574529892, 3735.6451137141926, 3736.8878933632036, 3743.794091324976, 3750.343689854278, 3711.0, 3717.583570679053, 3721.6650501455, 3722.9255414891823, 3726.850925962958, 3072.646645635758, 3136.780425370225, 3149.3543823516125, 3247.1719711473424, 3284.8193349365774, 4695.6852081417255, 4710.670253082286, 4715.491042866284, 4727.479977202505, 4735.171345014609, 4253.617174454314, 4258.657738243985, 4259.531451540328, 4259.8083086261195, 4260.957390426118, 4768.189407800647, 4815.277708343741, 4847.175980083394, 5049.924251136236, 5066.771995260222, 3547.946780798264, 3572.214112895159, 3572.7856328620105, 3580.480830279595, 3580.964190358115, 5242.344706911636, 5271.499207575274, 5276.419593844674, 5300.761465734051, 5318.521333080042, 3444.7244220462303, 3639.5996440391536, 3648.1244501320025, 3654.470101835219, 3655.592596850232, 3691.9815400922826, 3692.486080706897, 3696.4309804686022, 3707.3882809336155, 3717.8512859485318, 4222.556631553704, 4230.2562232424525, 4243.130158317537, 4244.596763307481, 4259.7231179973205, 4912.077313998166, 5038.64729468941, 5053.1157047516535, 5061.190209566452, 5093.745312734349, 1260.5084017233287, 1267.33464930912, 1282.9800308754564, 1292.4442489729317, 1296.2157894473896, 779.2597032818845, 812.1188510466093, 824.9175082491703, 826.7478419082117, 833.633201391398, 4017.4733601231346, 4029.0531725044593, 4037.4347591337237, 4046.4132700758814, 4062.390641403813, 4597.149527337453, 4617.792199351037, 4642.305558471134, 4644.19026488976, 4650.953535454563, 4001.279769641434, 4009.6591789698205, 4012.9598704012587, 4027.7986100695434, 4038.232735780251, 2312.4450131968206, 2427.1626288930293, 2436.0621160853198, 2448.8653124078346, 2456.385903524119, 4373.387725718402, 4668.97665511677, 4758.452777930513, 4813.205821039559, 4915.238138088602, 5335.146376579729, 5343.278657381282, 5349.277847490617, 5365.490278423544, 5395.140575494994, 4940.357753492023, 4948.579370753526, 4956.578690811321, 4962.230854217602, 4976.129177393942, 4977.800887964441, 5145.3568303962, 5165.457626949266, 5184.066867963803, 5235.188545775411, 5126.0, 5131.79472821083, 5139.92290115656, 5146.974265128651, 5163.148080566814, 4788.928166077581, 4907.141250281115, 4935.358403407534, 4937.407511098691, 4937.790436216488, 3648.7628304160144, 3655.83013435698, 3691.187938653492, 3698.0200246935124, 3702.1299994500732, 5239.790408383775, 5249.448807875118, 5250.474952504872, 5256.737163141904, 5257.185136303879, 5063.822766203165, 5070.706969722844, 5076.289319495348, 5081.212412076134, 5095.77069031887, 4837.4920633332995, 5041.2438134280155, 5110.9233361500355, 5113.3097031900215, 5118.49004543668, 4999.900001999867, 5013.473585273494, 5015.874603134805, 5023.824166154167, 5039.899202016012, 4976.253561965734, 5142.76857541419, 5157.226416037624, 5183.896322073613, 5195.29863468442, 4973.726445045557, 4976.975115124401, 5023.6985780853265, 5038.2190760432195, 5038.622103342337], "value": 3750.42101964223, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [587.5475883569654, 587.8647910980475, 613.3682307223559, 646.1245013007374, 657.441175001249, 12130.636080917575, 13120.868791312085, 13530.0, 13553.93223033886, 13648.11287266327, 12876.613701588967, 13215.933920330384, 13217.933910330436, 13227.537036203736, 13275.203487790739, 605.2827399531569, 752.4131177681418, 773.0298475413348, 773.0414286285022, 777.0752804163056, 603.9486643635296, 753.1075675324757, 764.9961750191269, 779.7621725373758, 785.8114052627357, 813.662330132994, 816.2735165702529, 816.481534225768, 816.5304949653943, 816.824382757707, 631.9684015799191, 812.2811311988874, 812.2851890336525, 812.3947658994028, 812.8780682897583, 477.3198192575566, 578.7366748129633, 582.1093726598311, 592.0, 599.9880002399927, 12809.679758006125, 12834.871651283462, 12836.679083022906, 12848.614541563767, 12856.935715321457, 11824.822627660593, 12013.918747312784, 12563.811542826865, 12663.366831658472, 13196.076274660778, 9477.763055923588, 9518.239710483951, 9782.51087445625, 9850.9507452463, 12018.098642601839, 3999.780615373875, 4041.5352234493116, 4114.5679703630985, 4122.6083522065355, 4129.401236820644, 63840.04239936451, 65318.67340663314, 65374.673126634065, 65431.345686543, 66708.0, 9657.275704322205, 10034.397936123856, 10168.373390107483, 10406.739831504121, 10607.84088238677, 18881.527961801065, 20379.184832606825, 20405.183792648426, 20442.693359599616, 20479.38561843147, 6909.550879192836, 7035.401990830735, 7040.36636702689, 7417.295356941082, 7490.0, 3587.65917237862, 3729.701623870138, 3743.644353786386, 3774.339490589136, 3785.356489396809, 2804.509210888127, 2875.3386721053857, 2894.8407837569134, 2899.217211352965, 2908.6800451950694, 490.1055573578234, 496.1936852614501, 499.28602098998783, 502.93461849960005, 511.5421697580719, 435.505701029334, 469.40854523300203, 477.84708893153856, 481.9783109760069, 494.84412410090704, 7343.596102214323, 7398.593077380688, 7494.1381741099385, 7511.3615342696485, 7814.648340824677, 457.89926216232374, 462.1588708550406, 477.82320541399906, 492.1117383123425, 499.21623051809036, 3373.1229880231317, 3442.5008373786163, 3501.2122272488486, 3503.737219708532, 3719.367707489733, 55935.0, 95191.0, 96086.0, 101748.49125754472, 101774.4911275439, 51827.74086129545, 82456.17543824605, 88503.55748221217, 89675.55162224278, 89821.10178898257, 75812.62093689496, 79752.0, 80024.59987700141, 80301.59849200716, 80893.59553202314, 7089.255628158969, 7350.338469537767, 7358.632068396666, 7436.516626419266, 7499.737509187153, 46451.76774116108, 47872.0, 47942.760286199744, 49082.75458622684, 49594.50405496043, 61756.38243617507, 68022.91862488266, 68194.65902670653, 68903.0, 69196.30803692101, 9161.31290153228, 9321.860172097287, 9502.524873756423, 9513.667021654486, 9690.224782017563, 8914.910850891667, 9415.246780257427, 9558.85661715061, 9615.951920240354, 9855.35940163887, 9029.23251523628, 9115.353723815695, 9243.907560924305, 9387.295952803704, 9512.429254244767, 8931.910680893368, 9114.0, 9180.862287065565, 9510.572024259063, 9776.706698799047, 8644.740657780276, 8658.913410866062, 8817.16236957488, 8906.109389061043, 9512.571934262836, 7747.777461283727, 7837.6081195941115, 7878.56667883249, 7925.564093974886, 8006.919930800617, 7724.227577242231, 7795.0, 8010.0, 8054.073781515201, 9248.583813728264], "value": 6155.025402057278, "unit": "score"}], "name": "dromaeo_css", "value": 6175.872333770727, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 117.0, 117.0, 118.0, 116.0, 115.0, 116.0, 117.0, 117.0, 115.0], "value": 116.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 127.0, 128.0, 166.0, 135.0, 126.0, 122.0, 128.0, 165.0, 124.0], "value": 137.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 163.0, 162.0, 162.0, 164.0, 162.0, 163.0, 166.0, 163.0, 162.0], "value": 163.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 78.0, 78.0, 108.0, 77.0, 78.0, 77.0, 77.0, 110.0, 77.0], "value": 83.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 123.0, 83.0, 84.0, 85.0, 110.0, 84.0, 84.0, 110.0], "value": 93.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 117.0, 118.0, 117.0, 117.0, 118.0, 117.0, 117.0, 117.0], "value": 117.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 147.0, 146.0, 148.0, 147.0, 148.0, 148.0, 148.0, 147.0, 146.0], "value": 147.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 63.0, 64.0, 64.0, 65.0, 65.0, 65.0, 65.0, 64.0], "value": 64.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 68.0, 68.0, 70.0, 67.0, 69.0, 69.0, 68.0, 69.0], "value": 68.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 87.0, 83.0, 89.0, 82.0, 93.0, 82.0, 82.0, 92.0], "value": 85.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 139.0, 142.0, 152.0, 136.0, 135.0, 137.0, 132.0, 175.0, 142.0], "value": 143.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 174.0, 179.0, 173.0, 175.0, 178.0, 176.0, 172.0, 171.0, 173.0], "value": 175.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 71.0, 72.0, 68.0, 69.0, 71.0, 74.0, 73.0, 70.0, 69.0], "value": 70.7, "unit": "score"}], "name": "kraken", "value": 1561.4999999999998, "alertThreshold": 2.0}]} 19:55:54 INFO - Return code: 0 19:55:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:55:54 INFO - # TBPL SUCCESS # 19:55:54 INFO - Running post-action listener: _resource_record_post_action 19:55:54 INFO - [mozharness: 2016-09-19 02:55:54.184257Z] Finished run-tests step (success) 19:55:54 INFO - Running post-run listener: _resource_record_post_run 19:55:54 INFO - Total resource usage - Wall time: 1203s; CPU: 13.0%; Read bytes: 4096; Write bytes: 408514560; Read time: 16; Write time: 350040 19:55:54 INFO - TinderboxPrint: CPU usage
13.1% 19:55:54 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 19:55:54 INFO - TinderboxPrint: I/O write bytes / time
408,514,560 / 350,040 19:55:54 INFO - TinderboxPrint: CPU idle
8,337.5 (86.9%) 19:55:54 INFO - TinderboxPrint: CPU user
1,205.9 (12.6%) 19:55:54 INFO - TinderboxPrint: Swap in / out
0 / 0 19:55:54 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 99831808; Read time: 0; Write time: 108328 19:55:54 INFO - run-tests - Wall time: 1187s; CPU: 13.0%; Read bytes: 0; Write bytes: 297082880; Read time: 0; Write time: 239660 19:55:54 INFO - Running post-run listener: _upload_blobber_files 19:55:54 INFO - Blob upload gear active. 19:55:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:55:54 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 19:55:54 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', 'Fx-Team-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:55:54 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 Fx-Team-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:55:55 INFO - (blobuploader) - INFO - Open directory for files ... 19:55:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:55:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:55:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:55:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:55:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:55:59 INFO - (blobuploader) - INFO - Done attempting. 19:55:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:55:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:55:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:55:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:55:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:55:59 INFO - (blobuploader) - INFO - Done attempting. 19:55:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:55:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:55:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:56:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:56:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:56:00 INFO - (blobuploader) - INFO - Done attempting. 19:56:00 INFO - (blobuploader) - INFO - Iteration through files over. 19:56:00 INFO - Return code: 0 19:56:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:56:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:56:00 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/a72c2d33dab182e775b6a62663278037c1e0f09d7c65559771033caa72a8c7164d93c8884f77f583529bf2df588b15abe1d3ad334c72eb12f8262c7485ccc158", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/4e6c722fcdc86cd34b91f8e385feb0c820b56ac4a22c8136f810937c5d4f6d128c836ce7474fe09548e34d595d5bef3aecaf7169d61c4cfd1cb2badb4964513d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/ec3826ad6ee26e45c1dc17c476da320a802a1c50c4bd48f0e1ed4ee34c494d7b56d565342d68e9e4ee12ef9ba804aa460b3d4c0e4297fdca353210b5913b8b04"} 19:56:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:56:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:56:00 INFO - Contents: 19:56:00 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/a72c2d33dab182e775b6a62663278037c1e0f09d7c65559771033caa72a8c7164d93c8884f77f583529bf2df588b15abe1d3ad334c72eb12f8262c7485ccc158", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/4e6c722fcdc86cd34b91f8e385feb0c820b56ac4a22c8136f810937c5d4f6d128c836ce7474fe09548e34d595d5bef3aecaf7169d61c4cfd1cb2badb4964513d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/ec3826ad6ee26e45c1dc17c476da320a802a1c50c4bd48f0e1ed4ee34c494d7b56d565342d68e9e4ee12ef9ba804aa460b3d4c0e4297fdca353210b5913b8b04"} 19:56:00 INFO - Running post-run listener: copy_logs_to_upload_dir 19:56:00 INFO - Copying logs to upload dir... 19:56:00 INFO - mkdir: /builds/slave/test/build/upload/logs 19:56:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1248.673067 ========= master_lag: -0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2016-09-18 19:55:59.113525) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-18 19:55:59.117121) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1474242289.533299-77868688 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/a72c2d33dab182e775b6a62663278037c1e0f09d7c65559771033caa72a8c7164d93c8884f77f583529bf2df588b15abe1d3ad334c72eb12f8262c7485ccc158", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/4e6c722fcdc86cd34b91f8e385feb0c820b56ac4a22c8136f810937c5d4f6d128c836ce7474fe09548e34d595d5bef3aecaf7169d61c4cfd1cb2badb4964513d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/ec3826ad6ee26e45c1dc17c476da320a802a1c50c4bd48f0e1ed4ee34c494d7b56d565342d68e9e4ee12ef9ba804aa460b3d4c0e4297fdca353210b5913b8b04"} build_url:https://queue.taskcluster.net/v1/task/JrzmoW4DRC2Nk-cWFrRkzA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011335 build_url: 'https://queue.taskcluster.net/v1/task/JrzmoW4DRC2Nk-cWFrRkzA/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/Fx-Team-Non-PGO/sha512/a72c2d33dab182e775b6a62663278037c1e0f09d7c65559771033caa72a8c7164d93c8884f77f583529bf2df588b15abe1d3ad334c72eb12f8262c7485ccc158", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/4e6c722fcdc86cd34b91f8e385feb0c820b56ac4a22c8136f810937c5d4f6d128c836ce7474fe09548e34d595d5bef3aecaf7169d61c4cfd1cb2badb4964513d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/ec3826ad6ee26e45c1dc17c476da320a802a1c50c4bd48f0e1ed4ee34c494d7b56d565342d68e9e4ee12ef9ba804aa460b3d4c0e4297fdca353210b5913b8b04"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-18 19:55:59.142607) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-18 19:55:59.142969) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1474242289.533299-77868688 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004493 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-18 19:55:59.225673) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-18 19:55:59.225991) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-18 19:55:59.226287) ========= ========= Total master_lag: 0.15 =========