builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-032 starttime: 1474408335.58 results: success (0) buildid: 20160920142006 builduid: de47eebdb617430296736d3ef1c8bef3 revision: e7108b1c6731f1456ee875ed32e469c5eb7df1c1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-20 14:52:15.578772) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-20 14:52:15.579250) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-20 14:52:15.579533) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-20 14:52:15.594026) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 14:52:15.594309) ========= 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=745a4373a2a16df560f216e000000264-1474408334.921985-1480842233 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-20 14:52:15-- 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% 223M=0s 2016-09-20 14:52:15 (223 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.116255 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 14:52:15.724418) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 14:52:15.724744) ========= 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=745a4373a2a16df560f216e000000264-1474408334.921985-1480842233 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013580 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 14:52:15.774487) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 14:52:15.774866) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev e7108b1c6731f1456ee875ed32e469c5eb7df1c1 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev e7108b1c6731f1456ee875ed32e469c5eb7df1c1 --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=745a4373a2a16df560f216e000000264-1474408334.921985-1480842233 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-20 14:52:15,855 truncating revision to first 12 chars 2016-09-20 14:52:15,855 Setting DEBUG logging. 2016-09-20 14:52:15,855 attempt 1/10 2016-09-20 14:52:15,855 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/e7108b1c6731?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-20 14:52:16,404 unpacking tar archive at: mozilla-aurora-e7108b1c6731/testing/mozharness/ program finished with exit code 0 elapsedTime=0.876694 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 14:52:16.665015) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-20 14:52:16.665333) ========= script_repo_revision: e7108b1c6731f1456ee875ed32e469c5eb7df1c1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-20 14:52:16.665723) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-20 14:52:16.666010) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-20 14:52:16.675691) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 32 secs) (at 2016-09-20 14:52:16.675957) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=745a4373a2a16df560f216e000000264-1474408334.921985-1480842233 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:52:16 INFO - MultiFileLogger online at 20160920 14:52:16 in /builds/slave/test 14:52:16 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 14:52:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:52:16 INFO - {'append_to_log': False, 14:52:16 INFO - 'base_work_dir': '/builds/slave/test', 14:52:16 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 14:52:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:52:16 INFO - 'branch': 'Mozilla-Aurora', 14:52:16 INFO - 'buildbot_json_path': 'buildprops.json', 14:52:16 INFO - 'config_files': ('talos/linux_config.py',), 14:52:16 INFO - 'default_actions': ('clobber', 14:52:16 INFO - 'read-buildbot-config', 14:52:16 INFO - 'download-and-extract', 14:52:16 INFO - 'populate-webroot', 14:52:16 INFO - 'create-virtualenv', 14:52:16 INFO - 'install', 14:52:16 INFO - 'run-tests'), 14:52:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:52:16 INFO - 'download_minidump_stackwalk': True, 14:52:16 INFO - 'download_symbols': 'ondemand', 14:52:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:52:16 INFO - 'tooltool.py': '/tools/tooltool.py', 14:52:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:52:16 INFO - '/tools/misc-python/virtualenv.py')}, 14:52:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:52:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:52:16 INFO - 'installer_path': 'installer.exe', 14:52:16 INFO - 'log_level': 'info', 14:52:16 INFO - 'log_name': 'talos', 14:52:16 INFO - 'log_to_console': True, 14:52:16 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:52:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:52:16 INFO - 'opt_config_files': (), 14:52:16 INFO - 'pip_index': False, 14:52:16 INFO - 'sps_profile': False, 14:52:16 INFO - 'sps_profile_interval': 0, 14:52:16 INFO - 'suite': 'dromaeojs', 14:52:16 INFO - 'system_bits': '32', 14:52:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:52:16 INFO - 'title': 'talos-linux64-ix-032', 14:52:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:52:16 INFO - 'use_talos_json': True, 14:52:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:52:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:52:16 INFO - 'work_dir': 'build'} 14:52:16 INFO - [mozharness: 2016-09-20 21:52:16.853866Z] Running clobber step. 14:52:16 INFO - Running pre-action listener: _resource_record_pre_action 14:52:16 INFO - Running main action method: clobber 14:52:16 INFO - rmtree: /builds/slave/test/build 14:52:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:52:17 INFO - Running post-action listener: _resource_record_post_action 14:52:17 INFO - [mozharness: 2016-09-20 21:52:17.309489Z] Finished clobber step (success) 14:52:17 INFO - [mozharness: 2016-09-20 21:52:17.309591Z] Running read-buildbot-config step. 14:52:17 INFO - Running pre-action listener: _resource_record_pre_action 14:52:17 INFO - Running main action method: read_buildbot_config 14:52:17 INFO - Using buildbot properties: 14:52:17 INFO - { 14:52:17 INFO - "project": "", 14:52:17 INFO - "product": "firefox", 14:52:17 INFO - "script_repo_revision": "production", 14:52:17 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 14:52:17 INFO - "repository": "", 14:52:17 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 14:52:17 INFO - "buildid": "20160920142006", 14:52:17 INFO - "stage_platform": "linux64-pgo", 14:52:17 INFO - "basedir": "/builds/slave/test", 14:52:17 INFO - "buildnumber": 773, 14:52:17 INFO - "platform": "ubuntu64_hw", 14:52:17 INFO - "mozci_request": { 14:52:17 INFO - "priority": -1, 14:52:17 INFO - "type": "trigger_all_talos_jobs", 14:52:17 INFO - "times": 6 14:52:17 INFO - }, 14:52:17 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 14:52:17 INFO - "slavebuilddir": "test", 14:52:17 INFO - "branch": "mozilla-aurora", 14:52:17 INFO - "revision": "e7108b1c6731f1456ee875ed32e469c5eb7df1c1", 14:52:17 INFO - "slavename": "talos-linux64-ix-032", 14:52:17 INFO - "builduid": "de47eebdb617430296736d3ef1c8bef3", 14:52:17 INFO - "repo_path": "releases/mozilla-aurora" 14:52:17 INFO - } 14:52:17 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474391126/firefox-51.0a2.en-US.linux-x86_64.tar.bz2. 14:52:17 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474391126/firefox-51.0a2.en-US.linux-x86_64.test_packages.json. 14:52:17 INFO - Running post-action listener: _resource_record_post_action 14:52:17 INFO - [mozharness: 2016-09-20 21:52:17.312378Z] Finished read-buildbot-config step (success) 14:52:17 INFO - [mozharness: 2016-09-20 21:52:17.312469Z] Running download-and-extract step. 14:52:17 INFO - Running pre-action listener: _resource_record_pre_action 14:52:17 INFO - Running main action method: download_and_extract 14:52:17 INFO - mkdir: /builds/slave/test/build/tests 14:52:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:52:17 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474391126/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 14:52:17 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474391126/firefox-51.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 14:52:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474391126/firefox-51.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 14:52:17 INFO - Downloaded 1361 bytes. 14:52:17 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 14:52:17 INFO - Using the following test package requirements: 14:52:17 INFO - {u'common': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 14:52:17 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 14:52:17 INFO - u'firefox-51.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 14:52:17 INFO - u'gtest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 14:52:17 INFO - u'firefox-51.0a2.en-US.linux-x86_64.gtest.tests.zip'], 14:52:17 INFO - u'jittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 14:52:17 INFO - u'jsshell-linux-x86_64.zip'], 14:52:17 INFO - u'mochitest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 14:52:17 INFO - u'firefox-51.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 14:52:17 INFO - u'mozbase': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 14:52:17 INFO - u'reftest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 14:52:17 INFO - u'firefox-51.0a2.en-US.linux-x86_64.reftest.tests.zip'], 14:52:17 INFO - u'talos': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 14:52:17 INFO - u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'], 14:52:17 INFO - u'web-platform': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 14:52:17 INFO - u'firefox-51.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 14:52:17 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 14:52:17 INFO - u'firefox-51.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 14:52:17 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 14:52:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474391126/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 14:52:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474391126/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:52:17 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474391126/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 14:52:17 INFO - Expected file size: 21894243 14:52:21 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 14:52:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474391126/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 14:52:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474391126/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:52:21 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474391126/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 14:52:21 INFO - Expected file size: 21894243 14:52:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474391126/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip 14:52:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474391126/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 14:52:26 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474391126/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip into memory 14:52:26 INFO - Expected file size: 14414059 14:52: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')]} 14:52:26 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474391126/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 14:52:26 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474391126/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 14:52:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474391126/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 14:52:27 INFO - Downloaded 63971086 bytes. 14:52:27 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474391126/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 14:52:27 INFO - mkdir: /builds/slave/test/properties 14:52:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:52:27 INFO - Writing to file /builds/slave/test/properties/build_url 14:52:27 INFO - Contents: 14:52:27 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474391126/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 14:52:27 INFO - Running post-action listener: _resource_record_post_action 14:52:27 INFO - Running post-action listener: set_extra_try_arguments 14:52:27 INFO - [mozharness: 2016-09-20 21:52:27.907454Z] Finished download-and-extract step (success) 14:52:27 INFO - [mozharness: 2016-09-20 21:52:27.907685Z] Running populate-webroot step. 14:52:27 INFO - Running pre-action listener: _resource_record_pre_action 14:52:27 INFO - Running main action method: populate_webroot 14:52:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:52:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:52:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:52:27 INFO - '--tppagecycles', 14:52:27 INFO - '7'], 14:52:27 INFO - 'tests': ['tsvgm']}}, 14:52:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:52:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:52:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:52:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:52:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:52:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:52:27 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:52:27 INFO - 'talos_options': ['--disable-e10s'], 14:52:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:52:27 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:52:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:52:27 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:52:27 INFO - 'talos_options': ['--disable-e10s'], 14:52:27 INFO - 'tests': ['damp', 'tps']}, 14:52:27 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:52:27 INFO - 'tests': ['damp', 'tps']}, 14:52:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:52:27 INFO - 'tests': ['dromaeo_dom']}, 14:52:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:52:27 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:52:27 INFO - 'tests': ['basic_compositor_video']}, 14:52:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:52:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:52:27 INFO - 'tests': ['a11yr', 14:52:27 INFO - 'ts_paint', 14:52:27 INFO - 'tpaint', 14:52:27 INFO - 'sessionrestore', 14:52:27 INFO - 'sessionrestore_no_auto_restore', 14:52:27 INFO - 'tabpaint']}, 14:52:27 INFO - 'other-e10s': {'tests': ['a11yr', 14:52:27 INFO - 'ts_paint', 14:52:27 INFO - 'tpaint', 14:52:27 INFO - 'sessionrestore', 14:52:27 INFO - 'sessionrestore_no_auto_restore', 14:52:27 INFO - 'tabpaint']}, 14:52:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:52:27 INFO - 'ts_paint', 14:52:27 INFO - 'tpaint', 14:52:27 INFO - 'sessionrestore', 14:52:27 INFO - 'sessionrestore_no_auto_restore', 14:52:27 INFO - 'tabpaint']}, 14:52:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:52:27 INFO - 'ts_paint', 14:52:27 INFO - 'tpaint', 14:52:27 INFO - 'sessionrestore', 14:52:27 INFO - 'sessionrestore_no_auto_restore', 14:52:27 INFO - 'tabpaint']}, 14:52:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:52:27 INFO - 'tests': ['a11yr', 14:52:27 INFO - 'ts_paint', 14:52:27 INFO - 'tpaint', 14:52:27 INFO - 'sessionrestore', 14:52:27 INFO - 'sessionrestore_no_auto_restore', 14:52:27 INFO - 'tabpaint']}, 14:52:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:52:27 INFO - 'tests': ['a11yr', 14:52:27 INFO - 'ts_paint', 14:52:27 INFO - 'tpaint', 14:52:27 INFO - 'sessionrestore', 14:52:27 INFO - 'sessionrestore_no_auto_restore', 14:52:27 INFO - 'tabpaint']}, 14:52:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:52:27 INFO - 'tests': ['tsvgx', 14:52:27 INFO - 'tsvgr_opacity', 14:52:27 INFO - 'tart', 14:52:27 INFO - 'tscrollx', 14:52:27 INFO - 'cart']}, 14:52:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:52:27 INFO - 'tsvgr_opacity', 14:52:27 INFO - 'tart', 14:52:27 INFO - 'tscrollx', 14:52:27 INFO - 'cart']}, 14:52:27 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:52:27 INFO - 'talos_options': ['--disable-e10s'], 14:52:27 INFO - 'tests': ['tp5o']}, 14:52:27 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:52:27 INFO - 'tests': ['tp5o']}, 14:52:27 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:52:27 INFO - 'talos_options': ['--disable-e10s', 14:52:27 INFO - '--xperf_path', 14:52:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:52:27 INFO - 'tests': ['tp5n']}, 14:52:27 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:52:27 INFO - 'talos_options': ['--xperf_path', 14:52:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:52:27 INFO - 'tests': ['tp5n']}}, 14:52:27 INFO - 'talos.zip': {'path': '', 14:52:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:52:27 INFO - Running post-action listener: _resource_record_post_action 14:52:27 INFO - [mozharness: 2016-09-20 21:52:27.926826Z] Finished populate-webroot step (success) 14:52:27 INFO - [mozharness: 2016-09-20 21:52:27.926916Z] Running create-virtualenv step. 14:52:27 INFO - Running pre-action listener: _resource_record_pre_action 14:52:27 INFO - Running main action method: create_virtualenv 14:52:27 INFO - Creating virtualenv /builds/slave/test/build/venv 14:52:27 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 14:52:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:52:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:52:28 INFO - Using real prefix '/usr' 14:52:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:52:28 INFO - Installing distribute.............................................................................................................................................................................................done. 14:52:30 INFO - Installing pip.................done. 14:52:30 INFO - Return code: 0 14:52:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:52:30 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')]} 14:52:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:52:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:52:30 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')]} 14:52:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:52:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:52:30 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 0x7f5122c5bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e428a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f0fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f0cf60>, '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 0x1f0dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f0e1d0>, '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': '745a4373a2a16df560f216e000000264-1474408334.921985-1480842233', '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 14:52:30 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 14:52:30 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 14:52:30 INFO - Using env: {'DISPLAY': ':0', 14:52:30 INFO - 'HOME': '/home/cltbld', 14:52:30 INFO - 'LANG': 'en_US.UTF-8', 14:52:30 INFO - 'LANGUAGE': 'en_US:en', 14:52:30 INFO - 'LOGNAME': 'cltbld', 14:52:30 INFO - 'MAIL': '/var/mail/cltbld', 14:52:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:52:30 INFO - 'MOZ_NO_REMOTE': '1', 14:52:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:52:30 INFO - 'NO_EM_RESTART': '1', 14:52:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:52:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:52:30 INFO - 'PWD': '/builds/slave/test', 14:52:30 INFO - 'SHELL': '/bin/bash', 14:52:30 INFO - 'SHLVL': '1', 14:52:30 INFO - 'TERM': 'linux', 14:52:30 INFO - 'TMOUT': '86400', 14:52:30 INFO - 'USER': 'cltbld', 14:52:30 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1474408334.921985-1480842233', 14:52:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:52:30 INFO - '_': '/tools/buildbot/bin/python'} 14:52:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:52:30 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 14:52:30 INFO - Cleaning up... 14:52:30 INFO - Return code: 0 14:52:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:52:30 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')]} 14:52:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:52:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:52:30 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')]} 14:52:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:52:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:52:30 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 0x7f5122c5bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e428a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f0fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f0cf60>, '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 0x1f0dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f0e1d0>, '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': '745a4373a2a16df560f216e000000264-1474408334.921985-1480842233', '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 14:52:30 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 14:52:30 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 14:52:30 INFO - Using env: {'DISPLAY': ':0', 14:52:30 INFO - 'HOME': '/home/cltbld', 14:52:30 INFO - 'LANG': 'en_US.UTF-8', 14:52:30 INFO - 'LANGUAGE': 'en_US:en', 14:52:30 INFO - 'LOGNAME': 'cltbld', 14:52:30 INFO - 'MAIL': '/var/mail/cltbld', 14:52:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:52:30 INFO - 'MOZ_NO_REMOTE': '1', 14:52:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:52:30 INFO - 'NO_EM_RESTART': '1', 14:52:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:52:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:52:30 INFO - 'PWD': '/builds/slave/test', 14:52:30 INFO - 'SHELL': '/bin/bash', 14:52:30 INFO - 'SHLVL': '1', 14:52:30 INFO - 'TERM': 'linux', 14:52:30 INFO - 'TMOUT': '86400', 14:52:30 INFO - 'USER': 'cltbld', 14:52:30 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1474408334.921985-1480842233', 14:52:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:52:30 INFO - '_': '/tools/buildbot/bin/python'} 14:52:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:52:30 INFO - Downloading/unpacking psutil>=3.1.1 14:52:30 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 14:52:30 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 14:52:30 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 14:52:30 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 14:52:30 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 14:52:30 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 14:52:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:52:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:52:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:52:32 INFO - Installing collected packages: psutil 14:52:32 INFO - Running setup.py install for psutil 14:52:32 INFO - building 'psutil._psutil_linux' extension 14:52:32 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 14:52:33 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 14:52:33 INFO - building 'psutil._psutil_posix' extension 14:52:33 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 14:52:33 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 14:52:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:52:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:52:33 INFO - Successfully installed psutil 14:52:33 INFO - Cleaning up... 14:52:33 INFO - Return code: 0 14:52:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:52:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:52:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:52:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:52:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:52:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:52:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:52:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f5122c5bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e428a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f0fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f0cf60>, '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 0x1f0dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f0e1d0>, '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': '745a4373a2a16df560f216e000000264-1474408334.921985-1480842233', '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 14:52:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:52:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:52:33 INFO - Using env: {'DISPLAY': ':0', 14:52:33 INFO - 'HOME': '/home/cltbld', 14:52:33 INFO - 'LANG': 'en_US.UTF-8', 14:52:33 INFO - 'LANGUAGE': 'en_US:en', 14:52:33 INFO - 'LOGNAME': 'cltbld', 14:52:33 INFO - 'MAIL': '/var/mail/cltbld', 14:52:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:52:33 INFO - 'MOZ_NO_REMOTE': '1', 14:52:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:52:33 INFO - 'NO_EM_RESTART': '1', 14:52:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:52:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:52:33 INFO - 'PWD': '/builds/slave/test', 14:52:33 INFO - 'SHELL': '/bin/bash', 14:52:33 INFO - 'SHLVL': '1', 14:52:33 INFO - 'TERM': 'linux', 14:52:33 INFO - 'TMOUT': '86400', 14:52:33 INFO - 'USER': 'cltbld', 14:52:33 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1474408334.921985-1480842233', 14:52:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:52:33 INFO - '_': '/tools/buildbot/bin/python'} 14:52:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:52:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:52:33 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 14:52:33 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 14:52:33 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 14:52:33 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 14:52:33 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 14:52:33 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 14:52:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:52:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:52:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:52:35 INFO - Installing collected packages: mozsystemmonitor 14:52:35 INFO - Running setup.py install for mozsystemmonitor 14:52:35 INFO - Successfully installed mozsystemmonitor 14:52:35 INFO - Cleaning up... 14:52:35 INFO - Return code: 0 14:52:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:52:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:52:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:52:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:52:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:52:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:52:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:52:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f5122c5bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e428a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f0fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f0cf60>, '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 0x1f0dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f0e1d0>, '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': '745a4373a2a16df560f216e000000264-1474408334.921985-1480842233', '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 14:52:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 14:52:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 14:52:35 INFO - Using env: {'DISPLAY': ':0', 14:52:35 INFO - 'HOME': '/home/cltbld', 14:52:35 INFO - 'LANG': 'en_US.UTF-8', 14:52:35 INFO - 'LANGUAGE': 'en_US:en', 14:52:35 INFO - 'LOGNAME': 'cltbld', 14:52:35 INFO - 'MAIL': '/var/mail/cltbld', 14:52:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:52:35 INFO - 'MOZ_NO_REMOTE': '1', 14:52:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:52:35 INFO - 'NO_EM_RESTART': '1', 14:52:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:52:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:52:35 INFO - 'PWD': '/builds/slave/test', 14:52:35 INFO - 'SHELL': '/bin/bash', 14:52:35 INFO - 'SHLVL': '1', 14:52:35 INFO - 'TERM': 'linux', 14:52:35 INFO - 'TMOUT': '86400', 14:52:35 INFO - 'USER': 'cltbld', 14:52:35 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1474408334.921985-1480842233', 14:52:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:52:35 INFO - '_': '/tools/buildbot/bin/python'} 14:52:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:52:35 INFO - Downloading/unpacking jsonschema==2.5.1 14:52:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:52:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:52:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:52:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:52:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:52:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:52:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:52:37 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:52: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 14:52: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 14:52: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 14:52: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 14:52: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 14:52: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 14:52:38 INFO - Downloading functools32-3.2.3-2.tar.gz 14:52:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:52:38 INFO - warning: no files found matching '*.txt' 14:52:38 INFO - no previously-included directories found matching 'build' 14:52:38 INFO - no previously-included directories found matching 'dist' 14:52:38 INFO - no previously-included directories found matching '.git*' 14:52:38 INFO - Installing collected packages: jsonschema, functools32 14:52:38 INFO - Running setup.py install for functools32 14:52:38 INFO - warning: no files found matching '*.txt' 14:52:38 INFO - no previously-included directories found matching 'build' 14:52:38 INFO - no previously-included directories found matching 'dist' 14:52:38 INFO - no previously-included directories found matching '.git*' 14:52:38 INFO - Successfully installed jsonschema functools32 14:52:38 INFO - Cleaning up... 14:52:38 INFO - Return code: 0 14:52:38 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:52:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:52:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:52:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:52:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:52:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:52:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:52:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f5122c5bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e428a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f0fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f0cf60>, '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 0x1f0dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f0e1d0>, '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': '745a4373a2a16df560f216e000000264-1474408334.921985-1480842233', '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 14:52:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 14:52:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 14:52:38 INFO - Using env: {'DISPLAY': ':0', 14:52:38 INFO - 'HOME': '/home/cltbld', 14:52:38 INFO - 'LANG': 'en_US.UTF-8', 14:52:38 INFO - 'LANGUAGE': 'en_US:en', 14:52:38 INFO - 'LOGNAME': 'cltbld', 14:52:38 INFO - 'MAIL': '/var/mail/cltbld', 14:52:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:52:38 INFO - 'MOZ_NO_REMOTE': '1', 14:52:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:52:38 INFO - 'NO_EM_RESTART': '1', 14:52:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:52:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:52:38 INFO - 'PWD': '/builds/slave/test', 14:52:38 INFO - 'SHELL': '/bin/bash', 14:52:38 INFO - 'SHLVL': '1', 14:52:38 INFO - 'TERM': 'linux', 14:52:38 INFO - 'TMOUT': '86400', 14:52:38 INFO - 'USER': 'cltbld', 14:52:38 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1474408334.921985-1480842233', 14:52:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:52:38 INFO - '_': '/tools/buildbot/bin/python'} 14:52:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:52:38 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:52:38 INFO - Cleaning up... 14:52:38 INFO - Return code: 0 14:52:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:52:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:52:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:52:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:52:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:52:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:52:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:52:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f5122c5bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e428a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f0fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f0cf60>, '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 0x1f0dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f0e1d0>, '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': '745a4373a2a16df560f216e000000264-1474408334.921985-1480842233', '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 14:52:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:52:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 14:52:38 INFO - Using env: {'DISPLAY': ':0', 14:52:38 INFO - 'HOME': '/home/cltbld', 14:52:38 INFO - 'LANG': 'en_US.UTF-8', 14:52:38 INFO - 'LANGUAGE': 'en_US:en', 14:52:38 INFO - 'LOGNAME': 'cltbld', 14:52:38 INFO - 'MAIL': '/var/mail/cltbld', 14:52:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:52:38 INFO - 'MOZ_NO_REMOTE': '1', 14:52:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:52:38 INFO - 'NO_EM_RESTART': '1', 14:52:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:52:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:52:38 INFO - 'PWD': '/builds/slave/test', 14:52:38 INFO - 'SHELL': '/bin/bash', 14:52:38 INFO - 'SHLVL': '1', 14:52:38 INFO - 'TERM': 'linux', 14:52:38 INFO - 'TMOUT': '86400', 14:52:38 INFO - 'USER': 'cltbld', 14:52:38 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1474408334.921985-1480842233', 14:52:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:52:38 INFO - '_': '/tools/buildbot/bin/python'} 14:52:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:52:38 INFO - Downloading/unpacking blobuploader==1.2.4 14:52: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 14:52: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 14:52: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 14:52: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 14:52: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 14:52: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 14:52:40 INFO - Downloading blobuploader-1.2.4.tar.gz 14:52:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:52:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:52:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:52:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:52:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:52:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:52:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:52:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:52:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:52:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:52: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 14:52: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 14:52: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 14:52: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 14:52: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 14:52: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 14:52:41 INFO - Downloading docopt-0.6.1.tar.gz 14:52:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:52:41 INFO - Installing collected packages: blobuploader, requests, docopt 14:52:41 INFO - Running setup.py install for blobuploader 14:52:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:52:41 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:52:41 INFO - Running setup.py install for requests 14:52:41 INFO - Running setup.py install for docopt 14:52:41 INFO - Successfully installed blobuploader requests docopt 14:52:41 INFO - Cleaning up... 14:52:42 INFO - Return code: 0 14:52:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:52:42 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')]} 14:52:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:52:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:52:42 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')]} 14:52:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:52:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:52:42 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 0x7f5122c5bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e428a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f0fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f0cf60>, '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 0x1f0dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f0e1d0>, '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': '745a4373a2a16df560f216e000000264-1474408334.921985-1480842233', '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 14:52:42 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 14:52:42 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 14:52:42 INFO - Using env: {'DISPLAY': ':0', 14:52:42 INFO - 'HOME': '/home/cltbld', 14:52:42 INFO - 'LANG': 'en_US.UTF-8', 14:52:42 INFO - 'LANGUAGE': 'en_US:en', 14:52:42 INFO - 'LOGNAME': 'cltbld', 14:52:42 INFO - 'MAIL': '/var/mail/cltbld', 14:52:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:52:42 INFO - 'MOZ_NO_REMOTE': '1', 14:52:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:52:42 INFO - 'NO_EM_RESTART': '1', 14:52:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:52:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:52:42 INFO - 'PWD': '/builds/slave/test', 14:52:42 INFO - 'SHELL': '/bin/bash', 14:52:42 INFO - 'SHLVL': '1', 14:52:42 INFO - 'TERM': 'linux', 14:52:42 INFO - 'TMOUT': '86400', 14:52:42 INFO - 'USER': 'cltbld', 14:52:42 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1474408334.921985-1480842233', 14:52:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:52:42 INFO - '_': '/tools/buildbot/bin/python'} 14:52:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:52:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:52:42 INFO - Running setup.py (path:/tmp/pip-OCFmDK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:52:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:52:42 INFO - Running setup.py (path:/tmp/pip-BN9qx8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:52:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:52:42 INFO - Running setup.py (path:/tmp/pip-xoRyiP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:52:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:52:42 INFO - Running setup.py (path:/tmp/pip-N2tbeT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:52:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:52:42 INFO - Running setup.py (path:/tmp/pip-SKdOO8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:52:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:52:42 INFO - Running setup.py (path:/tmp/pip-HuXHEI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:52:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:52:42 INFO - Running setup.py (path:/tmp/pip-N2E2E3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:52:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:52:42 INFO - Running setup.py (path:/tmp/pip-v3tsFd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:52:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:52:42 INFO - Running setup.py (path:/tmp/pip-Hbjpj3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:52:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:52:42 INFO - Running setup.py (path:/tmp/pip-cs9URB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:52:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:52:42 INFO - Running setup.py (path:/tmp/pip-yyv1eP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:52:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:52:42 INFO - Running setup.py (path:/tmp/pip-4pap11-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:52:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:52:43 INFO - Running setup.py (path:/tmp/pip-wc0Wq6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:52:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:52:43 INFO - Running setup.py (path:/tmp/pip-v19lLs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:52:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:52:43 INFO - Running setup.py (path:/tmp/pip-tXdex4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:52:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:52:43 INFO - Running setup.py (path:/tmp/pip-8FSOcx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:52:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:52:43 INFO - Running setup.py (path:/tmp/pip-r3NFuO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:52:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:52:43 INFO - Running setup.py install for manifestparser 14:52:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:52:43 INFO - Running setup.py install for mozcrash 14:52:43 INFO - Running setup.py install for mozdebug 14:52:43 INFO - Running setup.py install for mozdevice 14:52:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:52:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:52:43 INFO - Running setup.py install for mozfile 14:52:43 INFO - Running setup.py install for mozhttpd 14:52:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:52:44 INFO - Running setup.py install for mozinfo 14:52:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:52:44 INFO - Running setup.py install for mozInstall 14:52:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:52:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:52:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:52:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:52:44 INFO - Running setup.py install for mozleak 14:52:44 INFO - Running setup.py install for mozlog 14:52:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:52:44 INFO - Running setup.py install for moznetwork 14:52:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:52:44 INFO - Running setup.py install for mozprocess 14:52:44 INFO - Running setup.py install for mozprofile 14:52:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:52:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:52:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:52:44 INFO - Running setup.py install for mozrunner 14:52:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:52:44 INFO - Running setup.py install for mozscreenshot 14:52:44 INFO - Running setup.py install for moztest 14:52:45 INFO - Running setup.py install for mozversion 14:52:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:52:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:52:45 INFO - Cleaning up... 14:52:45 INFO - Return code: 0 14:52:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:52:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:52:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:52:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:52:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:52:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:52:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:52:45 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 0x7f5122c5bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e428a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f0fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f0cf60>, '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 0x1f0dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f0e1d0>, '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': '745a4373a2a16df560f216e000000264-1474408334.921985-1480842233', '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 14:52:45 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 14:52:45 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 14:52:45 INFO - Using env: {'DISPLAY': ':0', 14:52:45 INFO - 'HOME': '/home/cltbld', 14:52:45 INFO - 'LANG': 'en_US.UTF-8', 14:52:45 INFO - 'LANGUAGE': 'en_US:en', 14:52:45 INFO - 'LOGNAME': 'cltbld', 14:52:45 INFO - 'MAIL': '/var/mail/cltbld', 14:52:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:52:45 INFO - 'MOZ_NO_REMOTE': '1', 14:52:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:52:45 INFO - 'NO_EM_RESTART': '1', 14:52:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:52:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:52:45 INFO - 'PWD': '/builds/slave/test', 14:52:45 INFO - 'SHELL': '/bin/bash', 14:52:45 INFO - 'SHLVL': '1', 14:52:45 INFO - 'TERM': 'linux', 14:52:45 INFO - 'TMOUT': '86400', 14:52:45 INFO - 'USER': 'cltbld', 14:52:45 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1474408334.921985-1480842233', 14:52:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:52:45 INFO - '_': '/tools/buildbot/bin/python'} 14:52:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:52:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:52:45 INFO - Running setup.py (path:/tmp/pip-fEKtBW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:52:45 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)) 14:52:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:52:45 INFO - Running setup.py (path:/tmp/pip-kqTGxP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:52:45 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)) 14:52:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:52:45 INFO - Running setup.py (path:/tmp/pip-WEWzJS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:52:45 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)) 14:52:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:52:45 INFO - Running setup.py (path:/tmp/pip-DADH5Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:52:45 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)) 14:52:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:52:45 INFO - Running setup.py (path:/tmp/pip-g_wGZF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:52:45 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)) 14:52:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:52:45 INFO - Running setup.py (path:/tmp/pip-yvy0Dl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:52:45 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)) 14:52:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:52:45 INFO - Running setup.py (path:/tmp/pip-PGgHJo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:52:45 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)) 14:52:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:52:45 INFO - Running setup.py (path:/tmp/pip-FXmn5K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:52:45 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)) 14:52:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:52:45 INFO - Running setup.py (path:/tmp/pip-YUXOK6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:52:46 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)) 14:52:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:52:46 INFO - Running setup.py (path:/tmp/pip-GVd5rP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:52:46 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)) 14:52:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:52:46 INFO - Running setup.py (path:/tmp/pip-k57JMr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:52:46 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)) 14:52:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:52:46 INFO - Running setup.py (path:/tmp/pip-btyCls-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:52:46 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)) 14:52:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:52:46 INFO - Running setup.py (path:/tmp/pip-SbTfXz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:52:46 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)) 14:52:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:52:46 INFO - Running setup.py (path:/tmp/pip-9Pk0AT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:52:46 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)) 14:52:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:52:46 INFO - Running setup.py (path:/tmp/pip-4FoSfY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:52:46 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)) 14:52:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:52:46 INFO - Running setup.py (path:/tmp/pip-b_1E5g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:52:46 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)) 14:52:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:52:46 INFO - Running setup.py (path:/tmp/pip-SHU6Rw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:52:46 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)) 14:52:46 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)) 14:52:46 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)) 14:52:46 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)) 14:52:46 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)) 14:52:46 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)) 14:52:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:52:46 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 14:52:46 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 14:52:46 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 14:52:46 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 14:52:46 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 14:52:46 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 14:52:48 INFO - Downloading blessings-1.6.tar.gz 14:52:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:52:48 INFO - Installing collected packages: blessings 14:52:48 INFO - Running setup.py install for blessings 14:52:48 INFO - Successfully installed blessings 14:52:48 INFO - Cleaning up... 14:52:48 INFO - Return code: 0 14:52:48 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:52:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:52:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:52:48 INFO - Reading from file tmpfile_stdout 14:52:48 INFO - Current package versions: 14:52:48 INFO - argparse == 1.2.1 14:52:48 INFO - blessings == 1.6 14:52:48 INFO - blobuploader == 1.2.4 14:52:48 INFO - docopt == 0.6.1 14:52:48 INFO - functools32 == 3.2.3-2 14:52:48 INFO - jsonschema == 2.5.1 14:52:48 INFO - manifestparser == 1.1 14:52:48 INFO - mozInstall == 1.12 14:52:48 INFO - mozcrash == 0.17 14:52:48 INFO - mozdebug == 0.1 14:52:48 INFO - mozdevice == 0.48 14:52:48 INFO - mozfile == 1.2 14:52:48 INFO - mozhttpd == 0.7 14:52:48 INFO - mozinfo == 0.9 14:52:48 INFO - mozleak == 0.1 14:52:48 INFO - mozlog == 3.4 14:52:48 INFO - moznetwork == 0.27 14:52:48 INFO - mozprocess == 0.23 14:52:48 INFO - mozprofile == 0.28 14:52:48 INFO - mozrunner == 6.12 14:52:48 INFO - mozscreenshot == 0.1 14:52:48 INFO - mozsystemmonitor == 0.3 14:52:48 INFO - moztest == 0.7 14:52:48 INFO - mozversion == 1.4 14:52:48 INFO - psutil == 3.1.1 14:52:48 INFO - requests == 1.2.3 14:52:48 INFO - wsgiref == 0.1.2 14:52:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:52:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:52:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:52:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:52:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:52:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:52:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:52:48 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 0x7f5122c5bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e428a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f0fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f0cf60>, '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 0x1f0dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f0e1d0>, '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': '745a4373a2a16df560f216e000000264-1474408334.921985-1480842233', '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 14:52:48 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 14:52:48 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 14:52:48 INFO - Using env: {'DISPLAY': ':0', 14:52:48 INFO - 'HOME': '/home/cltbld', 14:52:48 INFO - 'LANG': 'en_US.UTF-8', 14:52:48 INFO - 'LANGUAGE': 'en_US:en', 14:52:48 INFO - 'LOGNAME': 'cltbld', 14:52:48 INFO - 'MAIL': '/var/mail/cltbld', 14:52:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:52:48 INFO - 'MOZ_NO_REMOTE': '1', 14:52:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:52:48 INFO - 'NO_EM_RESTART': '1', 14:52:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:52:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:52:48 INFO - 'PWD': '/builds/slave/test', 14:52:48 INFO - 'SHELL': '/bin/bash', 14:52:48 INFO - 'SHLVL': '1', 14:52:48 INFO - 'TERM': 'linux', 14:52:48 INFO - 'TMOUT': '86400', 14:52:48 INFO - 'USER': 'cltbld', 14:52:48 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1474408334.921985-1480842233', 14:52:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:52:48 INFO - '_': '/tools/buildbot/bin/python'} 14:52:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:52:49 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)) 14:52:49 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)) 14:52:49 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)) 14:52:49 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)) 14:52:49 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)) 14:52:49 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)) 14:52:49 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)) 14:52:49 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)) 14:52:49 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)) 14:52:49 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)) 14:52:49 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)) 14:52:49 INFO - Cleaning up... 14:52:49 INFO - Return code: 0 14:52:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:52:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:52:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:52:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:52:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:52:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:52:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:52:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f5122c5bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e428a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f0fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f0cf60>, '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 0x1f0dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f0e1d0>, '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': '745a4373a2a16df560f216e000000264-1474408334.921985-1480842233', '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 14:52:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 14:52:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:52:49 INFO - Using env: {'DISPLAY': ':0', 14:52:49 INFO - 'HOME': '/home/cltbld', 14:52:49 INFO - 'LANG': 'en_US.UTF-8', 14:52:49 INFO - 'LANGUAGE': 'en_US:en', 14:52:49 INFO - 'LOGNAME': 'cltbld', 14:52:49 INFO - 'MAIL': '/var/mail/cltbld', 14:52:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:52:49 INFO - 'MOZ_NO_REMOTE': '1', 14:52:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:52:49 INFO - 'NO_EM_RESTART': '1', 14:52:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:52:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:52:49 INFO - 'PWD': '/builds/slave/test', 14:52:49 INFO - 'SHELL': '/bin/bash', 14:52:49 INFO - 'SHLVL': '1', 14:52:49 INFO - 'TERM': 'linux', 14:52:49 INFO - 'TMOUT': '86400', 14:52:49 INFO - 'USER': 'cltbld', 14:52:49 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1474408334.921985-1480842233', 14:52:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:52:49 INFO - '_': '/tools/buildbot/bin/python'} 14:52:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:52:49 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:52:49 INFO - Cleaning up... 14:52:49 INFO - Return code: 0 14:52:49 INFO - Running post-action listener: _resource_record_post_action 14:52:49 INFO - Running post-action listener: _start_resource_monitoring 14:52:49 INFO - Starting resource monitoring. 14:52:49 INFO - [mozharness: 2016-09-20 21:52:49.448244Z] Finished create-virtualenv step (success) 14:52:49 INFO - [mozharness: 2016-09-20 21:52:49.448834Z] Running install step. 14:52:49 INFO - Running pre-action listener: _resource_record_pre_action 14:52:49 INFO - Running main action method: install 14:52:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:52:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:52:49 INFO - Reading from file tmpfile_stdout 14:52:49 INFO - Detecting whether we're running mozinstall >=1.0... 14:52:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:52:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:52:49 INFO - Reading from file tmpfile_stdout 14:52:49 INFO - Output received: 14:52:49 INFO - Usage: mozinstall [options] installer 14:52:49 INFO - Options: 14:52:49 INFO - -h, --help show this help message and exit 14:52:49 INFO - -d DEST, --destination=DEST 14:52:49 INFO - Directory to install application into. [default: 14:52:49 INFO - "/builds/slave/test"] 14:52:49 INFO - --app=APP Application being installed. [default: firefox] 14:52:49 INFO - mkdir: /builds/slave/test/build/application 14:52:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 14:52:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 14:53:05 INFO - Reading from file tmpfile_stdout 14:53:05 INFO - Output received: 14:53:05 INFO - /builds/slave/test/build/application/firefox/firefox 14:53:05 INFO - Running post-action listener: _resource_record_post_action 14:53:05 INFO - [mozharness: 2016-09-20 21:53:05.738468Z] Finished install step (success) 14:53:05 INFO - [mozharness: 2016-09-20 21:53:05.738714Z] Running run-tests step. 14:53:05 INFO - Running pre-action listener: _resource_record_pre_action 14:53:05 INFO - Running main action method: run_tests 14:53:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:53:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:53:05 INFO - Python 2.7.3 14:53:05 INFO - Return code: 0 14:53:05 INFO - grabbing minidump binary from tooltool 14:53:05 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')]} 14:53:05 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 0x1f0cf60>, '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 0x1f0dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f0e1d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:53:05 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 14:53:05 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 14:53:05 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 14:53:05 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:53:05 INFO - Return code: 0 14:53:05 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:53:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:53:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:53:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:53:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:53:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474391126/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-032', '--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 14:53:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474391126/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-032 --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 14:53:05 INFO - Using env: {'DISPLAY': ':0', 14:53:05 INFO - 'HOME': '/home/cltbld', 14:53:05 INFO - 'LANG': 'en_US.UTF-8', 14:53:05 INFO - 'LANGUAGE': 'en_US:en', 14:53:05 INFO - 'LOGNAME': 'cltbld', 14:53:05 INFO - 'MAIL': '/var/mail/cltbld', 14:53:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:53:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:53:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:53:05 INFO - 'MOZ_NO_REMOTE': '1', 14:53:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:53:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:53:05 INFO - 'NO_EM_RESTART': '1', 14:53:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:53:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:53:05 INFO - 'PWD': '/builds/slave/test', 14:53:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:53:05 INFO - 'SHELL': '/bin/bash', 14:53:05 INFO - 'SHLVL': '1', 14:53:05 INFO - 'TERM': 'linux', 14:53:05 INFO - 'TMOUT': '86400', 14:53:05 INFO - 'USER': 'cltbld', 14:53:05 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1474408334.921985-1480842233', 14:53:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:53:05 INFO - '_': '/tools/buildbot/bin/python'} 14:53:05 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474391126/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-032', '--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 14:53:06 INFO - mozversion application_buildid: 20160920100526 14:53:06 INFO - mozversion application_changeset: e7108b1c6731f1456ee875ed32e469c5eb7df1c1 14:53:06 INFO - mozversion application_display_name: FirefoxDeveloperEdition 14:53:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:53:06 INFO - mozversion application_name: Firefox 14:53:06 INFO - mozversion application_remotingname: firefox-dev 14:53:06 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:53:06 INFO - mozversion application_vendor: Mozilla 14:53:06 INFO - mozversion application_version: 51.0a2 14:53:06 INFO - mozversion platform_buildid: 20160920100526 14:53:06 INFO - mozversion platform_changeset: e7108b1c6731f1456ee875ed32e469c5eb7df1c1 14:53:06 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:53:06 INFO - mozversion platform_version: 51.0a2 14:53:06 INFO - using testdate: 1474408386 14:53:06 INFO - actual date: 1474408386 14:53:06 INFO - starting webserver on 'localhost:49529' 14:53:06 INFO - SUITE-START | Running 2 tests 14:53:06 INFO - TEST-START | dromaeo_css 14:53:06 INFO - operating with platform_type : linux_ 14:53:06 INFO - Initialising browser for dromaeo_css test... 14:53:06 INFO - TEST-INFO | started process 15101 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwRt_qD/profile http://localhost:49529/getInfo.html) 14:53:10 INFO - PROCESS | 15101 | __metrics Screen width/height:1600/1200 14:53:10 INFO - PROCESS | 15101 | colorDepth:24 14:53:10 INFO - PROCESS | 15101 | Browser inner width/height: 1024/697 14:53:10 INFO - PROCESS | 15101 | __metrics 14:53:11 INFO - PROCESS | 15101 | 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" 14:53:11 INFO - PROCESS | 15101 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:53:12 INFO - PROCESS | 15101 | console.error: PushService: 14:53:12 INFO - PROCESS | 15101 | stateChangeProcessEnqueue: Error transitioning state 14:53:12 INFO - PROCESS | 15101 | UnknownError 14:53:12 INFO - TEST-INFO | 15101: exit 0 14:53:12 INFO - Browser initialized. 14:53:12 INFO - Running cycle 1/1 for dromaeo_css test... 14:53:12 INFO - TEST-INFO | started process 15192 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwRt_qD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:53:24 INFO - PROCESS | 15192 | RSS: Main: 144482304 14:53:24 INFO - PROCESS | 15192 | 14:53:28 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.13355 seconds. 14:53:35 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.00023 seconds. 14:53:37 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.00027 seconds. 14:53:43 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.00015 seconds. 14:53:56 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.00068 seconds. 14:54:02 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.1832 seconds. 14:54:12 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.04929 seconds. 14:54:15 INFO - PROCESS | 15192 | 1474408455745 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] 14:54:37 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 14:54:44 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.04978 seconds. 14:54:54 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.01649 seconds. 14:55:00 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.05067 seconds. 14:55:08 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.03347 seconds. 14:55:44 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.00047 seconds. 14:55:48 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.16665 seconds. 14:55:50 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.11593 seconds. 14:56:05 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.00047 seconds. 14:56:10 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.0165 seconds. 14:56:22 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.0162 seconds. 14:56:25 INFO - PROCESS | 15192 | Cycle 1(1): loaded http://localhost:49529/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49529/tests/dromaeo/cssquery-ext.html) 14:56:26 INFO - PROCESS | 15192 | RSS: Main: 242495488 14:56:26 INFO - PROCESS | 15192 | 14:56:29 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.01641 seconds. 14:57:19 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.00037 seconds. 14:57:28 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.0166 seconds. 14:57:35 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.01702 seconds. 14:57:47 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.00035 seconds. 14:58:51 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.01663 seconds. 14:59:22 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.0006 seconds. 14:59:28 INFO - PROCESS | 15192 | Cycle 1(1): loaded http://localhost:49529/tests/dromaeo/cssquery-ext.html (next: http://localhost:49529/tests/dromaeo/cssquery-jquery.html) 14:59:29 INFO - PROCESS | 15192 | RSS: Main: 234340352 14:59:29 INFO - PROCESS | 15192 | 14:59:34 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.03316 seconds. 14:59:35 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.00031 seconds. 14:59:41 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.00026 seconds. 14:59:47 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.00025 seconds. 14:59:49 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.00043 seconds. 14:59:51 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.00046 seconds. 14:59:59 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.00044 seconds. 15:00:16 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.00024 seconds. 15:00:42 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.00018 seconds. 15:01:00 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.01583 seconds. 15:01:18 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:01:59 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.0003 seconds. 15:02:25 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.00056 seconds. 15:02:31 INFO - PROCESS | 15192 | Cycle 1(1): loaded http://localhost:49529/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49529/tests/dromaeo/cssquery-mootools.html) 15:02:31 INFO - PROCESS | 15192 | RSS: Main: 175341568 15:02:31 INFO - PROCESS | 15192 | 15:02:35 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.01664 seconds. 15:02:52 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.00049 seconds. 15:03:06 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.00017 seconds. 15:03:16 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.0001 seconds. 15:03:34 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.00008 seconds. 15:04:13 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.00023 seconds. 15:04:25 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.00051 seconds. 15:04:35 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.00027 seconds. 15:04:37 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.00029 seconds. 15:04:41 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.00034 seconds. 15:04:47 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.00029 seconds. 15:04:49 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.00032 seconds. 15:05:09 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.00052 seconds. 15:05:33 INFO - PROCESS | 15192 | Cycle 1(1): loaded http://localhost:49529/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49529/tests/dromaeo/cssquery-prototype.html) 15:05:34 INFO - PROCESS | 15192 | RSS: Main: 175030272 15:05:34 INFO - PROCESS | 15192 | 15:05:37 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.04993 seconds. 15:05:44 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.00011 seconds. 15:05:46 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.01699 seconds. 15:05:48 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.00011 seconds. 15:06:10 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.00043 seconds. 15:06:58 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.00043 seconds. 15:07:10 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.01589 seconds. 15:07:21 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.017 seconds. 15:07:24 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.00055 seconds. 15:07:39 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.00016 seconds. 15:07:44 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.00025 seconds. 15:08:06 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.0001 seconds. 15:08:10 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.00017 seconds. 15:08:14 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 15:08:18 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.00008 seconds. 15:08:37 INFO - PROCESS | 15192 | Cycle 1(1): loaded http://localhost:49529/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49529/tests/dromaeo/cssquery-yui.html) 15:08:37 INFO - PROCESS | 15192 | RSS: Main: 176951296 15:08:37 INFO - PROCESS | 15192 | 15:08:43 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.03321 seconds. 15:08:52 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.0168 seconds. 15:08:57 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.01614 seconds. 15:09:06 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.00052 seconds. 15:09:10 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.0002 seconds. 15:09:26 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.00038 seconds. 15:09:34 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.00086 seconds. 15:09:38 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.0005 seconds. 15:09:50 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.01686 seconds. 15:09:56 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.00031 seconds. 15:10:03 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.01592 seconds. 15:10:27 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.00038 seconds. 15:10:29 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.00048 seconds. 15:10:31 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.00009 seconds. 15:10:33 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.0003 seconds. 15:11:11 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.0002 seconds. 15:11:17 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.00029 seconds. 15:11:30 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.0001 seconds. 15:11:32 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.0341 seconds. 15:11:34 INFO - PROCESS | 15192 | [GFX1-]: Refresh driver waiting for the compositor for 1.01663 seconds. 15:11:40 INFO - PROCESS | 15192 | Cycle 1(1): loaded http://localhost:49529/tests/dromaeo/cssquery-yui.html (next: http://localhost:49529/tests/dromaeo/cssquery-dojo.html) 15:11:40 INFO - PROCESS | 15192 | RSS: Main: 256651264 15:11:40 INFO - PROCESS | 15192 | 15:11:40 INFO - PROCESS | 15192 | __start_tp_report 15:11:40 INFO - PROCESS | 15192 | _x_x_mozilla_page_load 15:11:40 INFO - PROCESS | 15192 | _x_x_mozilla_page_load_details 15:11:40 INFO - PROCESS | 15192 | |i|pagename|runs| 15:11:40 INFO - PROCESS | 15192 | |0;dojo.html;3095.3345030818377;3135.608048993876;3137.3568418474206;3163.6203655561335;3180.3798259339424;5596.692181929992;5648.830535083949;5687.061634830258;5698.116791897252;5708.942910570903;5651.463111004458;5710.600257981943;5721.113227449741;5724.685142317181;5761.510271626907;5119.616028797836;5154.587632989371;5168.198929165986;5176.197689358156;5217.295665085222;5028.4971502849785;5032.949670503284;5250.7112108834;5251.975864706384;5267.26258323835;5105.897882042357;5107.9744601277125;5108.208227724708;5154.226865970116;5156.0203561323415;5113.744312784364;5129.846104616849;5177;5184.559312458446;5207.218917162418;4864.1974074277705;5078.339815823956;5096.566791822699;5136.5377116059735;5153.072446959546;5438.809641662523;5473.397926228112;5496.5053145216725;5504.174373843915;5525.972370138124;5900.114982752579;5907.734151963172;5985.461308482214;5990.371011043865;5991.041433370639;5826.359100498942;6023.247094113236;6040.697965101727;6053.697315134225;6055.152278680988;5459.563234941195;5545.722713864331;5593.049181639171;5607.102863541813;5625.46558076982;6134;6143.170671959318;6146.692665366803;6148.385161483816;6165.938340616536;5748.741306641211;5947.613405128653;5952.5833191676165;5961.076033214858;5963.314218864886;5849.912251316234;5851.882962340729;5876.676782776903;5906.645601263852;5915.112733089984;5857.7949771757785;5873.882522349614;5876.294844618673;5878;5882.941170588325;5839.620424672383;5885.264341957255;5896.587238893237;5904.291485021825;5914.6746928918465;4415.425994620743;4455.977720111444;4552.544745525421;4601.056783359403;4619.815207391734;4085.571015043437;4109.301418758759;4116.58834116586;4132.855350062733;4142;5684.971575142098;5687.232223649837;5691.345495268014;5693.373728889819;5729.942700573024;5763.279590051244;5827.125931110282;6003.939960600425;6011.819645410645;6017.909731354033;5731.684757338387;5732.455416735404;5761.39505351932;5763.0202865512965;5766.192733017381;5221.451747566451;5226.3205783248695;5228.555572776357;5228.947710522846;5232.241325007929;5976.551758618034;6032.668203248688;6191.81424557264;6236.18929539157;6249.593776404519;5877.353491115889;5976.372480889444;5999.100134979788;6033.12519684652;6095.908561371494;6189;6216.595921265103;6217.253929528486;6224.1597384352535;6227.564070515202;5645.943540564542;5921.84045116895;5923.881522369442;5930.673812940329;5956.166136740946;5832.300123984979;5925.733341999707;5944.316403613467;5977.641341519523;6059.303180134341;5824.097264923944;5849.034909239927;5867.794627188028;5873.4126587342635;5874.882502350015;5866.472017518444;5878.29460464747;5878.441548052928;5880.176775251382;5883.911741323798;5588.161775733673;5732.7706891723865;5785.797497087582;5817.95276850171;5833.008388573868;5975.462208401264;5975.731092100779;5977.043673012471;5986.2517185351835;5988.820335389945;5539.944600553943;5606.579506536945;5820.534357251325;5828.825135245949;5833.212516310244 15:11:40 INFO - PROCESS | 15192 | |1;ext.html;597.2653636027686;616.8982117950538;639.9488040956724;640.3372509452714;649.4122818848942;34884.30231395374;37809.24381512368;46119.53880461194;46890;47155;48042.51957480424;48702;48730.26904596434;48845.755771221186;48858;9291.11734385234;9530.523473826315;9536.952315238414;9539.47532885691;9544.093311135432;8602.956985215096;8819.118088191162;11074.114070874311;11349.716257093556;11359.772804543903;11405.372704501291;11503.252288601214;11530.711732206679;11536.653900383002;11544.249623774469;8548.615312310898;11313.886861131406;11400.657980260605;11400.71498212543;11424;8311.709090181876;10692.198085143567;11131.554737810478;11158.665240042812;11532.59635912739;45691.08617827658;46095.539044609795;46157.30764038542;46216;46248.768756156;3854.1906199698096;3921.901952451183;3930.410438434229;4167.729097608676;4331.393604895317;34650.82674586611;34874.651253487646;34937.6506234937;35309.47035794465;36329.273414531825;4212.831486740557;4288.356746488021;4390.297552391634;4433.977830110861;4524.072565124142;389241;391911;392113;392279;392986.99999999715;38881.80559097186;39653;39763.80118099391;67625.66187169033;68341;41897.162056758694;42120.78939605282;42122.57877421248;42152.57847421548;42163.7891810539;17174.656506869793;17527.649447011245;17534.12329383526;17562.209700563504;17594.38419655306;13091.607251782461;13392.866071339162;14451.132932024113;14518.128912265298;14522.346494407777;9404.764880877896;9692.22462203022;9714.688517050108;9731.464769437749;9815.656452024145;3651.2514934438377;3679.3745063339297;3679.742418030712;3688.483612294281;3763.0592351912023;1036.1400037968467;1043.8538604595362;1072.624581396505;1121.8317252412203;1168.9123315751353;34081.82959085238;34101.48847767286;34244.273371238436;34874.12814679653;35266.29467410637;1150.2580835361146;1173.3546549397797;1175.9945246814023;1193.797054500737;1244.2658831289582;18021.098945052694;18075.638487230182;18135.63728725418;18145.274189032556;18238.544036399195;559524;567245;569045;570007;570521;62080;62636.37363626305;62792.05811912734;62820.05769913455;62982.37017629765;1336.6658335416146;1344.8184495093037;1362.611655678127;1374.3952660829202;1463.3195564062762;33331.666683333824;35498;35539.64460355467;35601;35628;52213.7389313051;52567;52766.20850687166;52861;52907.470925290254;431225;432490;434435;434489;436069;31273.218169545944;32311.192220194684;32417.51373729443;33491.66508334885;33503.49744753782;51177;51198.23202651889;51977.96044079173;52256.47743522516;52576.73711631417;43547.34678979881;44458.777706111265;44977.77511112423;45356.31965520586;46325.76837115793;43604.99554502531;45374.54625453703;46067.539324606325;46411.30383044325;46692.53307467018;44010.77994610006;44252.557474424844;44302;44363.55636443723;44524;36192.81903590465;38098.42852357272;39573.60426395814;41007.79496102501;41363;29052.27369315784;29846;30046;30175.245618859703;30232.697673022987 15:11:40 INFO - PROCESS | 15192 | |2;jquery.html;395.1050869779951;432.1292595420228;432.8574548775888;440.3372923749757;450.2638186564967;17126.828731712678;20746.170153193852;21713.240036598723;21725.891370543144;21727.02228399726;20918.686219706717;21020.36938891832;21039;21044.158233670674;21223.363299101053;5684.459976302244;6129.754809807602;6161.845953851167;6168.013117901137;6168.537359698041;5964.739328941949;5981.461668449839;6032.095185722155;6132.294786099589;6136.263648362203;5992.850178745544;6053.878922421549;6058.030715085587;6065.939340606582;6072.726727297282;5772.27846519183;5985.25184351956;6000.669963152025;6002.159697642333;6094.6038507497315;5493.2034854945805;5495.065838807413;5501.587380946445;5505.449455054502;5507.642003269774;24556.877215613986;24611.63082553763;24645.63031554528;24656.630150547757;24860.5027899441;2914.577386278978;2956.4087182563358;3032.166154307583;3254.4142054430195;3319.767616266862;11021.504032318566;11466.770664586746;11484.71288217793;11580.826287605692;11599.246049006872;1932.4009557037364;2004.3485867093252;2019.6667549854255;2037.3582321568658;2106.631339515594;73390.63304683496;87535;88483.55758221168;90588;91791.54104229502;10719.78560428787;11055.447227638586;11454.59908903201;11591.76816463666;11832.822507662391;16682.749758753627;16694.916525417295;16715.247813848408;16728.916355418387;16729.330826766793;8632.179942905414;8869.246114080375;8936.41913275635;8955;9037;8505;8553.657853685907;8614.655413783505;8687.174718401742;8754.168354006359;4987.1771157759285;5002.399712034507;5062.594992400599;5063.974680126576;5193.454687257784;1811.7082382563517;2101.8843963581844;2147.7744836791912;2180.8473406861685;2190.5728382965167;923.3259720404143;1037.8235699930879;1051.279873286801;1055.9472026398648;1101.1851230089835;19999.200031998556;20024.199032038847;20141.597168056556;20292.594148116954;20412.489687757923;996.3523709588825;1014.7158795537262;1027.6300531808542;1039.781645854364;1075.0163600305789;11152;11522.827157592643;11537.769244615061;11551.480183391768;11595;451842;455927;456040;457226;470697;91718.54140729253;93507.53246233726;93683;95658.52170739102;98481;993;1040.245821779204;1048.4443245079967;1058.6188971970084;1064.0689396777818;16480.42318518846;17957.67148595544;18084.90957545204;18277.634447311244;18404.26382944667;44151.77924110359;44596;44645.77677111594;44680.77659611681;46029.539704602525;60193;60414;60629;61570.69214653898;64032.67983660052;8311;8649.308055355683;8883.777905552413;8923.598438070432;8991.190792828676;10697.518611662339;13439.596812095653;13795.517156899461;14154.787678185041;14637.129249401203;11472.770544589228;11784.58753943608;11923.344216067853;12645.367731613567;12952.158109722837;11307.830382544433;11451.541938322374;11594.768104637691;11929.343886086;12313.076519260912;12607.369631518572;12970.481180753042;12980.701929806944;13155.013373996797;13205.867941320465;8139.877901831359;8416.873746893923;8591.312694984512;8679.739607811776;8932.553372331227;12093.879061209274;12615.684607884508;12862.67843303925;12864.871351286367;12940.352982351033 15:11:40 INFO - PROCESS | 15192 | |3;mootools.html;1541.237087641617;1582.4698725926814;1717.4246627379828;1835.9357422490207;1837.3018253063833;8440.746777596667;9006.594703238354;9023.23302519285;9028.097190280969;9035.322350823682;8035.116137224901;8238.17618238176;8893.199612034916;9056.773580660487;9303.813923721538;2551.974480255197;2582.3027782498716;2583.987080064597;2584.043903755604;2588.8835002424844;2466.8766561671846;2531.0508559290265;2533.1134103063964;2535.8605276709773;2536.4546622476164;2559.4497183105627;2571.6014017827265;2583.806214533908;2589.1067581684288;2589.741025897414;2508.83692559983;2541.301142185895;2541.5171117487616;2549.3244290263096;2550.528152291832;2367.3134790910617;2400.111958575321;2402.699662542182;2419.4919066995953;2446.510697860417;9387.389819661701;9470.715878523655;9493.145616894513;9494.667686631004;9509.90490095097;5294.894102117936;5517.972410137923;5519.917201241985;5525.143602741581;5525.281713377275;3572.392693242129;3636.5272514572935;3724.0131365188245;3733.104055026774;3736.252749450121;1019.0777346501428;1056.144522936424;1059.6132411669762;1064.99467502662;1094.5074716377583;12765.297908615023;12767.61697149087;12786.680332991657;12797.232166070065;12800.935995320056;4813.807447702123;4989.825356112518;5047.747612619354;5092.337996060563;5160.948390516121;10822.40476773785;10827.945860270805;10843.78312433747;10853.403062831461;10853.45732713377;5730.398308177665;5757.798477053283;5775.48020678141;5786.797462088806;5793.942060579425;4651.069786042805;4653.6509761768;5162.45794191612;5218.9739051304505;5266.368035835648;4020.8793736187963;4081.0817566047403;4165.625093741578;4169.187008533368;4171.457710497616;1379.2138481065897;1391.200059965524;1397.4619771388088;1400.390829988958;1403.0669604712753;1005.8843233028149;1014.8071866345371;1022.4070039377143;1025.7640742629237;1030.3302853145515;6286.49708023357;6337.904931426032;6367.4906007519285;6393.305773969902;6398.744050238031;1029.7013865978931;1049.2235745548392;1058.756486008207;1070.0418234630608;1105.4859490337033;4363.672724545671;4404.097160082176;4409.867703968886;4419.027813880941;4529.365888775574;11125.332480051224;11409.42952852354;11462.312261264351;11473.770524589461;11512.481938312796;11351.205415620992;11360;11363.03414209785;11364.431778411046;11367.204295699386;8036.156203598655;8274.544900030556;8282.213189746964;8405.243528082501;8560.785980350542;6198.349173336735;6302.243730752339;6471.158749362553;6585.3085426029575;6599.835004124743;10244.53899574506;10298.330608510423;10775.08411785007;10968.067714244378;11092.112630989663;10325.793484130425;10537;10623.097036751962;10640.042396184072;10989;7413.592252426169;7573.545587264782;7866.134725180111;8205.79485512867;8468.153184681483;8869.201771840566;8947.642094316154;8952.552372381224;8961.910380896108;8967;7783.066032076187;7837.804054898673;7857.803554911173;8057.556834374166;8067.435279530377;7779.961100194463;7904.367650587824;7905.051393832777;7965.681372745025;8176.345892328718;7390.371818395497;7415.147258065176;7508.43686723487;7524.811879703051;7526.661300241392;5374.6506477078865;5387.434319396407;5430.592705547021;5450.5367043801725;5471.124620060771;5530.944690553204;5586.050371436784;5636.408177141341;5675.120356344608;5680.00599895025 15:11:40 INFO - PROCESS | 15192 | |4;prototype.html;357.1963083063108;361.3477672800595;373.708507364256;375.66753423220456;378.92800367930107;5648.463395977381;5669.773209071643;5674.546036317095;5678.943210567893;5679.829605111848;5277.973610131953;5591.8602034949145;5602.5824247209275;5608.10270356743;5608.298962629671;4369.470685260166;4420.160169567772;4429.424174857264;4439.866803995886;4441.733495990251;4423.557644235583;4426.55734426558;4430.556944305576;4431.711938723989;4442.244818380883;4406.317020861771;4420.077266389131;4424.712393694415;4430.246858034126;4435.645148388137;4151.335786274197;4317.848875289381;4331.6967812253;4349.84775532858;4372.300431930876;3817.0838998640124;3846.480725102131;3858.2862170498547;3860.9227815443533;3862.4592557042038;5413.64811287269;5449.7275136243425;5474.288342515487;5480.177973303997;5485.807996720096;4828.903421931577;4962.206047032493;5010.599152067863;5015.297858299841;5021.974890125526;5798.855028624318;5803.65178089316;5818.2363377395395;5818.3308919474375;5823.301203855564;4243.724157929755;4258.254805409041;4268.167707297047;4271.551487093872;4274.807633656493;5713.685747283897;5777.59556831022;5846.912296315559;5979.953508136102;6039.5772295939305;4251.107267473803;4340.934885976713;4344.9348259776125;4348.239058164809;4359.760213188241;4405.546169764034;4408;4419.18245124653;4436.334549817553;4441.977790111029;4669.41632295963;4726.834560790425;4919.532644398776;5031.534548071419;5035.62232832539;5823.475887170175;5823.767049317979;5827.679477628686;5854.297484301911;5875.177475153482;1503.150719843285;1521.8630323270959;1522.2617030740082;1530.8086489188852;1532.2185685300576;980.2305190425624;994.3039872089566;1012.8936461671566;1065.0574241796066;1066.898644628759;5641.689707066069;5679.176519404746;5680.5455563554815;5687.971560142173;5696.572757043238;5980.671063091485;5990.993017339153;5999.970000149971;6027.30685971119;6037.396260373927;5464.142191654856;5716.771329146871;5763.682997435181;5770.384292398136;5779.739911703983;3216.453202955503;3233.967660323414;3235.3691030248874;3237.9676203238137;3246.204679853451;6136.110264011693;6227.412009937437;6271.62370257786;6272.1219029335925;6290.496760259169;6155.399596104955;6162.568620196633;6195.380461953859;6195.411435913581;6199.659018754011;6089.634621922699;6250.1874756281395;6251.531135164881;6271.78048768291;6275.937240627627;6075.149479072845;6084.148219249397;6088.786892458743;6118.724657390517;6121.84695382619;5970.134330522137;5970.253718285215;5988.041913294026;5995.280566332068;6024.367441418588;5793.565482588907;5995.760169593167;6157.84605384869;6194.566380353513;6219.782307619212;4328.848490302824;4363.825446982212;4386.188555117314;4395.648348132077;4396.978015109904;5817.447342502455;5944.256967879015;6061.605995610271;6061.696915154313;6072.90890636632;5869.530437564899;5884.852453771474;5884.882302354014;5886;5888.087346461305;5873.472897046884;5875.236219291465;5876.529877609866;5887.3523912370465;5917.644941303535;5633.8309850704545;5792.594518383841;5820.70896455184;5828.446297601721;5844.6785426801935;5895.233619629421;5900.763969441175;5916.201312822714;5921.407859214044;5939.881202375842;5557.861053473695;5735.799247026334;5767.5674324425;5780.624259423124;5791.971040144772 15:11:40 INFO - PROCESS | 15192 | |5;yui.html;797.0913158998742;809.146350600117;875.6366108065154;876.9210771030608;928.076563819;16643.833561664378;18178.545536361566;18219.544511387223;18247.817521824778;18268;15369.231538423062;15432.922835385807;16552.66894662106;17244.913775431138;17294.913525432356;722.5664601239267;740.9740659076933;876.2157868707503;895.0914821456228;899.4648184330346;848.9660413583449;860.8665656823202;874.7026011156206;887.8401887660218;890.5013192612125;942.7313215733517;946.0775743649955;946.4794363100302;946.8295706772778;946.9479178645172;771.456123432984;778.3306356533377;939.8778158839341;941.5010044676299;942.6700654770843;659.1398225315961;660.9438197753196;677.4005005570057;688.2463702246033;688.5593220338988;16848.66302673952;16931.3227470901;16963.660726785394;16965.491035268962;16999.490015299438;14164.787528187086;14554;14571.489997850025;14713.632159196106;14718.705625887422;14002.299885005708;14100.788488172686;14222.644433889236;14488.782668259984;14615.70768584633;4820.662553621248;4890.73100979446;5010.298558201855;5015.548600625962;5036.345275114249;60887.391126088616;61421;61500.99999999955;61575.692121539556;61584;11750.23623464472;12010.699732506757;12183.634490965285;12329.876701233052;13021.348932553334;23023.424414389774;23063.884680576826;23076.076956921872;23120.884395577916;23154.536909261722;8496.40525163245;8585.270252028648;8751.737447876574;8884.156005179497;9284.582193801296;5008.248762685554;5243.449437808975;5299;5338.706371149624;5382.67703937765;3655.3968595182027;3767.9434808477895;3772.264408440383;3775.3959366501226;3777.660010599059;601.3024891126272;602.4969150759077;610.2128254551578;613.9201903752484;618.9938100618937;638.1735652330243;647.9686183813777;653.3597074866657;680.5610381304031;702.3257672634226;9723.805523889483;9738.269629777795;9755.902440975642;9790;10098.545565449573;657.8190997475637;671.1007250284545;696.6238231355012;707.8478127202676;737.7491652838061;4536.909261814745;4586.977065114653;4643.95356046442;4736.526347365305;4746.95253047472;83132.58433707793;84147;101825;138142;142014;68580.6570967142;70242;90522.54738726265;99925.50037249767;113939.43030284795;106979.46510267399;108020.45989770158;108404.45797771118;108871.45564272287;110978.44510777557;8536.786580335542;8650.524221167645;8827.646894124164;9011.369204155646;9104.089591041107;54733.72633136809;55599.444005559424;57777.422225777205;59413.7029314868;59727.402725972184;51810;74930;77021;78210;81523;13584.388702508608;13779.242141682304;14255.429782808571;14282.2144782038;14287.285635718381;15844.52466426009;16628.726502778394;16695.91652041732;16973;17031.65936681284;13710.314484275947;14138;14386.85613143897;14579.416823327372;15195.392184312504;13023;13519;13786.862131378557;13941.442342306194;13981.510647127301;14860.554183374516;14865.554033379016;15039.473618423302;16006.919965400564;16042.43851465193;10802.945985270024;10933.781324373309;11062.336259824437;11493.597724079616;11744.412779361168;12882.935585322013;13031.869681303066;13050.54323098687;13131;13448.596542103753 15:11:40 INFO - PROCESS | 15192 | __end_tp_report 15:11:40 INFO - PROCESS | 15192 | __start_cc_report 15:11:40 INFO - PROCESS | 15192 | _x_x_mozilla_cycle_collect,893 15:11:40 INFO - PROCESS | 15192 | __end_cc_report 15:11:40 INFO - PROCESS | 15192 | __startTimestamp1474409500490__endTimestamp 15:11:40 INFO - PROCESS | 15192 | 15:11:40 INFO - PROCESS | 15192 | ------- Summary: start ------- 15:11:40 INFO - PROCESS | 15192 | Number of tests: 6 15:11:40 INFO - PROCESS | 15192 | 15:11:40 INFO - PROCESS | 15192 | [#0] dojo.html Cycles:165 Average:5561.75 Median:5785.80 stddev:632.51 (10.9%) stddev-sans-first:604.14 15:11:40 INFO - PROCESS | 15192 | Values: 3095.3 3135.6 3137.4 3163.6 3180.4 5596.7 5648.8 5687.1 5698.1 5708.9 5651.5 5710.6 5721.1 5724.7 5761.5 5119.6 5154.6 5168.2 5176.2 5217.3 5028.5 5032.9 5250.7 5252.0 5267.3 5105.9 5108.0 5108.2 5154.2 5156.0 5113.7 5129.8 5177.0 5184.6 5207.2 4864.2 5078.3 5096.6 5136.5 5153.1 5438.8 5473.4 5496.5 5504.2 5526.0 5900.1 5907.7 5985.5 5990.4 5991.0 5826.4 6023.2 6040.7 6053.7 6055.2 5459.6 5545.7 5593.0 5607.1 5625.5 6134.0 6143.2 6146.7 6148.4 6165.9 5748.7 5947.6 5952.6 5961.1 5963.3 5849.9 5851.9 5876.7 5906.6 5915.1 5857.8 5873.9 5876.3 5878.0 5882.9 5839.6 5885.3 5896.6 5904.3 5914.7 4415.4 4456.0 4552.5 4601.1 4619.8 4085.6 4109.3 4116.6 4132.9 4142.0 5685.0 5687.2 5691.3 5693.4 5729.9 5763.3 5827.1 6003.9 6011.8 6017.9 5731.7 5732.5 5761.4 5763.0 5766.2 5221.5 5226.3 5228.6 5228.9 5232.2 5976.6 6032.7 6191.8 6236.2 6249.6 5877.4 5976.4 5999.1 6033.1 6095.9 6189.0 6216.6 6217.3 6224.2 6227.6 5645.9 5921.8 5923.9 5930.7 5956.2 5832.3 5925.7 5944.3 5977.6 6059.3 5824.1 5849.0 5867.8 5873.4 5874.9 5866.5 5878.3 5878.4 5880.2 5883.9 5588.2 5732.8 5785.8 5818.0 5833.0 5975.5 5975.7 5977.0 5986.3 5988.8 5539.9 5606.6 5820.5 5828.8 5833.2 15:11:40 INFO - PROCESS | 15192 | 15:11:40 INFO - PROCESS | 15192 | [#1] ext.html Cycles:180 Average:62772.41 Median:39708.40 stddev:124685.58 (314.0%) stddev-sans-first:124947.97 15:11:40 INFO - PROCESS | 15192 | Values: 597.3 616.9 639.9 640.3 649.4 34884.3 37809.2 46119.5 46890.0 47155.0 48042.5 48702.0 48730.3 48845.8 48858.0 9291.1 9530.5 9537.0 9539.5 9544.1 8603.0 8819.1 11074.1 11349.7 11359.8 11405.4 11503.3 11530.7 11536.7 11544.2 8548.6 11313.9 11400.7 11400.7 11424.0 8311.7 10692.2 11131.6 11158.7 11532.6 45691.1 46095.5 46157.3 46216.0 46248.8 3854.2 3921.9 3930.4 4167.7 4331.4 34650.8 34874.7 34937.7 35309.5 36329.3 4212.8 4288.4 4390.3 4434.0 4524.1 389241.0 391911.0 392113.0 392279.0 392987.0 38881.8 39653.0 39763.8 67625.7 68341.0 41897.2 42120.8 42122.6 42152.6 42163.8 17174.7 17527.6 17534.1 17562.2 17594.4 13091.6 13392.9 14451.1 14518.1 14522.3 9404.8 9692.2 9714.7 9731.5 9815.7 3651.3 3679.4 3679.7 3688.5 3763.1 1036.1 1043.9 1072.6 1121.8 1168.9 34081.8 34101.5 34244.3 34874.1 35266.3 1150.3 1173.4 1176.0 1193.8 1244.3 18021.1 18075.6 18135.6 18145.3 18238.5 559524.0 567245.0 569045.0 570007.0 570521.0 62080.0 62636.4 62792.1 62820.1 62982.4 1336.7 1344.8 1362.6 1374.4 1463.3 33331.7 35498.0 35539.6 35601.0 35628.0 52213.7 52567.0 52766.2 52861.0 52907.5 431225.0 432490.0 434435.0 434489.0 436069.0 31273.2 32311.2 32417.5 33491.7 33503.5 51177.0 51198.2 51978.0 52256.5 52576.7 43547.3 44458.8 44977.8 45356.3 46325.8 43605.0 45374.5 46067.5 46411.3 46692.5 44010.8 44252.6 44302.0 44363.6 44524.0 36192.8 38098.4 39573.6 41007.8 41363.0 29052.3 29846.0 30046.0 30175.2 30232.7 15:11:40 INFO - PROCESS | 15192 | 15:11:40 INFO - PROCESS | 15192 | [#2] jquery.html Cycles:180 Average:28997.45 Median:24758.57 stddev:75908.68 (306.6%) stddev-sans-first:76091.25 15:11:40 INFO - PROCESS | 15192 | Values: 395.1 432.1 432.9 440.3 450.3 17126.8 20746.2 21713.2 21725.9 21727.0 20918.7 21020.4 21039.0 21044.2 21223.4 5684.5 6129.8 6161.8 6168.0 6168.5 5964.7 5981.5 6032.1 6132.3 6136.3 5992.9 6053.9 6058.0 6065.9 6072.7 5772.3 5985.3 6000.7 6002.2 6094.6 5493.2 5495.1 5501.6 5505.4 5507.6 24556.9 24611.6 24645.6 24656.6 24860.5 2914.6 2956.4 3032.2 3254.4 3319.8 11021.5 11466.8 11484.7 11580.8 11599.2 1932.4 2004.3 2019.7 2037.4 2106.6 73390.6 87535.0 88483.6 90588.0 91791.5 10719.8 11055.4 11454.6 11591.8 11832.8 16682.7 16694.9 16715.2 16728.9 16729.3 8632.2 8869.2 8936.4 8955.0 9037.0 8505.0 8553.7 8614.7 8687.2 8754.2 4987.2 5002.4 5062.6 5064.0 5193.5 1811.7 2101.9 2147.8 2180.8 2190.6 923.3 1037.8 1051.3 1055.9 1101.2 19999.2 20024.2 20141.6 20292.6 20412.5 996.4 1014.7 1027.6 1039.8 1075.0 11152.0 11522.8 11537.8 11551.5 11595.0 451842.0 455927.0 456040.0 457226.0 470697.0 91718.5 93507.5 93683.0 95658.5 98481.0 993.0 1040.2 1048.4 1058.6 1064.1 16480.4 17957.7 18084.9 18277.6 18404.3 44151.8 44596.0 44645.8 44680.8 46029.5 60193.0 60414.0 60629.0 61570.7 64032.7 8311.0 8649.3 8883.8 8923.6 8991.2 10697.5 13439.6 13795.5 14154.8 14637.1 11472.8 11784.6 11923.3 12645.4 12952.2 11307.8 11451.5 11594.8 11929.3 12313.1 12607.4 12970.5 12980.7 13155.0 13205.9 8139.9 8416.9 8591.3 8679.7 8932.6 12093.9 12615.7 12862.7 12864.9 12940.4 15:11:40 INFO - PROCESS | 15192 | 15:11:40 INFO - PROCESS | 15192 | [#3] mootools.html Cycles:180 Average:6095.53 Median:4383.88 stddev:3401.94 (77.6%) stddev-sans-first:3394.26 15:11:40 INFO - PROCESS | 15192 | Values: 1541.2 1582.5 1717.4 1835.9 1837.3 8440.7 9006.6 9023.2 9028.1 9035.3 8035.1 8238.2 8893.2 9056.8 9303.8 2552.0 2582.3 2584.0 2584.0 2588.9 2466.9 2531.1 2533.1 2535.9 2536.5 2559.4 2571.6 2583.8 2589.1 2589.7 2508.8 2541.3 2541.5 2549.3 2550.5 2367.3 2400.1 2402.7 2419.5 2446.5 9387.4 9470.7 9493.1 9494.7 9509.9 5294.9 5518.0 5519.9 5525.1 5525.3 3572.4 3636.5 3724.0 3733.1 3736.3 1019.1 1056.1 1059.6 1065.0 1094.5 12765.3 12767.6 12786.7 12797.2 12800.9 4813.8 4989.8 5047.7 5092.3 5160.9 10822.4 10827.9 10843.8 10853.4 10853.5 5730.4 5757.8 5775.5 5786.8 5793.9 4651.1 4653.7 5162.5 5219.0 5266.4 4020.9 4081.1 4165.6 4169.2 4171.5 1379.2 1391.2 1397.5 1400.4 1403.1 1005.9 1014.8 1022.4 1025.8 1030.3 6286.5 6337.9 6367.5 6393.3 6398.7 1029.7 1049.2 1058.8 1070.0 1105.5 4363.7 4404.1 4409.9 4419.0 4529.4 11125.3 11409.4 11462.3 11473.8 11512.5 11351.2 11360.0 11363.0 11364.4 11367.2 8036.2 8274.5 8282.2 8405.2 8560.8 6198.3 6302.2 6471.2 6585.3 6599.8 10244.5 10298.3 10775.1 10968.1 11092.1 10325.8 10537.0 10623.1 10640.0 10989.0 7413.6 7573.5 7866.1 8205.8 8468.2 8869.2 8947.6 8952.6 8961.9 8967.0 7783.1 7837.8 7857.8 8057.6 8067.4 7780.0 7904.4 7905.1 7965.7 8176.3 7390.4 7415.1 7508.4 7524.8 7526.7 5374.7 5387.4 5430.6 5450.5 5471.1 5530.9 5586.1 5636.4 5675.1 5680.0 15:11:40 INFO - PROCESS | 15192 | 15:11:40 INFO - PROCESS | 15192 | [#4] prototype.html Cycles:180 Average:4955.06 Median:5679.06 stddev:1441.77 (25.4%) stddev-sans-first:1403.90 15:11:40 INFO - PROCESS | 15192 | Values: 357.2 361.3 373.7 375.7 378.9 5648.5 5669.8 5674.5 5678.9 5679.8 5278.0 5591.9 5602.6 5608.1 5608.3 4369.5 4420.2 4429.4 4439.9 4441.7 4423.6 4426.6 4430.6 4431.7 4442.2 4406.3 4420.1 4424.7 4430.2 4435.6 4151.3 4317.8 4331.7 4349.8 4372.3 3817.1 3846.5 3858.3 3860.9 3862.5 5413.6 5449.7 5474.3 5480.2 5485.8 4828.9 4962.2 5010.6 5015.3 5022.0 5798.9 5803.7 5818.2 5818.3 5823.3 4243.7 4258.3 4268.2 4271.6 4274.8 5713.7 5777.6 5846.9 5980.0 6039.6 4251.1 4340.9 4344.9 4348.2 4359.8 4405.5 4408.0 4419.2 4436.3 4442.0 4669.4 4726.8 4919.5 5031.5 5035.6 5823.5 5823.8 5827.7 5854.3 5875.2 1503.2 1521.9 1522.3 1530.8 1532.2 980.2 994.3 1012.9 1065.1 1066.9 5641.7 5679.2 5680.5 5688.0 5696.6 5980.7 5991.0 6000.0 6027.3 6037.4 5464.1 5716.8 5763.7 5770.4 5779.7 3216.5 3234.0 3235.4 3238.0 3246.2 6136.1 6227.4 6271.6 6272.1 6290.5 6155.4 6162.6 6195.4 6195.4 6199.7 6089.6 6250.2 6251.5 6271.8 6275.9 6075.1 6084.1 6088.8 6118.7 6121.8 5970.1 5970.3 5988.0 5995.3 6024.4 5793.6 5995.8 6157.8 6194.6 6219.8 4328.8 4363.8 4386.2 4395.6 4397.0 5817.4 5944.3 6061.6 6061.7 6072.9 5869.5 5884.9 5884.9 5886.0 5888.1 5873.5 5875.2 5876.5 5887.4 5917.6 5633.8 5792.6 5820.7 5828.4 5844.7 5895.2 5900.8 5916.2 5921.4 5939.9 5557.9 5735.8 5767.6 5780.6 5792.0 15:11:40 INFO - PROCESS | 15192 | 15:11:40 INFO - PROCESS | 15192 | [#5] yui.html Cycles:180 Average:21266.09 Median:4855.70 stddev:30178.45 (621.5%) stddev-sans-first:30223.98 15:11:40 INFO - PROCESS | 15192 | Values: 797.1 809.1 875.6 876.9 928.1 16643.8 18178.5 18219.5 18247.8 18268.0 15369.2 15432.9 16552.7 17244.9 17294.9 722.6 741.0 876.2 895.1 899.5 849.0 860.9 874.7 887.8 890.5 942.7 946.1 946.5 946.8 946.9 771.5 778.3 939.9 941.5 942.7 659.1 660.9 677.4 688.2 688.6 16848.7 16931.3 16963.7 16965.5 16999.5 14164.8 14554.0 14571.5 14713.6 14718.7 14002.3 14100.8 14222.6 14488.8 14615.7 4820.7 4890.7 5010.3 5015.5 5036.3 60887.4 61421.0 61501.0 61575.7 61584.0 11750.2 12010.7 12183.6 12329.9 13021.3 23023.4 23063.9 23076.1 23120.9 23154.5 8496.4 8585.3 8751.7 8884.2 9284.6 5008.2 5243.4 5299.0 5338.7 5382.7 3655.4 3767.9 3772.3 3775.4 3777.7 601.3 602.5 610.2 613.9 619.0 638.2 648.0 653.4 680.6 702.3 9723.8 9738.3 9755.9 9790.0 10098.5 657.8 671.1 696.6 707.8 737.7 4536.9 4587.0 4644.0 4736.5 4747.0 83132.6 84147.0 101825.0 138142.0 142014.0 68580.7 70242.0 90522.5 99925.5 113939.4 106979.5 108020.5 108404.5 108871.5 110978.4 8536.8 8650.5 8827.6 9011.4 9104.1 54733.7 55599.4 57777.4 59413.7 59727.4 51810.0 74930.0 77021.0 78210.0 81523.0 13584.4 13779.2 14255.4 14282.2 14287.3 15844.5 16628.7 16695.9 16973.0 17031.7 13710.3 14138.0 14386.9 14579.4 15195.4 13023.0 13519.0 13786.9 13941.4 13981.5 14860.6 14865.6 15039.5 16006.9 16042.4 10802.9 10933.8 11062.3 11493.6 11744.4 12882.9 13031.9 13050.5 13131.0 13448.6 15:11:40 INFO - PROCESS | 15192 | -------- Summary: end -------- 15:11:40 INFO - PROCESS | 15192 | 15:11:41 INFO - TEST-INFO | 15192: exit 0 15:11:41 INFO - TEST-OK | dromaeo_css | took 1115278ms 15:11:41 INFO - TEST-START | kraken 15:11:41 INFO - operating with platform_type : linux_ 15:11:41 INFO - Initialising browser for kraken test... 15:11:41 INFO - TEST-INFO | started process 15513 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwo7MIF/profile http://localhost:49529/getInfo.html) 15:11:45 INFO - PROCESS | 15513 | __metrics Screen width/height:1600/1200 15:11:45 INFO - PROCESS | 15513 | colorDepth:24 15:11:45 INFO - PROCESS | 15513 | Browser inner width/height: 1024/697 15:11:45 INFO - PROCESS | 15513 | __metrics 15:11:46 INFO - PROCESS | 15513 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:11:46 INFO - PROCESS | 15513 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:11:46 INFO - PROCESS | 15513 | console.error: PushService: 15:11:46 INFO - PROCESS | 15513 | stateChangeProcessEnqueue: Error transitioning state 15:11:46 INFO - PROCESS | 15513 | UnknownError 15:11:47 INFO - TEST-INFO | 15513: exit 0 15:11:47 INFO - Browser initialized. 15:11:47 INFO - Running cycle 1/1 for kraken test... 15:11:47 INFO - TEST-INFO | started process 15605 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwo7MIF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:11:59 INFO - PROCESS | 15605 | RSS: Main: 144490496 15:11:59 INFO - PROCESS | 15605 | 15:12:01 INFO - PROCESS | 15605 | Cycle 1(1): loaded http://localhost:49529/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49529/tests/kraken/driver.html?testName=audio-beat-detection) 15:12:02 INFO - PROCESS | 15605 | RSS: Main: 186494976 15:12:02 INFO - PROCESS | 15605 | 15:12:05 INFO - PROCESS | 15605 | Cycle 1(1): loaded http://localhost:49529/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49529/tests/kraken/driver.html?testName=audio-dft) 15:12:05 INFO - PROCESS | 15605 | RSS: Main: 175411200 15:12:05 INFO - PROCESS | 15605 | 15:12:08 INFO - PROCESS | 15605 | Cycle 1(1): loaded http://localhost:49529/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49529/tests/kraken/driver.html?testName=audio-fft) 15:12:09 INFO - PROCESS | 15605 | RSS: Main: 266686464 15:12:09 INFO - PROCESS | 15605 | 15:12:11 INFO - PROCESS | 15605 | Cycle 1(1): loaded http://localhost:49529/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49529/tests/kraken/driver.html?testName=audio-oscillator) 15:12:11 INFO - PROCESS | 15605 | RSS: Main: 172556288 15:12:11 INFO - PROCESS | 15605 | 15:12:13 INFO - PROCESS | 15605 | Cycle 1(1): loaded http://localhost:49529/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49529/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:12:14 INFO - PROCESS | 15605 | RSS: Main: 174493696 15:12:14 INFO - PROCESS | 15605 | 15:12:17 INFO - PROCESS | 15605 | Cycle 1(1): loaded http://localhost:49529/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49529/tests/kraken/driver.html?testName=imaging-darkroom) 15:12:17 INFO - PROCESS | 15605 | RSS: Main: 210862080 15:12:17 INFO - PROCESS | 15605 | 15:12:21 INFO - PROCESS | 15605 | Cycle 1(1): loaded http://localhost:49529/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49529/tests/kraken/driver.html?testName=imaging-desaturate) 15:12:21 INFO - PROCESS | 15605 | RSS: Main: 209846272 15:12:21 INFO - PROCESS | 15605 | 15:12:24 INFO - PROCESS | 15605 | Cycle 1(1): loaded http://localhost:49529/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49529/tests/kraken/driver.html?testName=json-parse-financial) 15:12:25 INFO - PROCESS | 15605 | RSS: Main: 209866752 15:12:25 INFO - PROCESS | 15605 | 15:12:26 INFO - PROCESS | 15605 | Cycle 1(1): loaded http://localhost:49529/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49529/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:12:27 INFO - PROCESS | 15605 | RSS: Main: 171855872 15:12:27 INFO - PROCESS | 15605 | 15:12:29 INFO - PROCESS | 15605 | Cycle 1(1): loaded http://localhost:49529/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49529/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:12:29 INFO - PROCESS | 15605 | RSS: Main: 179871744 15:12:29 INFO - PROCESS | 15605 | 15:12:31 INFO - PROCESS | 15605 | Cycle 1(1): loaded http://localhost:49529/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49529/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:12:32 INFO - PROCESS | 15605 | RSS: Main: 183836672 15:12:32 INFO - PROCESS | 15605 | 15:12:34 INFO - PROCESS | 15605 | Cycle 1(1): loaded http://localhost:49529/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49529/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:12:35 INFO - PROCESS | 15605 | RSS: Main: 184385536 15:12:35 INFO - PROCESS | 15605 | 15:12:38 INFO - PROCESS | 15605 | Cycle 1(1): loaded http://localhost:49529/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49529/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:12:38 INFO - PROCESS | 15605 | RSS: Main: 183123968 15:12:38 INFO - PROCESS | 15605 | 15:12:40 INFO - PROCESS | 15605 | Cycle 1(1): loaded http://localhost:49529/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49529/tests/kraken/driver.html?testName=ai-astar) 15:12:40 INFO - PROCESS | 15605 | RSS: Main: 234127360 15:12:40 INFO - PROCESS | 15605 | 15:12:40 INFO - PROCESS | 15605 | __start_tp_report 15:12:40 INFO - PROCESS | 15605 | _x_x_mozilla_page_load 15:12:40 INFO - PROCESS | 15605 | _x_x_mozilla_page_load_details 15:12:40 INFO - PROCESS | 15605 | |i|pagename|runs| 15:12:40 INFO - PROCESS | 15605 | |0;ai-astar;108;111;110;111;111;111;111;110;109;110 15:12:40 INFO - PROCESS | 15605 | |1;audio-beat-detection;142;116;116;164;118;121;121;119;152;117 15:12:40 INFO - PROCESS | 15605 | |2;audio-dft;159;160;159;161;160;157;166;169;158;158 15:12:40 INFO - PROCESS | 15605 | |3;audio-fft;75;76;76;100;76;76;74;76;96;74 15:12:40 INFO - PROCESS | 15605 | |4;audio-oscillator;81;82;116;82;81;81;102;80;80;113 15:12:40 INFO - PROCESS | 15605 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;117;117;118 15:12:40 INFO - PROCESS | 15605 | |6;imaging-darkroom;147;146;146;146;147;145;146;146;147;147 15:12:40 INFO - PROCESS | 15605 | |7;imaging-desaturate;95;95;95;95;95;95;96;96;95;95 15:12:40 INFO - PROCESS | 15605 | |8;json-parse-financial;51;49;52;48;49;50;49;50;48;50 15:12:40 INFO - PROCESS | 15605 | |9;json-stringify-tinderbox;59;60;59;60;61;60;59;60;60;59 15:12:40 INFO - PROCESS | 15605 | |10;stanford-crypto-aes;74;73;73;73;73;73;73;72;73;73 15:12:40 INFO - PROCESS | 15605 | |11;stanford-crypto-ccm;124;124;120;122;126;118;124;118;164;118 15:12:40 INFO - PROCESS | 15605 | |12;stanford-crypto-pbkdf2;153;153;162;154;154;158;154;151;154;157 15:12:40 INFO - PROCESS | 15605 | |13;stanford-crypto-sha256-iterative;63;64;66;61;63;62;63;63;61;61 15:12:40 INFO - PROCESS | 15605 | __end_tp_report 15:12:40 INFO - PROCESS | 15605 | __start_cc_report 15:12:40 INFO - PROCESS | 15605 | _x_x_mozilla_cycle_collect,1591 15:12:40 INFO - PROCESS | 15605 | __end_cc_report 15:12:40 INFO - PROCESS | 15605 | __startTimestamp1474409560591__endTimestamp 15:12:40 INFO - PROCESS | 15605 | 15:12:40 INFO - PROCESS | 15605 | ------- Summary: start ------- 15:12:40 INFO - PROCESS | 15605 | Number of tests: 14 15:12:40 INFO - PROCESS | 15605 | 15:12:40 INFO - PROCESS | 15605 | [#0] ai-astar Cycles:10 Average:110.20 Median:111.00 stddev:1.03 (0.9%) stddev-sans-first:0.73 15:12:40 INFO - PROCESS | 15605 | Values: 108.0 111.0 110.0 111.0 111.0 111.0 111.0 110.0 109.0 110.0 15:12:40 INFO - PROCESS | 15605 | 15:12:40 INFO - PROCESS | 15605 | [#1] audio-beat-detection Cycles:10 Average:128.60 Median:121.00 stddev:17.49 (14.5%) stddev-sans-first:17.86 15:12:40 INFO - PROCESS | 15605 | Values: 142.0 116.0 116.0 164.0 118.0 121.0 121.0 119.0 152.0 117.0 15:12:40 INFO - PROCESS | 15605 | 15:12:40 INFO - PROCESS | 15605 | [#2] audio-dft Cycles:10 Average:160.70 Median:160.00 stddev:3.83 (2.4%) stddev-sans-first:4.01 15:12:40 INFO - PROCESS | 15605 | Values: 159.0 160.0 159.0 161.0 160.0 157.0 166.0 169.0 158.0 158.0 15:12:40 INFO - PROCESS | 15605 | 15:12:40 INFO - PROCESS | 15605 | [#3] audio-fft Cycles:10 Average:79.90 Median:76.00 stddev:9.62 (12.7%) stddev-sans-first:10.04 15:12:40 INFO - PROCESS | 15605 | Values: 75.0 76.0 76.0 100.0 76.0 76.0 74.0 76.0 96.0 74.0 15:12:40 INFO - PROCESS | 15605 | 15:12:40 INFO - PROCESS | 15605 | [#4] audio-oscillator Cycles:10 Average:89.80 Median:81.00 stddev:14.60 (18.0%) stddev-sans-first:15.14 15:12:40 INFO - PROCESS | 15605 | Values: 81.0 82.0 116.0 82.0 81.0 81.0 102.0 80.0 80.0 113.0 15:12:40 INFO - PROCESS | 15605 | 15:12:40 INFO - PROCESS | 15605 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 15:12:40 INFO - PROCESS | 15605 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 118.0 15:12:40 INFO - PROCESS | 15605 | 15:12:40 INFO - PROCESS | 15605 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.50 stddev:0.67 (0.5%) stddev-sans-first:0.67 15:12:40 INFO - PROCESS | 15605 | Values: 147.0 146.0 146.0 146.0 147.0 145.0 146.0 146.0 147.0 147.0 15:12:40 INFO - PROCESS | 15605 | 15:12:40 INFO - PROCESS | 15605 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 15:12:40 INFO - PROCESS | 15605 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 96.0 96.0 95.0 95.0 15:12:40 INFO - PROCESS | 15605 | 15:12:40 INFO - PROCESS | 15605 | [#8] json-parse-financial Cycles:10 Average:49.60 Median:50.00 stddev:1.26 (2.5%) stddev-sans-first:1.24 15:12:40 INFO - PROCESS | 15605 | Values: 51.0 49.0 52.0 48.0 49.0 50.0 49.0 50.0 48.0 50.0 15:12:40 INFO - PROCESS | 15605 | 15:12:40 INFO - PROCESS | 15605 | [#9] json-stringify-tinderbox Cycles:10 Average:59.70 Median:60.00 stddev:0.67 (1.1%) stddev-sans-first:0.67 15:12:40 INFO - PROCESS | 15605 | Values: 59.0 60.0 59.0 60.0 61.0 60.0 59.0 60.0 60.0 59.0 15:12:40 INFO - PROCESS | 15605 | 15:12:40 INFO - PROCESS | 15605 | [#10] stanford-crypto-aes Cycles:10 Average:73.00 Median:73.00 stddev:0.47 (0.6%) stddev-sans-first:0.33 15:12:40 INFO - PROCESS | 15605 | Values: 74.0 73.0 73.0 73.0 73.0 73.0 73.0 72.0 73.0 73.0 15:12:40 INFO - PROCESS | 15605 | 15:12:40 INFO - PROCESS | 15605 | [#11] stanford-crypto-ccm Cycles:10 Average:125.80 Median:124.00 stddev:13.74 (11.1%) stddev-sans-first:14.56 15:12:40 INFO - PROCESS | 15605 | Values: 124.0 124.0 120.0 122.0 126.0 118.0 124.0 118.0 164.0 118.0 15:12:40 INFO - PROCESS | 15605 | 15:12:40 INFO - PROCESS | 15605 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:155.00 Median:154.00 stddev:3.16 (2.1%) stddev-sans-first:3.27 15:12:40 INFO - PROCESS | 15605 | Values: 153.0 153.0 162.0 154.0 154.0 158.0 154.0 151.0 154.0 157.0 15:12:40 INFO - PROCESS | 15605 | 15:12:40 INFO - PROCESS | 15605 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.70 Median:63.00 stddev:1.57 (2.5%) stddev-sans-first:1.66 15:12:40 INFO - PROCESS | 15605 | Values: 63.0 64.0 66.0 61.0 63.0 62.0 63.0 63.0 61.0 61.0 15:12:40 INFO - PROCESS | 15605 | -------- Summary: end -------- 15:12:40 INFO - PROCESS | 15605 | 15:12:41 INFO - TEST-INFO | 15605: exit 0 15:12:41 INFO - TEST-OK | kraken | took 60365ms 15:12:41 INFO - SUITE-END | took 1175s 15:12:41 INFO - Completed test suite (00:19:35) 15:12:41 INFO - javascript benchmark 15:12:41 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3095.3345030818377, 3135.608048993876, 3137.3568418474206, 3163.6203655561335, 3180.3798259339424, 5596.692181929992, 5648.830535083949, 5687.061634830258, 5698.116791897252, 5708.942910570903, 5651.463111004458, 5710.600257981943, 5721.113227449741, 5724.685142317181, 5761.510271626907, 5119.616028797836, 5154.587632989371, 5168.198929165986, 5176.197689358156, 5217.295665085222, 5028.4971502849785, 5032.949670503284, 5250.7112108834, 5251.975864706384, 5267.26258323835, 5105.897882042357, 5107.9744601277125, 5108.208227724708, 5154.226865970116, 5156.0203561323415, 5113.744312784364, 5129.846104616849, 5177.0, 5184.559312458446, 5207.218917162418, 4864.1974074277705, 5078.339815823956, 5096.566791822699, 5136.5377116059735, 5153.072446959546, 5438.809641662523, 5473.397926228112, 5496.5053145216725, 5504.174373843915, 5525.972370138124, 5900.114982752579, 5907.734151963172, 5985.461308482214, 5990.371011043865, 5991.041433370639, 5826.359100498942, 6023.247094113236, 6040.697965101727, 6053.697315134225, 6055.152278680988, 5459.563234941195, 5545.722713864331, 5593.049181639171, 5607.102863541813, 5625.46558076982, 6134.0, 6143.170671959318, 6146.692665366803, 6148.385161483816, 6165.938340616536, 5748.741306641211, 5947.613405128653, 5952.5833191676165, 5961.076033214858, 5963.314218864886, 5849.912251316234, 5851.882962340729, 5876.676782776903, 5906.645601263852, 5915.112733089984, 5857.7949771757785, 5873.882522349614, 5876.294844618673, 5878.0, 5882.941170588325, 5839.620424672383, 5885.264341957255, 5896.587238893237, 5904.291485021825, 5914.6746928918465, 4415.425994620743, 4455.977720111444, 4552.544745525421, 4601.056783359403, 4619.815207391734, 4085.571015043437, 4109.301418758759, 4116.58834116586, 4132.855350062733, 4142.0, 5684.971575142098, 5687.232223649837, 5691.345495268014, 5693.373728889819, 5729.942700573024, 5763.279590051244, 5827.125931110282, 6003.939960600425, 6011.819645410645, 6017.909731354033, 5731.684757338387, 5732.455416735404, 5761.39505351932, 5763.0202865512965, 5766.192733017381, 5221.451747566451, 5226.3205783248695, 5228.555572776357, 5228.947710522846, 5232.241325007929, 5976.551758618034, 6032.668203248688, 6191.81424557264, 6236.18929539157, 6249.593776404519, 5877.353491115889, 5976.372480889444, 5999.100134979788, 6033.12519684652, 6095.908561371494, 6189.0, 6216.595921265103, 6217.253929528486, 6224.1597384352535, 6227.564070515202, 5645.943540564542, 5921.84045116895, 5923.881522369442, 5930.673812940329, 5956.166136740946, 5832.300123984979, 5925.733341999707, 5944.316403613467, 5977.641341519523, 6059.303180134341, 5824.097264923944, 5849.034909239927, 5867.794627188028, 5873.4126587342635, 5874.882502350015, 5866.472017518444, 5878.29460464747, 5878.441548052928, 5880.176775251382, 5883.911741323798, 5588.161775733673, 5732.7706891723865, 5785.797497087582, 5817.95276850171, 5833.008388573868, 5975.462208401264, 5975.731092100779, 5977.043673012471, 5986.2517185351835, 5988.820335389945, 5539.944600553943, 5606.579506536945, 5820.534357251325, 5828.825135245949, 5833.212516310244], "value": 5518.19318964684, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [597.2653636027686, 616.8982117950538, 639.9488040956724, 640.3372509452714, 649.4122818848942, 34884.30231395374, 37809.24381512368, 46119.53880461194, 46890.0, 47155.0, 48042.51957480424, 48702.0, 48730.26904596434, 48845.755771221186, 48858.0, 9291.11734385234, 9530.523473826315, 9536.952315238414, 9539.47532885691, 9544.093311135432, 8602.956985215096, 8819.118088191162, 11074.114070874311, 11349.716257093556, 11359.772804543903, 11405.372704501291, 11503.252288601214, 11530.711732206679, 11536.653900383002, 11544.249623774469, 8548.615312310898, 11313.886861131406, 11400.657980260605, 11400.71498212543, 11424.0, 8311.709090181876, 10692.198085143567, 11131.554737810478, 11158.665240042812, 11532.59635912739, 45691.08617827658, 46095.539044609795, 46157.30764038542, 46216.0, 46248.768756156, 3854.1906199698096, 3921.901952451183, 3930.410438434229, 4167.729097608676, 4331.393604895317, 34650.82674586611, 34874.651253487646, 34937.6506234937, 35309.47035794465, 36329.273414531825, 4212.831486740557, 4288.356746488021, 4390.297552391634, 4433.977830110861, 4524.072565124142, 389241.0, 391911.0, 392113.0, 392279.0, 392986.99999999715, 38881.80559097186, 39653.0, 39763.80118099391, 67625.66187169033, 68341.0, 41897.162056758694, 42120.78939605282, 42122.57877421248, 42152.57847421548, 42163.7891810539, 17174.656506869793, 17527.649447011245, 17534.12329383526, 17562.209700563504, 17594.38419655306, 13091.607251782461, 13392.866071339162, 14451.132932024113, 14518.128912265298, 14522.346494407777, 9404.764880877896, 9692.22462203022, 9714.688517050108, 9731.464769437749, 9815.656452024145, 3651.2514934438377, 3679.3745063339297, 3679.742418030712, 3688.483612294281, 3763.0592351912023, 1036.1400037968467, 1043.8538604595362, 1072.624581396505, 1121.8317252412203, 1168.9123315751353, 34081.82959085238, 34101.48847767286, 34244.273371238436, 34874.12814679653, 35266.29467410637, 1150.2580835361146, 1173.3546549397797, 1175.9945246814023, 1193.797054500737, 1244.2658831289582, 18021.098945052694, 18075.638487230182, 18135.63728725418, 18145.274189032556, 18238.544036399195, 559524.0, 567245.0, 569045.0, 570007.0, 570521.0, 62080.0, 62636.37363626305, 62792.05811912734, 62820.05769913455, 62982.37017629765, 1336.6658335416146, 1344.8184495093037, 1362.611655678127, 1374.3952660829202, 1463.3195564062762, 33331.666683333824, 35498.0, 35539.64460355467, 35601.0, 35628.0, 52213.7389313051, 52567.0, 52766.20850687166, 52861.0, 52907.470925290254, 431225.0, 432490.0, 434435.0, 434489.0, 436069.0, 31273.218169545944, 32311.192220194684, 32417.51373729443, 33491.66508334885, 33503.49744753782, 51177.0, 51198.23202651889, 51977.96044079173, 52256.47743522516, 52576.73711631417, 43547.34678979881, 44458.777706111265, 44977.77511112423, 45356.31965520586, 46325.76837115793, 43604.99554502531, 45374.54625453703, 46067.539324606325, 46411.30383044325, 46692.53307467018, 44010.77994610006, 44252.557474424844, 44302.0, 44363.55636443723, 44524.0, 36192.81903590465, 38098.42852357272, 39573.60426395814, 41007.79496102501, 41363.0, 29052.27369315784, 29846.0, 30046.0, 30175.245618859703, 30232.697673022987], "value": 20603.011085707076, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [395.1050869779951, 432.1292595420228, 432.8574548775888, 440.3372923749757, 450.2638186564967, 17126.828731712678, 20746.170153193852, 21713.240036598723, 21725.891370543144, 21727.02228399726, 20918.686219706717, 21020.36938891832, 21039.0, 21044.158233670674, 21223.363299101053, 5684.459976302244, 6129.754809807602, 6161.845953851167, 6168.013117901137, 6168.537359698041, 5964.739328941949, 5981.461668449839, 6032.095185722155, 6132.294786099589, 6136.263648362203, 5992.850178745544, 6053.878922421549, 6058.030715085587, 6065.939340606582, 6072.726727297282, 5772.27846519183, 5985.25184351956, 6000.669963152025, 6002.159697642333, 6094.6038507497315, 5493.2034854945805, 5495.065838807413, 5501.587380946445, 5505.449455054502, 5507.642003269774, 24556.877215613986, 24611.63082553763, 24645.63031554528, 24656.630150547757, 24860.5027899441, 2914.577386278978, 2956.4087182563358, 3032.166154307583, 3254.4142054430195, 3319.767616266862, 11021.504032318566, 11466.770664586746, 11484.71288217793, 11580.826287605692, 11599.246049006872, 1932.4009557037364, 2004.3485867093252, 2019.6667549854255, 2037.3582321568658, 2106.631339515594, 73390.63304683496, 87535.0, 88483.55758221168, 90588.0, 91791.54104229502, 10719.78560428787, 11055.447227638586, 11454.59908903201, 11591.76816463666, 11832.822507662391, 16682.749758753627, 16694.916525417295, 16715.247813848408, 16728.916355418387, 16729.330826766793, 8632.179942905414, 8869.246114080375, 8936.41913275635, 8955.0, 9037.0, 8505.0, 8553.657853685907, 8614.655413783505, 8687.174718401742, 8754.168354006359, 4987.1771157759285, 5002.399712034507, 5062.594992400599, 5063.974680126576, 5193.454687257784, 1811.7082382563517, 2101.8843963581844, 2147.7744836791912, 2180.8473406861685, 2190.5728382965167, 923.3259720404143, 1037.8235699930879, 1051.279873286801, 1055.9472026398648, 1101.1851230089835, 19999.200031998556, 20024.199032038847, 20141.597168056556, 20292.594148116954, 20412.489687757923, 996.3523709588825, 1014.7158795537262, 1027.6300531808542, 1039.781645854364, 1075.0163600305789, 11152.0, 11522.827157592643, 11537.769244615061, 11551.480183391768, 11595.0, 451842.0, 455927.0, 456040.0, 457226.0, 470697.0, 91718.54140729253, 93507.53246233726, 93683.0, 95658.52170739102, 98481.0, 993.0, 1040.245821779204, 1048.4443245079967, 1058.6188971970084, 1064.0689396777818, 16480.42318518846, 17957.67148595544, 18084.90957545204, 18277.634447311244, 18404.26382944667, 44151.77924110359, 44596.0, 44645.77677111594, 44680.77659611681, 46029.539704602525, 60193.0, 60414.0, 60629.0, 61570.69214653898, 64032.67983660052, 8311.0, 8649.308055355683, 8883.777905552413, 8923.598438070432, 8991.190792828676, 10697.518611662339, 13439.596812095653, 13795.517156899461, 14154.787678185041, 14637.129249401203, 11472.770544589228, 11784.58753943608, 11923.344216067853, 12645.367731613567, 12952.158109722837, 11307.830382544433, 11451.541938322374, 11594.768104637691, 11929.343886086, 12313.076519260912, 12607.369631518572, 12970.481180753042, 12980.701929806944, 13155.013373996797, 13205.867941320465, 8139.877901831359, 8416.873746893923, 8591.312694984512, 8679.739607811776, 8932.553372331227, 12093.879061209274, 12615.684607884508, 12862.67843303925, 12864.871351286367, 12940.352982351033], "value": 9753.97634671182, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1541.237087641617, 1582.4698725926814, 1717.4246627379828, 1835.9357422490207, 1837.3018253063833, 8440.746777596667, 9006.594703238354, 9023.23302519285, 9028.097190280969, 9035.322350823682, 8035.116137224901, 8238.17618238176, 8893.199612034916, 9056.773580660487, 9303.813923721538, 2551.974480255197, 2582.3027782498716, 2583.987080064597, 2584.043903755604, 2588.8835002424844, 2466.8766561671846, 2531.0508559290265, 2533.1134103063964, 2535.8605276709773, 2536.4546622476164, 2559.4497183105627, 2571.6014017827265, 2583.806214533908, 2589.1067581684288, 2589.741025897414, 2508.83692559983, 2541.301142185895, 2541.5171117487616, 2549.3244290263096, 2550.528152291832, 2367.3134790910617, 2400.111958575321, 2402.699662542182, 2419.4919066995953, 2446.510697860417, 9387.389819661701, 9470.715878523655, 9493.145616894513, 9494.667686631004, 9509.90490095097, 5294.894102117936, 5517.972410137923, 5519.917201241985, 5525.143602741581, 5525.281713377275, 3572.392693242129, 3636.5272514572935, 3724.0131365188245, 3733.104055026774, 3736.252749450121, 1019.0777346501428, 1056.144522936424, 1059.6132411669762, 1064.99467502662, 1094.5074716377583, 12765.297908615023, 12767.61697149087, 12786.680332991657, 12797.232166070065, 12800.935995320056, 4813.807447702123, 4989.825356112518, 5047.747612619354, 5092.337996060563, 5160.948390516121, 10822.40476773785, 10827.945860270805, 10843.78312433747, 10853.403062831461, 10853.45732713377, 5730.398308177665, 5757.798477053283, 5775.48020678141, 5786.797462088806, 5793.942060579425, 4651.069786042805, 4653.6509761768, 5162.45794191612, 5218.9739051304505, 5266.368035835648, 4020.8793736187963, 4081.0817566047403, 4165.625093741578, 4169.187008533368, 4171.457710497616, 1379.2138481065897, 1391.200059965524, 1397.4619771388088, 1400.390829988958, 1403.0669604712753, 1005.8843233028149, 1014.8071866345371, 1022.4070039377143, 1025.7640742629237, 1030.3302853145515, 6286.49708023357, 6337.904931426032, 6367.4906007519285, 6393.305773969902, 6398.744050238031, 1029.7013865978931, 1049.2235745548392, 1058.756486008207, 1070.0418234630608, 1105.4859490337033, 4363.672724545671, 4404.097160082176, 4409.867703968886, 4419.027813880941, 4529.365888775574, 11125.332480051224, 11409.42952852354, 11462.312261264351, 11473.770524589461, 11512.481938312796, 11351.205415620992, 11360.0, 11363.03414209785, 11364.431778411046, 11367.204295699386, 8036.156203598655, 8274.544900030556, 8282.213189746964, 8405.243528082501, 8560.785980350542, 6198.349173336735, 6302.243730752339, 6471.158749362553, 6585.3085426029575, 6599.835004124743, 10244.53899574506, 10298.330608510423, 10775.08411785007, 10968.067714244378, 11092.112630989663, 10325.793484130425, 10537.0, 10623.097036751962, 10640.042396184072, 10989.0, 7413.592252426169, 7573.545587264782, 7866.134725180111, 8205.79485512867, 8468.153184681483, 8869.201771840566, 8947.642094316154, 8952.552372381224, 8961.910380896108, 8967.0, 7783.066032076187, 7837.804054898673, 7857.803554911173, 8057.556834374166, 8067.435279530377, 7779.961100194463, 7904.367650587824, 7905.051393832777, 7965.681372745025, 8176.345892328718, 7390.371818395497, 7415.147258065176, 7508.43686723487, 7524.811879703051, 7526.661300241392, 5374.6506477078865, 5387.434319396407, 5430.592705547021, 5450.5367043801725, 5471.124620060771, 5530.944690553204, 5586.050371436784, 5636.408177141341, 5675.120356344608, 5680.00599895025], "value": 4911.472367242429, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [357.1963083063108, 361.3477672800595, 373.708507364256, 375.66753423220456, 378.92800367930107, 5648.463395977381, 5669.773209071643, 5674.546036317095, 5678.943210567893, 5679.829605111848, 5277.973610131953, 5591.8602034949145, 5602.5824247209275, 5608.10270356743, 5608.298962629671, 4369.470685260166, 4420.160169567772, 4429.424174857264, 4439.866803995886, 4441.733495990251, 4423.557644235583, 4426.55734426558, 4430.556944305576, 4431.711938723989, 4442.244818380883, 4406.317020861771, 4420.077266389131, 4424.712393694415, 4430.246858034126, 4435.645148388137, 4151.335786274197, 4317.848875289381, 4331.6967812253, 4349.84775532858, 4372.300431930876, 3817.0838998640124, 3846.480725102131, 3858.2862170498547, 3860.9227815443533, 3862.4592557042038, 5413.64811287269, 5449.7275136243425, 5474.288342515487, 5480.177973303997, 5485.807996720096, 4828.903421931577, 4962.206047032493, 5010.599152067863, 5015.297858299841, 5021.974890125526, 5798.855028624318, 5803.65178089316, 5818.2363377395395, 5818.3308919474375, 5823.301203855564, 4243.724157929755, 4258.254805409041, 4268.167707297047, 4271.551487093872, 4274.807633656493, 5713.685747283897, 5777.59556831022, 5846.912296315559, 5979.953508136102, 6039.5772295939305, 4251.107267473803, 4340.934885976713, 4344.9348259776125, 4348.239058164809, 4359.760213188241, 4405.546169764034, 4408.0, 4419.18245124653, 4436.334549817553, 4441.977790111029, 4669.41632295963, 4726.834560790425, 4919.532644398776, 5031.534548071419, 5035.62232832539, 5823.475887170175, 5823.767049317979, 5827.679477628686, 5854.297484301911, 5875.177475153482, 1503.150719843285, 1521.8630323270959, 1522.2617030740082, 1530.8086489188852, 1532.2185685300576, 980.2305190425624, 994.3039872089566, 1012.8936461671566, 1065.0574241796066, 1066.898644628759, 5641.689707066069, 5679.176519404746, 5680.5455563554815, 5687.971560142173, 5696.572757043238, 5980.671063091485, 5990.993017339153, 5999.970000149971, 6027.30685971119, 6037.396260373927, 5464.142191654856, 5716.771329146871, 5763.682997435181, 5770.384292398136, 5779.739911703983, 3216.453202955503, 3233.967660323414, 3235.3691030248874, 3237.9676203238137, 3246.204679853451, 6136.110264011693, 6227.412009937437, 6271.62370257786, 6272.1219029335925, 6290.496760259169, 6155.399596104955, 6162.568620196633, 6195.380461953859, 6195.411435913581, 6199.659018754011, 6089.634621922699, 6250.1874756281395, 6251.531135164881, 6271.78048768291, 6275.937240627627, 6075.149479072845, 6084.148219249397, 6088.786892458743, 6118.724657390517, 6121.84695382619, 5970.134330522137, 5970.253718285215, 5988.041913294026, 5995.280566332068, 6024.367441418588, 5793.565482588907, 5995.760169593167, 6157.84605384869, 6194.566380353513, 6219.782307619212, 4328.848490302824, 4363.825446982212, 4386.188555117314, 4395.648348132077, 4396.978015109904, 5817.447342502455, 5944.256967879015, 6061.605995610271, 6061.696915154313, 6072.90890636632, 5869.530437564899, 5884.852453771474, 5884.882302354014, 5886.0, 5888.087346461305, 5873.472897046884, 5875.236219291465, 5876.529877609866, 5887.3523912370465, 5917.644941303535, 5633.8309850704545, 5792.594518383841, 5820.70896455184, 5828.446297601721, 5844.6785426801935, 5895.233619629421, 5900.763969441175, 5916.201312822714, 5921.407859214044, 5939.881202375842, 5557.861053473695, 5735.799247026334, 5767.5674324425, 5780.624259423124, 5791.971040144772], "value": 4502.090839037273, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [797.0913158998742, 809.146350600117, 875.6366108065154, 876.9210771030608, 928.076563819, 16643.833561664378, 18178.545536361566, 18219.544511387223, 18247.817521824778, 18268.0, 15369.231538423062, 15432.922835385807, 16552.66894662106, 17244.913775431138, 17294.913525432356, 722.5664601239267, 740.9740659076933, 876.2157868707503, 895.0914821456228, 899.4648184330346, 848.9660413583449, 860.8665656823202, 874.7026011156206, 887.8401887660218, 890.5013192612125, 942.7313215733517, 946.0775743649955, 946.4794363100302, 946.8295706772778, 946.9479178645172, 771.456123432984, 778.3306356533377, 939.8778158839341, 941.5010044676299, 942.6700654770843, 659.1398225315961, 660.9438197753196, 677.4005005570057, 688.2463702246033, 688.5593220338988, 16848.66302673952, 16931.3227470901, 16963.660726785394, 16965.491035268962, 16999.490015299438, 14164.787528187086, 14554.0, 14571.489997850025, 14713.632159196106, 14718.705625887422, 14002.299885005708, 14100.788488172686, 14222.644433889236, 14488.782668259984, 14615.70768584633, 4820.662553621248, 4890.73100979446, 5010.298558201855, 5015.548600625962, 5036.345275114249, 60887.391126088616, 61421.0, 61500.99999999955, 61575.692121539556, 61584.0, 11750.23623464472, 12010.699732506757, 12183.634490965285, 12329.876701233052, 13021.348932553334, 23023.424414389774, 23063.884680576826, 23076.076956921872, 23120.884395577916, 23154.536909261722, 8496.40525163245, 8585.270252028648, 8751.737447876574, 8884.156005179497, 9284.582193801296, 5008.248762685554, 5243.449437808975, 5299.0, 5338.706371149624, 5382.67703937765, 3655.3968595182027, 3767.9434808477895, 3772.264408440383, 3775.3959366501226, 3777.660010599059, 601.3024891126272, 602.4969150759077, 610.2128254551578, 613.9201903752484, 618.9938100618937, 638.1735652330243, 647.9686183813777, 653.3597074866657, 680.5610381304031, 702.3257672634226, 9723.805523889483, 9738.269629777795, 9755.902440975642, 9790.0, 10098.545565449573, 657.8190997475637, 671.1007250284545, 696.6238231355012, 707.8478127202676, 737.7491652838061, 4536.909261814745, 4586.977065114653, 4643.95356046442, 4736.526347365305, 4746.95253047472, 83132.58433707793, 84147.0, 101825.0, 138142.0, 142014.0, 68580.6570967142, 70242.0, 90522.54738726265, 99925.50037249767, 113939.43030284795, 106979.46510267399, 108020.45989770158, 108404.45797771118, 108871.45564272287, 110978.44510777557, 8536.786580335542, 8650.524221167645, 8827.646894124164, 9011.369204155646, 9104.089591041107, 54733.72633136809, 55599.444005559424, 57777.422225777205, 59413.7029314868, 59727.402725972184, 51810.0, 74930.0, 77021.0, 78210.0, 81523.0, 13584.388702508608, 13779.242141682304, 14255.429782808571, 14282.2144782038, 14287.285635718381, 15844.52466426009, 16628.726502778394, 16695.91652041732, 16973.0, 17031.65936681284, 13710.314484275947, 14138.0, 14386.85613143897, 14579.416823327372, 15195.392184312504, 13023.0, 13519.0, 13786.862131378557, 13941.442342306194, 13981.510647127301, 14860.554183374516, 14865.554033379016, 15039.473618423302, 16006.919965400564, 16042.43851465193, 10802.945985270024, 10933.781324373309, 11062.336259824437, 11493.597724079616, 11744.412779361168, 12882.935585322013, 13031.869681303066, 13050.54323098687, 13131.0, 13448.596542103753], "value": 7966.412505333515, "unit": "score"}], "name": "dromaeo_css", "value": 7617.39735402333, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 111.0, 110.0, 111.0, 111.0, 111.0, 111.0, 110.0, 109.0, 110.0], "value": 110.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 116.0, 116.0, 164.0, 118.0, 121.0, 121.0, 119.0, 152.0, 117.0], "value": 128.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 160.0, 159.0, 161.0, 160.0, 157.0, 166.0, 169.0, 158.0, 158.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 100.0, 76.0, 76.0, 74.0, 76.0, 96.0, 74.0], "value": 79.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 116.0, 82.0, 81.0, 81.0, 102.0, 80.0, 80.0, 113.0], "value": 89.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 118.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 146.0, 147.0, 145.0, 146.0, 146.0, 147.0, 147.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 96.0, 95.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 49.0, 52.0, 48.0, 49.0, 50.0, 49.0, 50.0, 48.0, 50.0], "value": 49.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 59.0, 60.0, 61.0, 60.0, 59.0, 60.0, 60.0, 59.0], "value": 59.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 73.0, 73.0, 73.0, 73.0, 73.0, 73.0, 72.0, 73.0, 73.0], "value": 73.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 124.0, 120.0, 122.0, 126.0, 118.0, 124.0, 118.0, 164.0, 118.0], "value": 125.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 153.0, 162.0, 154.0, 154.0, 158.0, 154.0, 151.0, 154.0, 157.0], "value": 155.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 66.0, 61.0, 63.0, 62.0, 63.0, 63.0, 61.0, 61.0], "value": 62.7, "unit": "score"}], "name": "kraken", "value": 1453.6, "alertThreshold": 2.0}]} 15:12:41 INFO - Return code: 0 15:12:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:12:41 INFO - # TBPL SUCCESS # 15:12:41 INFO - Running post-action listener: _resource_record_post_action 15:12:41 INFO - [mozharness: 2016-09-20 22:12:41.885754Z] Finished run-tests step (success) 15:12:41 INFO - Running post-run listener: _resource_record_post_run 15:12:41 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 487424; Write bytes: 364998656; Read time: 396; Write time: 484936 15:12:41 INFO - TinderboxPrint: CPU usage
13.0% 15:12:41 INFO - TinderboxPrint: I/O read bytes / time
487,424 / 396 15:12:41 INFO - TinderboxPrint: I/O write bytes / time
364,998,656 / 484,936 15:12:41 INFO - TinderboxPrint: CPU idle
8,277.0 (87.0%) 15:12:41 INFO - TinderboxPrint: CPU user
1,183.6 (12.4%) 15:12:41 INFO - TinderboxPrint: Swap in / out
0 / 0 15:12:41 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 4096; Write bytes: 122843136; Read time: 20; Write time: 201484 15:12:41 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 483328; Write bytes: 242155520; Read time: 376; Write time: 283452 15:12:42 INFO - Running post-run listener: _upload_blobber_files 15:12:42 INFO - Blob upload gear active. 15:12:42 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:12:42 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 15:12:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:12:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:12:42 INFO - (blobuploader) - INFO - Open directory for files ... 15:12:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:12:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:12:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:12:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:12:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:12:47 INFO - (blobuploader) - INFO - Done attempting. 15:12:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:12:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:12:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:12:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:12:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:12:48 INFO - (blobuploader) - INFO - Done attempting. 15:12:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:12:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:12:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:12:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:12:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:12:49 INFO - (blobuploader) - INFO - Done attempting. 15:12:49 INFO - (blobuploader) - INFO - Iteration through files over. 15:12:49 INFO - Return code: 0 15:12:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:12:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:12:49 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/497743a2fb4907c24eb3623637f338f9f35fdf4ec912787acfe4e53e2ff28f03e52e114a8af4d3be91fb9461285d13bf77ea3ba0ed5030c0dccfbde26afc7e84", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/696cca0ae2e1126a864039819faf89f4e54502500da689dd2cc1f4335caa4e4215991cd28e845426d4ce8f0569c5395f36cb85e8e52f85629e64edc85e2202ed", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/41e2cdf00c32e82f61a41e97d3e0e3ee756ea34c3bfb6b2300debb60d878dc3c3e71b37cca2d9edae7232d90d6f58b2136d66aef4ebb296d78baad756703e838"} 15:12:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:12:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:12:49 INFO - Contents: 15:12:49 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/497743a2fb4907c24eb3623637f338f9f35fdf4ec912787acfe4e53e2ff28f03e52e114a8af4d3be91fb9461285d13bf77ea3ba0ed5030c0dccfbde26afc7e84", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/696cca0ae2e1126a864039819faf89f4e54502500da689dd2cc1f4335caa4e4215991cd28e845426d4ce8f0569c5395f36cb85e8e52f85629e64edc85e2202ed", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/41e2cdf00c32e82f61a41e97d3e0e3ee756ea34c3bfb6b2300debb60d878dc3c3e71b37cca2d9edae7232d90d6f58b2136d66aef4ebb296d78baad756703e838"} 15:12:49 INFO - Running post-run listener: copy_logs_to_upload_dir 15:12:49 INFO - Copying logs to upload dir... 15:12:49 INFO - mkdir: /builds/slave/test/build/upload/logs 15:12:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1232.409866 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 32 secs) (at 2016-09-20 15:12:49.103023) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-20 15:12:49.107106) ========= 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=745a4373a2a16df560f216e000000264-1474408334.921985-1480842233 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/497743a2fb4907c24eb3623637f338f9f35fdf4ec912787acfe4e53e2ff28f03e52e114a8af4d3be91fb9461285d13bf77ea3ba0ed5030c0dccfbde26afc7e84", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/696cca0ae2e1126a864039819faf89f4e54502500da689dd2cc1f4335caa4e4215991cd28e845426d4ce8f0569c5395f36cb85e8e52f85629e64edc85e2202ed", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/41e2cdf00c32e82f61a41e97d3e0e3ee756ea34c3bfb6b2300debb60d878dc3c3e71b37cca2d9edae7232d90d6f58b2136d66aef4ebb296d78baad756703e838"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474391126/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011339 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-linux64/1474391126/firefox-51.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/497743a2fb4907c24eb3623637f338f9f35fdf4ec912787acfe4e53e2ff28f03e52e114a8af4d3be91fb9461285d13bf77ea3ba0ed5030c0dccfbde26afc7e84", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/696cca0ae2e1126a864039819faf89f4e54502500da689dd2cc1f4335caa4e4215991cd28e845426d4ce8f0569c5395f36cb85e8e52f85629e64edc85e2202ed", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/41e2cdf00c32e82f61a41e97d3e0e3ee756ea34c3bfb6b2300debb60d878dc3c3e71b37cca2d9edae7232d90d6f58b2136d66aef4ebb296d78baad756703e838"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-20 15:12:49.132697) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 15:12:49.133018) ========= 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=745a4373a2a16df560f216e000000264-1474408334.921985-1480842233 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004535 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 15:12:49.218909) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-20 15:12:49.219252) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-20 15:12:49.219583) ========= ========= Total master_lag: 0.18 =========