builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-023 starttime: 1474376795.05 results: success (0) buildid: 20160920030429 builduid: 2ee4a62913f548db8194aef004d4f91c revision: 62f79d676e0e11b3ad59a5425b3ebb3ec5bbefb5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-20 06:06:35.053537) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-20 06:06:35.054217) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-20 06:06:35.054582) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-20 06:06:35.069165) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 06:06:35.069648) ========= 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=6d15e502d8e29d5d2676d664000001c3-1474376794.784953-1940298164 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-20 06:06:35-- 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% 52.7M=0s 2016-09-20 06:06:35 (52.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.376692 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 06:06:35.460287) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 06:06:35.460594) ========= 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=6d15e502d8e29d5d2676d664000001c3-1474376794.784953-1940298164 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018605 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 06:06:35.510273) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 06:06:35.510627) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 62f79d676e0e11b3ad59a5425b3ebb3ec5bbefb5 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 62f79d676e0e11b3ad59a5425b3ebb3ec5bbefb5 --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=6d15e502d8e29d5d2676d664000001c3-1474376794.784953-1940298164 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-20 06:06:36,064 truncating revision to first 12 chars 2016-09-20 06:06:36,064 Setting DEBUG logging. 2016-09-20 06:06:36,064 attempt 1/10 2016-09-20 06:06:36,064 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/62f79d676e0e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-20 06:06:36,355 unpacking tar archive at: mozilla-central-62f79d676e0e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.696650 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 06:06:36.221237) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-20 06:06:36.221547) ========= script_repo_revision: 62f79d676e0e11b3ad59a5425b3ebb3ec5bbefb5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-20 06:06:36.221947) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-20 06:06:36.222216) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-20 06:06:36.232686) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-09-20 06:06:36.232986) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=6d15e502d8e29d5d2676d664000001c3-1474376794.784953-1940298164 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 06:06:36 INFO - MultiFileLogger online at 20160920 06:06:36 in /builds/slave/test 06:06:36 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 06:06:36 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:06:36 INFO - {'append_to_log': False, 06:06:36 INFO - 'base_work_dir': '/builds/slave/test', 06:06:36 INFO - 'blob_upload_branch': 'Firefox', 06:06:36 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:06:36 INFO - 'branch': 'Firefox', 06:06:36 INFO - 'buildbot_json_path': 'buildprops.json', 06:06:36 INFO - 'config_files': ('talos/linux_config.py',), 06:06:36 INFO - 'default_actions': ('clobber', 06:06:36 INFO - 'read-buildbot-config', 06:06:36 INFO - 'download-and-extract', 06:06:36 INFO - 'populate-webroot', 06:06:36 INFO - 'create-virtualenv', 06:06:36 INFO - 'install', 06:06:36 INFO - 'run-tests'), 06:06:36 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:06:36 INFO - 'download_minidump_stackwalk': True, 06:06:36 INFO - 'download_symbols': 'ondemand', 06:06:36 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:06:36 INFO - 'tooltool.py': '/tools/tooltool.py', 06:06:36 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:06:36 INFO - '/tools/misc-python/virtualenv.py')}, 06:06:36 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:06:36 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:06:36 INFO - 'installer_path': 'installer.exe', 06:06:36 INFO - 'log_level': 'info', 06:06:36 INFO - 'log_name': 'talos', 06:06:36 INFO - 'log_to_console': True, 06:06:36 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 06:06:36 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 06:06:36 INFO - 'opt_config_files': (), 06:06:36 INFO - 'pip_index': False, 06:06:36 INFO - 'sps_profile': False, 06:06:36 INFO - 'sps_profile_interval': 0, 06:06:36 INFO - 'suite': 'dromaeojs', 06:06:36 INFO - 'system_bits': '32', 06:06:36 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:06:36 INFO - 'title': 'talos-linux64-ix-023', 06:06:36 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:06:36 INFO - 'use_talos_json': True, 06:06:36 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:06:36 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:06:36 INFO - 'work_dir': 'build'} 06:06:36 INFO - [mozharness: 2016-09-20 13:06:36.874856Z] Running clobber step. 06:06:36 INFO - Running pre-action listener: _resource_record_pre_action 06:06:36 INFO - Running main action method: clobber 06:06:36 INFO - rmtree: /builds/slave/test/build 06:06:36 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:06:37 INFO - Running post-action listener: _resource_record_post_action 06:06:37 INFO - [mozharness: 2016-09-20 13:06:37.337353Z] Finished clobber step (success) 06:06:37 INFO - [mozharness: 2016-09-20 13:06:37.337455Z] Running read-buildbot-config step. 06:06:37 INFO - Running pre-action listener: _resource_record_pre_action 06:06:37 INFO - Running main action method: read_buildbot_config 06:06:37 INFO - Using buildbot properties: 06:06:37 INFO - { 06:06:37 INFO - "project": "", 06:06:37 INFO - "product": "firefox", 06:06:37 INFO - "script_repo_revision": "production", 06:06:37 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 06:06:37 INFO - "repository": "", 06:06:37 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 06:06:37 INFO - "buildid": "20160920030429", 06:06:37 INFO - "stage_platform": "linux64-pgo", 06:06:37 INFO - "basedir": "/builds/slave/test", 06:06:37 INFO - "nightly_build": "True", 06:06:37 INFO - "buildnumber": 582, 06:06:37 INFO - "slavename": "talos-linux64-ix-023", 06:06:37 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 06:06:37 INFO - "platform": "ubuntu64_hw", 06:06:37 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 06:06:37 INFO - "branch": "mozilla-central", 06:06:37 INFO - "repo_path": "mozilla-central", 06:06:37 INFO - "revision": "62f79d676e0e11b3ad59a5425b3ebb3ec5bbefb5", 06:06:37 INFO - "pgo_build": "True", 06:06:37 INFO - "builduid": "2ee4a62913f548db8194aef004d4f91c", 06:06:37 INFO - "slavebuilddir": "test" 06:06:37 INFO - } 06:06:37 INFO - Found installer url https://queue.taskcluster.net/v1/task/YMqburc_Q9WQoBaqvvA8yA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2. 06:06:37 INFO - Running post-action listener: _resource_record_post_action 06:06:37 INFO - [mozharness: 2016-09-20 13:06:37.339973Z] Finished read-buildbot-config step (success) 06:06:37 INFO - [mozharness: 2016-09-20 13:06:37.340063Z] Running download-and-extract step. 06:06:37 INFO - Running pre-action listener: _resource_record_pre_action 06:06:37 INFO - Running main action method: download_and_extract 06:06:37 INFO - mkdir: /builds/slave/test/build/tests 06:06:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:06:37 INFO - trying https://queue.taskcluster.net/v1/task/YMqburc_Q9WQoBaqvvA8yA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 06:06:37 INFO - Downloading https://queue.taskcluster.net/v1/task/YMqburc_Q9WQoBaqvvA8yA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 06:06:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YMqburc_Q9WQoBaqvvA8yA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 06:06:37 INFO - Downloaded 1361 bytes. 06:06:37 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 06:06:37 INFO - Using the following test package requirements: 06:06:37 INFO - {u'common': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 06:06:37 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 06:06:37 INFO - u'firefox-52.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 06:06:37 INFO - u'gtest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 06:06:37 INFO - u'firefox-52.0a1.en-US.linux-x86_64.gtest.tests.zip'], 06:06:37 INFO - u'jittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 06:06:37 INFO - u'jsshell-linux-x86_64.zip'], 06:06:37 INFO - u'mochitest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 06:06:37 INFO - u'firefox-52.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 06:06:37 INFO - u'mozbase': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 06:06:37 INFO - u'reftest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 06:06:37 INFO - u'firefox-52.0a1.en-US.linux-x86_64.reftest.tests.zip'], 06:06:37 INFO - u'talos': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 06:06:37 INFO - u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'], 06:06:37 INFO - u'web-platform': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 06:06:37 INFO - u'firefox-52.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 06:06:37 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 06:06:37 INFO - u'firefox-52.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 06:06:37 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 06:06:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YMqburc_Q9WQoBaqvvA8yA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 06:06:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YMqburc_Q9WQoBaqvvA8yA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 06:06:37 INFO - Fetch https://queue.taskcluster.net/v1/task/YMqburc_Q9WQoBaqvvA8yA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 06:06:38 INFO - Expected file size: 21941008 06:06:42 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 06:06:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YMqburc_Q9WQoBaqvvA8yA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 06:06:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YMqburc_Q9WQoBaqvvA8yA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 06:06:42 INFO - Fetch https://queue.taskcluster.net/v1/task/YMqburc_Q9WQoBaqvvA8yA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 06:06:43 INFO - Expected file size: 21941008 06:06:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YMqburc_Q9WQoBaqvvA8yA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip 06:06:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YMqburc_Q9WQoBaqvvA8yA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 06:06:48 INFO - Fetch https://queue.taskcluster.net/v1/task/YMqburc_Q9WQoBaqvvA8yA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip into memory 06:06:48 INFO - Expected file size: 14414059 06:06: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')]} 06:06:49 INFO - trying https://queue.taskcluster.net/v1/task/YMqburc_Q9WQoBaqvvA8yA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 06:06:49 INFO - Downloading https://queue.taskcluster.net/v1/task/YMqburc_Q9WQoBaqvvA8yA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 06:06:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YMqburc_Q9WQoBaqvvA8yA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 06:06:50 INFO - Downloaded 59579576 bytes. 06:06:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YMqburc_Q9WQoBaqvvA8yA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 06:06:50 INFO - mkdir: /builds/slave/test/properties 06:06:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:06:50 INFO - Writing to file /builds/slave/test/properties/build_url 06:06:50 INFO - Contents: 06:06:50 INFO - build_url:https://queue.taskcluster.net/v1/task/YMqburc_Q9WQoBaqvvA8yA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 06:06:51 INFO - Running post-action listener: _resource_record_post_action 06:06:51 INFO - Running post-action listener: set_extra_try_arguments 06:06:51 INFO - [mozharness: 2016-09-20 13:06:51.385881Z] Finished download-and-extract step (success) 06:06:51 INFO - [mozharness: 2016-09-20 13:06:51.386113Z] Running populate-webroot step. 06:06:51 INFO - Running pre-action listener: _resource_record_pre_action 06:06:51 INFO - Running main action method: populate_webroot 06:06:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:06:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:06:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:06:51 INFO - '--tppagecycles', 06:06:51 INFO - '7'], 06:06:51 INFO - 'tests': ['tsvgm']}}, 06:06:51 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:06:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:06:51 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:06:51 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:06:51 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:06:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:06:51 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:06:51 INFO - 'talos_options': ['--disable-e10s'], 06:06:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:06:51 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:06:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:06:51 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:06:51 INFO - 'talos_options': ['--disable-e10s'], 06:06:51 INFO - 'tests': ['damp', 'tps']}, 06:06:51 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:06:51 INFO - 'tests': ['damp', 'tps']}, 06:06:51 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:06:51 INFO - 'tests': ['dromaeo_dom']}, 06:06:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:06:51 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:06:51 INFO - 'tests': ['basic_compositor_video']}, 06:06:51 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 06:06:51 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:06:51 INFO - 'tests': ['a11yr', 06:06:51 INFO - 'ts_paint', 06:06:51 INFO - 'tpaint', 06:06:51 INFO - 'sessionrestore', 06:06:51 INFO - 'sessionrestore_no_auto_restore', 06:06:51 INFO - 'tabpaint']}, 06:06:51 INFO - 'other-e10s': {'tests': ['a11yr', 06:06:51 INFO - 'ts_paint', 06:06:51 INFO - 'tpaint', 06:06:51 INFO - 'sessionrestore', 06:06:51 INFO - 'sessionrestore_no_auto_restore', 06:06:51 INFO - 'tabpaint']}, 06:06:51 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:06:51 INFO - 'ts_paint', 06:06:51 INFO - 'tpaint', 06:06:51 INFO - 'sessionrestore', 06:06:51 INFO - 'sessionrestore_no_auto_restore', 06:06:51 INFO - 'tabpaint']}, 06:06:51 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:06:51 INFO - 'ts_paint', 06:06:51 INFO - 'tpaint', 06:06:51 INFO - 'sessionrestore', 06:06:51 INFO - 'sessionrestore_no_auto_restore', 06:06:51 INFO - 'tabpaint']}, 06:06:51 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:06:51 INFO - 'tests': ['a11yr', 06:06:51 INFO - 'ts_paint', 06:06:51 INFO - 'tpaint', 06:06:51 INFO - 'sessionrestore', 06:06:51 INFO - 'sessionrestore_no_auto_restore', 06:06:51 INFO - 'tabpaint']}, 06:06:51 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:06:51 INFO - 'tests': ['a11yr', 06:06:51 INFO - 'ts_paint', 06:06:51 INFO - 'tpaint', 06:06:51 INFO - 'sessionrestore', 06:06:51 INFO - 'sessionrestore_no_auto_restore', 06:06:51 INFO - 'tabpaint']}, 06:06:51 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:06:51 INFO - 'tests': ['tsvgx', 06:06:51 INFO - 'tsvgr_opacity', 06:06:51 INFO - 'tart', 06:06:51 INFO - 'tscrollx', 06:06:51 INFO - 'cart']}, 06:06:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:06:51 INFO - 'tsvgr_opacity', 06:06:51 INFO - 'tart', 06:06:51 INFO - 'tscrollx', 06:06:51 INFO - 'cart']}, 06:06:51 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:06:51 INFO - 'talos_options': ['--disable-e10s'], 06:06:51 INFO - 'tests': ['tp5o']}, 06:06:51 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:06:51 INFO - 'tests': ['tp5o']}, 06:06:51 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:06:51 INFO - 'talos_options': ['--disable-e10s', 06:06:51 INFO - '--xperf_path', 06:06:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:06:51 INFO - 'tests': ['tp5n']}, 06:06:51 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:06:51 INFO - 'talos_options': ['--xperf_path', 06:06:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:06:51 INFO - 'tests': ['tp5n']}}, 06:06:51 INFO - 'talos.zip': {'path': '', 06:06:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:06:51 INFO - Running post-action listener: _resource_record_post_action 06:06:51 INFO - [mozharness: 2016-09-20 13:06:51.403927Z] Finished populate-webroot step (success) 06:06:51 INFO - [mozharness: 2016-09-20 13:06:51.404016Z] Running create-virtualenv step. 06:06:51 INFO - Running pre-action listener: _resource_record_pre_action 06:06:51 INFO - Running main action method: create_virtualenv 06:06:51 INFO - Creating virtualenv /builds/slave/test/build/venv 06:06:51 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 06:06:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:06:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:06:51 INFO - Using real prefix '/usr' 06:06:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:06:52 INFO - Installing distribute.............................................................................................................................................................................................done. 06:06:53 INFO - Installing pip.................done. 06:06:53 INFO - Return code: 0 06:06:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:06:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:06:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:06:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:06:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:06:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:06:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:06:53 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 0x7f24e4514f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13188a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13e47b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1473790>, '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 0x1474690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1474b20>, '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': '6d15e502d8e29d5d2676d664000001c3-1474376794.784953-1940298164', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 06:06:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 06:06:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 06:06:53 INFO - Using env: {'DISPLAY': ':0', 06:06:53 INFO - 'HOME': '/home/cltbld', 06:06:53 INFO - 'LANG': 'en_US.UTF-8', 06:06:53 INFO - 'LANGUAGE': 'en_US:en', 06:06:53 INFO - 'LOGNAME': 'cltbld', 06:06:53 INFO - 'MAIL': '/var/mail/cltbld', 06:06:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:06:53 INFO - 'MOZ_NO_REMOTE': '1', 06:06:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:06:53 INFO - 'NO_EM_RESTART': '1', 06:06:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:06:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:06:53 INFO - 'PWD': '/builds/slave/test', 06:06:53 INFO - 'SHELL': '/bin/bash', 06:06:53 INFO - 'SHLVL': '1', 06:06:53 INFO - 'TERM': 'linux', 06:06:53 INFO - 'TMOUT': '86400', 06:06:53 INFO - 'USER': 'cltbld', 06:06:53 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1474376794.784953-1940298164', 06:06:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:06:53 INFO - '_': '/tools/buildbot/bin/python'} 06:06:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:06:54 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 06:06:54 INFO - Cleaning up... 06:06:54 INFO - Return code: 0 06:06:54 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:06:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:06:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:06:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:06:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:06:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:06:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:06:54 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 0x7f24e4514f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13188a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13e47b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1473790>, '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 0x1474690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1474b20>, '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': '6d15e502d8e29d5d2676d664000001c3-1474376794.784953-1940298164', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 06:06:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 06:06:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 06:06:54 INFO - Using env: {'DISPLAY': ':0', 06:06:54 INFO - 'HOME': '/home/cltbld', 06:06:54 INFO - 'LANG': 'en_US.UTF-8', 06:06:54 INFO - 'LANGUAGE': 'en_US:en', 06:06:54 INFO - 'LOGNAME': 'cltbld', 06:06:54 INFO - 'MAIL': '/var/mail/cltbld', 06:06:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:06:54 INFO - 'MOZ_NO_REMOTE': '1', 06:06:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:06:54 INFO - 'NO_EM_RESTART': '1', 06:06:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:06:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:06:54 INFO - 'PWD': '/builds/slave/test', 06:06:54 INFO - 'SHELL': '/bin/bash', 06:06:54 INFO - 'SHLVL': '1', 06:06:54 INFO - 'TERM': 'linux', 06:06:54 INFO - 'TMOUT': '86400', 06:06:54 INFO - 'USER': 'cltbld', 06:06:54 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1474376794.784953-1940298164', 06:06:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:06:54 INFO - '_': '/tools/buildbot/bin/python'} 06:06:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:06:54 INFO - Downloading/unpacking psutil>=3.1.1 06:06:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:06:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:06:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:06:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:06:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:06:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:06:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:06:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:06:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:06:56 INFO - Installing collected packages: psutil 06:06:56 INFO - Running setup.py install for psutil 06:06:56 INFO - building 'psutil._psutil_linux' extension 06:06:56 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 06:06:56 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 06:06:56 INFO - building 'psutil._psutil_posix' extension 06:06:56 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 06:06:56 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 06:06:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:06:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:06:56 INFO - Successfully installed psutil 06:06:56 INFO - Cleaning up... 06:06:56 INFO - Return code: 0 06:06:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:06:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:06:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:06:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:06:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:06:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:06:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:06:56 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 0x7f24e4514f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13188a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13e47b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1473790>, '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 0x1474690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1474b20>, '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': '6d15e502d8e29d5d2676d664000001c3-1474376794.784953-1940298164', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 06:06:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 06:06:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 06:06:56 INFO - Using env: {'DISPLAY': ':0', 06:06:56 INFO - 'HOME': '/home/cltbld', 06:06:56 INFO - 'LANG': 'en_US.UTF-8', 06:06:56 INFO - 'LANGUAGE': 'en_US:en', 06:06:56 INFO - 'LOGNAME': 'cltbld', 06:06:56 INFO - 'MAIL': '/var/mail/cltbld', 06:06:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:06:56 INFO - 'MOZ_NO_REMOTE': '1', 06:06:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:06:56 INFO - 'NO_EM_RESTART': '1', 06:06:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:06:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:06:56 INFO - 'PWD': '/builds/slave/test', 06:06:56 INFO - 'SHELL': '/bin/bash', 06:06:56 INFO - 'SHLVL': '1', 06:06:56 INFO - 'TERM': 'linux', 06:06:56 INFO - 'TMOUT': '86400', 06:06:56 INFO - 'USER': 'cltbld', 06:06:56 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1474376794.784953-1940298164', 06:06:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:06:56 INFO - '_': '/tools/buildbot/bin/python'} 06:06:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:06:57 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:06:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:06:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:06:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:06:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:06:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:06:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:06:59 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:06:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:06:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:06:59 INFO - Installing collected packages: mozsystemmonitor 06:06:59 INFO - Running setup.py install for mozsystemmonitor 06:06:59 INFO - Successfully installed mozsystemmonitor 06:06:59 INFO - Cleaning up... 06:06:59 INFO - Return code: 0 06:06:59 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:06:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:06:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:06:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:06:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:06:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:06:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:06:59 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 0x7f24e4514f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13188a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13e47b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1473790>, '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 0x1474690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1474b20>, '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': '6d15e502d8e29d5d2676d664000001c3-1474376794.784953-1940298164', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 06:06:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 06:06:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 06:06:59 INFO - Using env: {'DISPLAY': ':0', 06:06:59 INFO - 'HOME': '/home/cltbld', 06:06:59 INFO - 'LANG': 'en_US.UTF-8', 06:06:59 INFO - 'LANGUAGE': 'en_US:en', 06:06:59 INFO - 'LOGNAME': 'cltbld', 06:06:59 INFO - 'MAIL': '/var/mail/cltbld', 06:06:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:06:59 INFO - 'MOZ_NO_REMOTE': '1', 06:06:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:06:59 INFO - 'NO_EM_RESTART': '1', 06:06:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:06:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:06:59 INFO - 'PWD': '/builds/slave/test', 06:06:59 INFO - 'SHELL': '/bin/bash', 06:06:59 INFO - 'SHLVL': '1', 06:06:59 INFO - 'TERM': 'linux', 06:06:59 INFO - 'TMOUT': '86400', 06:06:59 INFO - 'USER': 'cltbld', 06:06:59 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1474376794.784953-1940298164', 06:06:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:06:59 INFO - '_': '/tools/buildbot/bin/python'} 06:06:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:06:59 INFO - Downloading/unpacking jsonschema==2.5.1 06:06:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:06:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:06:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:06:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:06:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:06:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:07:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:07:01 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:07:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:07:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:07:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:07:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:07:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:07:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:07:01 INFO - Downloading functools32-3.2.3-2.tar.gz 06:07:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:07:01 INFO - warning: no files found matching '*.txt' 06:07:01 INFO - no previously-included directories found matching 'build' 06:07:01 INFO - no previously-included directories found matching 'dist' 06:07:01 INFO - no previously-included directories found matching '.git*' 06:07:01 INFO - Installing collected packages: jsonschema, functools32 06:07:01 INFO - Running setup.py install for functools32 06:07:01 INFO - warning: no files found matching '*.txt' 06:07:01 INFO - no previously-included directories found matching 'build' 06:07:01 INFO - no previously-included directories found matching 'dist' 06:07:01 INFO - no previously-included directories found matching '.git*' 06:07:01 INFO - Successfully installed jsonschema functools32 06:07:01 INFO - Cleaning up... 06:07:01 INFO - Return code: 0 06:07:01 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:07:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:07:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:07:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:01 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 0x7f24e4514f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13188a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13e47b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1473790>, '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 0x1474690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1474b20>, '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': '6d15e502d8e29d5d2676d664000001c3-1474376794.784953-1940298164', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 06:07:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 06:07:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 06:07:01 INFO - Using env: {'DISPLAY': ':0', 06:07:01 INFO - 'HOME': '/home/cltbld', 06:07:01 INFO - 'LANG': 'en_US.UTF-8', 06:07:01 INFO - 'LANGUAGE': 'en_US:en', 06:07:01 INFO - 'LOGNAME': 'cltbld', 06:07:01 INFO - 'MAIL': '/var/mail/cltbld', 06:07:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:07:01 INFO - 'MOZ_NO_REMOTE': '1', 06:07:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:07:01 INFO - 'NO_EM_RESTART': '1', 06:07:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:07:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:07:01 INFO - 'PWD': '/builds/slave/test', 06:07:01 INFO - 'SHELL': '/bin/bash', 06:07:01 INFO - 'SHLVL': '1', 06:07:01 INFO - 'TERM': 'linux', 06:07:01 INFO - 'TMOUT': '86400', 06:07:01 INFO - 'USER': 'cltbld', 06:07:01 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1474376794.784953-1940298164', 06:07:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:07:01 INFO - '_': '/tools/buildbot/bin/python'} 06:07:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:07:02 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:07:02 INFO - Cleaning up... 06:07:02 INFO - Return code: 0 06:07:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:07:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:07:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:07:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:02 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 0x7f24e4514f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13188a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13e47b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1473790>, '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 0x1474690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1474b20>, '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': '6d15e502d8e29d5d2676d664000001c3-1474376794.784953-1940298164', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 06:07:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 06:07:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 06:07:02 INFO - Using env: {'DISPLAY': ':0', 06:07:02 INFO - 'HOME': '/home/cltbld', 06:07:02 INFO - 'LANG': 'en_US.UTF-8', 06:07:02 INFO - 'LANGUAGE': 'en_US:en', 06:07:02 INFO - 'LOGNAME': 'cltbld', 06:07:02 INFO - 'MAIL': '/var/mail/cltbld', 06:07:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:07:02 INFO - 'MOZ_NO_REMOTE': '1', 06:07:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:07:02 INFO - 'NO_EM_RESTART': '1', 06:07:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:07:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:07:02 INFO - 'PWD': '/builds/slave/test', 06:07:02 INFO - 'SHELL': '/bin/bash', 06:07:02 INFO - 'SHLVL': '1', 06:07:02 INFO - 'TERM': 'linux', 06:07:02 INFO - 'TMOUT': '86400', 06:07:02 INFO - 'USER': 'cltbld', 06:07:02 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1474376794.784953-1940298164', 06:07:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:07:02 INFO - '_': '/tools/buildbot/bin/python'} 06:07:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:07:02 INFO - Downloading/unpacking blobuploader==1.2.4 06:07:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:07:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:07:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:07:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:07:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:07:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:07:04 INFO - Downloading blobuploader-1.2.4.tar.gz 06:07:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:07:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:07:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:07:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:07:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:07:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:07:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:07:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:07:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:07:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:07:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:07:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:07:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:07:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:07:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:07:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:07:04 INFO - Downloading docopt-0.6.1.tar.gz 06:07:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:07:04 INFO - Installing collected packages: blobuploader, requests, docopt 06:07:04 INFO - Running setup.py install for blobuploader 06:07:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:07:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:07:05 INFO - Running setup.py install for requests 06:07:05 INFO - Running setup.py install for docopt 06:07:05 INFO - Successfully installed blobuploader requests docopt 06:07:05 INFO - Cleaning up... 06:07:05 INFO - Return code: 0 06:07:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:07: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')]} 06:07:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:07:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07: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')]} 06:07:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:07:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:05 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 0x7f24e4514f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13188a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13e47b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1473790>, '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 0x1474690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1474b20>, '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': '6d15e502d8e29d5d2676d664000001c3-1474376794.784953-1940298164', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 06:07:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 06:07:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:07:05 INFO - Using env: {'DISPLAY': ':0', 06:07:05 INFO - 'HOME': '/home/cltbld', 06:07:05 INFO - 'LANG': 'en_US.UTF-8', 06:07:05 INFO - 'LANGUAGE': 'en_US:en', 06:07:05 INFO - 'LOGNAME': 'cltbld', 06:07:05 INFO - 'MAIL': '/var/mail/cltbld', 06:07:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:07:05 INFO - 'MOZ_NO_REMOTE': '1', 06:07:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:07:05 INFO - 'NO_EM_RESTART': '1', 06:07:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:07:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:07:05 INFO - 'PWD': '/builds/slave/test', 06:07:05 INFO - 'SHELL': '/bin/bash', 06:07:05 INFO - 'SHLVL': '1', 06:07:05 INFO - 'TERM': 'linux', 06:07:05 INFO - 'TMOUT': '86400', 06:07:05 INFO - 'USER': 'cltbld', 06:07:05 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1474376794.784953-1940298164', 06:07:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:07:05 INFO - '_': '/tools/buildbot/bin/python'} 06:07:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:07:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:07:05 INFO - Running setup.py (path:/tmp/pip-FJ97q2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:07:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:07:05 INFO - Running setup.py (path:/tmp/pip-sCKcIp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:07:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:07:05 INFO - Running setup.py (path:/tmp/pip-uiJxYq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:07:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:07:05 INFO - Running setup.py (path:/tmp/pip-tat5vL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:07:06 INFO - Running setup.py (path:/tmp/pip-keC9oQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:07:06 INFO - Running setup.py (path:/tmp/pip-MSp2Kk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:07:06 INFO - Running setup.py (path:/tmp/pip-Ica89w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:07:06 INFO - Running setup.py (path:/tmp/pip-B7Tp_M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:07:06 INFO - Running setup.py (path:/tmp/pip-XJF6_J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:07:06 INFO - Running setup.py (path:/tmp/pip-4DsC2E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:07:06 INFO - Running setup.py (path:/tmp/pip-KpUXJ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:07:06 INFO - Running setup.py (path:/tmp/pip-IthIzy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:07:06 INFO - Running setup.py (path:/tmp/pip-pOXaPZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:07:06 INFO - Running setup.py (path:/tmp/pip-eobUPT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:07:06 INFO - Running setup.py (path:/tmp/pip-pya0O2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:07:06 INFO - Running setup.py (path:/tmp/pip-sxQzyM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:07:06 INFO - Running setup.py (path:/tmp/pip-N3Cbo7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:07:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:07:06 INFO - Running setup.py install for manifestparser 06:07:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:07:07 INFO - Running setup.py install for mozcrash 06:07:07 INFO - Running setup.py install for mozdebug 06:07:07 INFO - Running setup.py install for mozdevice 06:07:07 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:07:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:07:07 INFO - Running setup.py install for mozfile 06:07:07 INFO - Running setup.py install for mozhttpd 06:07:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:07:07 INFO - Running setup.py install for mozinfo 06:07:07 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:07:07 INFO - Running setup.py install for mozInstall 06:07:07 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:07:07 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:07:07 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:07:07 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:07:07 INFO - Running setup.py install for mozleak 06:07:07 INFO - Running setup.py install for mozlog 06:07:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:07:08 INFO - Running setup.py install for moznetwork 06:07:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:07:08 INFO - Running setup.py install for mozprocess 06:07:08 INFO - Running setup.py install for mozprofile 06:07:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:07:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:07:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:07:08 INFO - Running setup.py install for mozrunner 06:07:08 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:07:08 INFO - Running setup.py install for mozscreenshot 06:07:08 INFO - Running setup.py install for moztest 06:07:08 INFO - Running setup.py install for mozversion 06:07:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:07:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:07:08 INFO - Cleaning up... 06:07:08 INFO - Return code: 0 06:07:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:07:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:07:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:07:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:08 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 0x7f24e4514f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13188a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13e47b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1473790>, '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 0x1474690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1474b20>, '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': '6d15e502d8e29d5d2676d664000001c3-1474376794.784953-1940298164', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 06:07:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 06:07:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:07:08 INFO - Using env: {'DISPLAY': ':0', 06:07:08 INFO - 'HOME': '/home/cltbld', 06:07:08 INFO - 'LANG': 'en_US.UTF-8', 06:07:08 INFO - 'LANGUAGE': 'en_US:en', 06:07:08 INFO - 'LOGNAME': 'cltbld', 06:07:08 INFO - 'MAIL': '/var/mail/cltbld', 06:07:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:07:08 INFO - 'MOZ_NO_REMOTE': '1', 06:07:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:07:08 INFO - 'NO_EM_RESTART': '1', 06:07:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:07:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:07:08 INFO - 'PWD': '/builds/slave/test', 06:07:08 INFO - 'SHELL': '/bin/bash', 06:07:08 INFO - 'SHLVL': '1', 06:07:08 INFO - 'TERM': 'linux', 06:07:08 INFO - 'TMOUT': '86400', 06:07:08 INFO - 'USER': 'cltbld', 06:07:08 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1474376794.784953-1940298164', 06:07:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:07:08 INFO - '_': '/tools/buildbot/bin/python'} 06:07:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:07:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:07:09 INFO - Running setup.py (path:/tmp/pip-rP9Ru2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:07:09 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 06:07:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:07:09 INFO - Running setup.py (path:/tmp/pip-zwFEHD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:07:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:07:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:07:09 INFO - Running setup.py (path:/tmp/pip-GWPy8n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:07:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 06:07:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:07:09 INFO - Running setup.py (path:/tmp/pip-LG4B1E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:07:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:07:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:07:09 INFO - Running setup.py (path:/tmp/pip-7OWpYd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:07:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 06:07:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:07:09 INFO - Running setup.py (path:/tmp/pip-t18eZJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:07:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 06:07:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:07:09 INFO - Running setup.py (path:/tmp/pip-PhmlQV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:07:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 06:07:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:07:09 INFO - Running setup.py (path:/tmp/pip-P0wLqY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:07:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 06:07:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:07:09 INFO - Running setup.py (path:/tmp/pip-Ffw19S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:07:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 06:07:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:07:09 INFO - Running setup.py (path:/tmp/pip-G1YezS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:07:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:07:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:07:09 INFO - Running setup.py (path:/tmp/pip-nf8aif-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:07:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 06:07:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:07:09 INFO - Running setup.py (path:/tmp/pip-z4txff-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:07:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 06:07:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:07:09 INFO - Running setup.py (path:/tmp/pip-FMz3x4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:07:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 06:07:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:07:09 INFO - Running setup.py (path:/tmp/pip-1p4xPt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:07:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 06:07:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:07:10 INFO - Running setup.py (path:/tmp/pip-qjbl_R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:07:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 06:07:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:07:10 INFO - Running setup.py (path:/tmp/pip-7dL7om-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:07:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 06:07:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:07:10 INFO - Running setup.py (path:/tmp/pip-BZTgX7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:07:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 06:07:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:07:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:07:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 06:07:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:07:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:07:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:07:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:07:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:07:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:07:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:07:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:07:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:07:12 INFO - Downloading blessings-1.6.tar.gz 06:07:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:07:12 INFO - Installing collected packages: blessings 06:07:12 INFO - Running setup.py install for blessings 06:07:12 INFO - Successfully installed blessings 06:07:12 INFO - Cleaning up... 06:07:12 INFO - Return code: 0 06:07:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:07:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:07:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:07:12 INFO - Reading from file tmpfile_stdout 06:07:12 INFO - Current package versions: 06:07:12 INFO - argparse == 1.2.1 06:07:12 INFO - blessings == 1.6 06:07:12 INFO - blobuploader == 1.2.4 06:07:12 INFO - docopt == 0.6.1 06:07:12 INFO - functools32 == 3.2.3-2 06:07:12 INFO - jsonschema == 2.5.1 06:07:12 INFO - manifestparser == 1.1 06:07:12 INFO - mozInstall == 1.12 06:07:12 INFO - mozcrash == 0.17 06:07:12 INFO - mozdebug == 0.1 06:07:12 INFO - mozdevice == 0.48 06:07:12 INFO - mozfile == 1.2 06:07:12 INFO - mozhttpd == 0.7 06:07:12 INFO - mozinfo == 0.9 06:07:12 INFO - mozleak == 0.1 06:07:12 INFO - mozlog == 3.4 06:07:12 INFO - moznetwork == 0.27 06:07:12 INFO - mozprocess == 0.23 06:07:12 INFO - mozprofile == 0.28 06:07:12 INFO - mozrunner == 6.12 06:07:12 INFO - mozscreenshot == 0.1 06:07:12 INFO - mozsystemmonitor == 0.3 06:07:12 INFO - moztest == 0.7 06:07:12 INFO - mozversion == 1.4 06:07:12 INFO - psutil == 3.1.1 06:07:12 INFO - requests == 1.2.3 06:07:12 INFO - wsgiref == 0.1.2 06:07:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:07:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:07:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:07:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:12 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 0x7f24e4514f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13188a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13e47b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1473790>, '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 0x1474690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1474b20>, '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': '6d15e502d8e29d5d2676d664000001c3-1474376794.784953-1940298164', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 06:07:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 06:07:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:07:12 INFO - Using env: {'DISPLAY': ':0', 06:07:12 INFO - 'HOME': '/home/cltbld', 06:07:12 INFO - 'LANG': 'en_US.UTF-8', 06:07:12 INFO - 'LANGUAGE': 'en_US:en', 06:07:12 INFO - 'LOGNAME': 'cltbld', 06:07:12 INFO - 'MAIL': '/var/mail/cltbld', 06:07:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:07:12 INFO - 'MOZ_NO_REMOTE': '1', 06:07:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:07:12 INFO - 'NO_EM_RESTART': '1', 06:07:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:07:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:07:12 INFO - 'PWD': '/builds/slave/test', 06:07:12 INFO - 'SHELL': '/bin/bash', 06:07:12 INFO - 'SHLVL': '1', 06:07:12 INFO - 'TERM': 'linux', 06:07:12 INFO - 'TMOUT': '86400', 06:07:12 INFO - 'USER': 'cltbld', 06:07:12 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1474376794.784953-1940298164', 06:07:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:07:12 INFO - '_': '/tools/buildbot/bin/python'} 06:07:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:07:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 06:07:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 06:07:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 06:07:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 06:07:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 06:07:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 06:07:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 06:07:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 06:07:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 06:07:12 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 06:07:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 06:07:12 INFO - Cleaning up... 06:07:12 INFO - Return code: 0 06:07:12 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:07:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:07:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:07:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:12 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 0x7f24e4514f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13188a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13e47b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1473790>, '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 0x1474690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1474b20>, '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': '6d15e502d8e29d5d2676d664000001c3-1474376794.784953-1940298164', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 06:07:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 06:07:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 06:07:12 INFO - Using env: {'DISPLAY': ':0', 06:07:12 INFO - 'HOME': '/home/cltbld', 06:07:12 INFO - 'LANG': 'en_US.UTF-8', 06:07:12 INFO - 'LANGUAGE': 'en_US:en', 06:07:12 INFO - 'LOGNAME': 'cltbld', 06:07:12 INFO - 'MAIL': '/var/mail/cltbld', 06:07:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:07:12 INFO - 'MOZ_NO_REMOTE': '1', 06:07:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:07:12 INFO - 'NO_EM_RESTART': '1', 06:07:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:07:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:07:12 INFO - 'PWD': '/builds/slave/test', 06:07:12 INFO - 'SHELL': '/bin/bash', 06:07:12 INFO - 'SHLVL': '1', 06:07:12 INFO - 'TERM': 'linux', 06:07:12 INFO - 'TMOUT': '86400', 06:07:12 INFO - 'USER': 'cltbld', 06:07:12 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1474376794.784953-1940298164', 06:07:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:07:12 INFO - '_': '/tools/buildbot/bin/python'} 06:07:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:07:13 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:07:13 INFO - Cleaning up... 06:07:13 INFO - Return code: 0 06:07:13 INFO - Running post-action listener: _resource_record_post_action 06:07:13 INFO - Running post-action listener: _start_resource_monitoring 06:07:13 INFO - Starting resource monitoring. 06:07:13 INFO - [mozharness: 2016-09-20 13:07:13.120277Z] Finished create-virtualenv step (success) 06:07:13 INFO - [mozharness: 2016-09-20 13:07:13.120787Z] Running install step. 06:07:13 INFO - Running pre-action listener: _resource_record_pre_action 06:07:13 INFO - Running main action method: install 06:07:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:07:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:07:13 INFO - Reading from file tmpfile_stdout 06:07:13 INFO - Detecting whether we're running mozinstall >=1.0... 06:07:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:07:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:07:13 INFO - Reading from file tmpfile_stdout 06:07:13 INFO - Output received: 06:07:13 INFO - Usage: mozinstall [options] installer 06:07:13 INFO - Options: 06:07:13 INFO - -h, --help show this help message and exit 06:07:13 INFO - -d DEST, --destination=DEST 06:07:13 INFO - Directory to install application into. [default: 06:07:13 INFO - "/builds/slave/test"] 06:07:13 INFO - --app=APP Application being installed. [default: firefox] 06:07:13 INFO - mkdir: /builds/slave/test/build/application 06:07:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 06:07:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 06:07:30 INFO - Reading from file tmpfile_stdout 06:07:30 INFO - Output received: 06:07:30 INFO - /builds/slave/test/build/application/firefox/firefox 06:07:30 INFO - Running post-action listener: _resource_record_post_action 06:07:30 INFO - [mozharness: 2016-09-20 13:07:30.354645Z] Finished install step (success) 06:07:30 INFO - [mozharness: 2016-09-20 13:07:30.354891Z] Running run-tests step. 06:07:30 INFO - Running pre-action listener: _resource_record_pre_action 06:07:30 INFO - Running main action method: run_tests 06:07:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:07:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:07:30 INFO - Python 2.7.3 06:07:30 INFO - Return code: 0 06:07:30 INFO - grabbing minidump binary from tooltool 06:07: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')]} 06:07:30 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 0x1473790>, '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 0x1474690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1474b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:07:30 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 06:07:30 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 06:07:30 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 06:07:30 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:07:30 INFO - Return code: 0 06:07:30 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 06:07:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:07:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:07:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 06:07:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:07:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YMqburc_Q9WQoBaqvvA8yA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-023', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 06:07:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/YMqburc_Q9WQoBaqvvA8yA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-023 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 06:07:30 INFO - Using env: {'DISPLAY': ':0', 06:07:30 INFO - 'HOME': '/home/cltbld', 06:07:30 INFO - 'LANG': 'en_US.UTF-8', 06:07:30 INFO - 'LANGUAGE': 'en_US:en', 06:07:30 INFO - 'LOGNAME': 'cltbld', 06:07:30 INFO - 'MAIL': '/var/mail/cltbld', 06:07:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:07:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 06:07:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:07:30 INFO - 'MOZ_NO_REMOTE': '1', 06:07:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:07:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:07:30 INFO - 'NO_EM_RESTART': '1', 06:07:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:07:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:07:30 INFO - 'PWD': '/builds/slave/test', 06:07:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:07:30 INFO - 'SHELL': '/bin/bash', 06:07:30 INFO - 'SHLVL': '1', 06:07:30 INFO - 'TERM': 'linux', 06:07:30 INFO - 'TMOUT': '86400', 06:07:30 INFO - 'USER': 'cltbld', 06:07:30 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1474376794.784953-1940298164', 06:07:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:07:30 INFO - '_': '/tools/buildbot/bin/python'} 06:07:30 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YMqburc_Q9WQoBaqvvA8yA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-023', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 06:07:30 INFO - mozversion application_buildid: 20160920030429 06:07:30 INFO - mozversion application_changeset: 62f79d676e0e11b3ad59a5425b3ebb3ec5bbefb5 06:07:30 INFO - mozversion application_display_name: Nightly 06:07:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:07:30 INFO - mozversion application_name: Firefox 06:07:30 INFO - mozversion application_remotingname: firefox 06:07:30 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 06:07:30 INFO - mozversion application_vendor: Mozilla 06:07:30 INFO - mozversion application_version: 52.0a1 06:07:30 INFO - mozversion platform_buildid: 20160920030429 06:07:30 INFO - mozversion platform_changeset: 62f79d676e0e11b3ad59a5425b3ebb3ec5bbefb5 06:07:30 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 06:07:30 INFO - mozversion platform_version: 52.0a1 06:07:30 INFO - using testdate: 1474376850 06:07:30 INFO - actual date: 1474376850 06:07:30 INFO - starting webserver on 'localhost:58119' 06:07:30 INFO - SUITE-START | Running 2 tests 06:07:30 INFO - TEST-START | dromaeo_css 06:07:30 INFO - operating with platform_type : linux_ 06:07:30 INFO - Initialising browser for dromaeo_css test... 06:07:30 INFO - TEST-INFO | started process 793 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppEx5q_/profile http://localhost:58119/getInfo.html) 06:07:34 INFO - PROCESS | 793 | __metrics Screen width/height:1600/1200 06:07:34 INFO - PROCESS | 793 | colorDepth:24 06:07:34 INFO - PROCESS | 793 | Browser inner width/height: 1024/697 06:07:34 INFO - PROCESS | 793 | __metrics 06:07:35 INFO - PROCESS | 793 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 06:07:35 INFO - PROCESS | 793 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:07:36 INFO - PROCESS | 793 | console.error: PushService: 06:07:36 INFO - PROCESS | 793 | stateChangeProcessEnqueue: Error transitioning state 06:07:36 INFO - PROCESS | 793 | UnknownError 06:07:36 INFO - TEST-INFO | 793: exit 0 06:07:36 INFO - Browser initialized. 06:07:36 INFO - Running cycle 1/1 for dromaeo_css test... 06:07:36 INFO - TEST-INFO | started process 885 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppEx5q_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:07:48 INFO - PROCESS | 885 | RSS: Main: 172986368 06:07:48 INFO - PROCESS | 885 | 06:08:07 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 06:08:40 INFO - PROCESS | 885 | 1474376920120 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 06:08:42 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.03336 seconds. 06:08:48 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 06:08:50 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 06:09:04 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.05006 seconds. 06:09:14 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 06:09:16 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.08338 seconds. 06:09:30 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 06:09:32 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 06:09:35 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.08339 seconds. 06:09:51 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 06:10:14 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.03339 seconds. 06:10:32 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.03341 seconds. 06:10:39 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.63343 seconds. 06:10:41 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 06:10:49 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.05006 seconds. 06:10:49 INFO - PROCESS | 885 | Cycle 1(1): loaded http://localhost:58119/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58119/tests/dromaeo/cssquery-ext.html) 06:10:50 INFO - PROCESS | 885 | RSS: Main: 300670976 06:10:50 INFO - PROCESS | 885 | 06:10:54 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 06:11:00 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 06:11:05 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 06:11:07 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 06:11:21 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 06:11:23 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 06:11:31 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 06:11:37 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 06:11:48 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 06:11:55 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 06:12:01 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 06:12:14 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 06:12:32 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 06:12:35 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 06:12:36 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 06:12:42 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 06:12:44 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 06:12:52 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 06:13:06 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 06:13:12 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 06:13:15 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 06:13:20 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 06:13:34 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 06:13:36 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 06:13:49 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 06:13:53 INFO - PROCESS | 885 | Cycle 1(1): loaded http://localhost:58119/tests/dromaeo/cssquery-ext.html (next: http://localhost:58119/tests/dromaeo/cssquery-jquery.html) 06:13:53 INFO - PROCESS | 885 | RSS: Main: 275243008 06:13:53 INFO - PROCESS | 885 | 06:13:57 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.03346 seconds. 06:14:00 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 06:14:02 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 06:14:09 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 06:14:14 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 06:14:20 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 06:14:22 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 06:14:23 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 2.00009 seconds. 06:14:40 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 06:14:44 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 06:14:50 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 06:15:02 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 06:15:29 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 06:15:32 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00009 seconds. 06:15:36 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 06:15:43 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 06:15:47 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00008 seconds. 06:15:55 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 06:15:57 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 06:16:15 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 06:16:22 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 06:16:25 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 06:16:47 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 06:16:55 INFO - PROCESS | 885 | Cycle 1(1): loaded http://localhost:58119/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58119/tests/dromaeo/cssquery-mootools.html) 06:16:56 INFO - PROCESS | 885 | RSS: Main: 275107840 06:16:56 INFO - PROCESS | 885 | 06:16:59 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 06:17:18 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 06:17:32 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 06:17:34 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 06:17:39 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 06:17:42 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.03339 seconds. 06:18:11 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 06:18:13 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 06:18:31 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 06:18:57 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 06:19:11 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 06:19:20 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 06:19:21 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00009 seconds. 06:19:29 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 06:19:43 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 06:19:49 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 06:19:53 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 06:19:58 INFO - PROCESS | 885 | Cycle 1(1): loaded http://localhost:58119/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58119/tests/dromaeo/cssquery-prototype.html) 06:19:58 INFO - PROCESS | 885 | RSS: Main: 254533632 06:19:58 INFO - PROCESS | 885 | 06:20:02 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.03339 seconds. 06:20:14 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 06:20:21 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 06:20:27 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 06:20:29 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 06:20:33 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 06:20:35 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 06:20:39 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00008 seconds. 06:20:45 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 06:20:47 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 06:20:56 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.03342 seconds. 06:20:58 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 06:21:10 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 06:21:21 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 06:21:22 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 06:21:28 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 06:21:37 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 06:21:44 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 06:21:52 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 06:22:00 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 06:22:07 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 06:22:18 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 06:22:19 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.01675 seconds. 06:22:21 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 06:22:27 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 06:22:31 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 06:22:47 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 06:22:55 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 06:23:01 INFO - PROCESS | 885 | Cycle 1(1): loaded http://localhost:58119/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58119/tests/dromaeo/cssquery-yui.html) 06:23:02 INFO - PROCESS | 885 | RSS: Main: 216469504 06:23:02 INFO - PROCESS | 885 | 06:23:05 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 06:23:08 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 06:23:12 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 06:23:14 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 06:23:24 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 06:23:27 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 06:23:34 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 06:23:57 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 06:24:03 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 06:24:06 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.03339 seconds. 06:24:24 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 06:24:25 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 06:24:27 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 06:24:33 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 06:24:35 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 06:24:41 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 06:24:43 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 06:24:45 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 06:25:08 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 06:25:13 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 06:25:14 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 06:25:16 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 06:25:26 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 06:25:38 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 06:25:40 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 06:25:42 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 06:25:54 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 06:26:04 INFO - PROCESS | 885 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 06:26:04 INFO - PROCESS | 885 | Cycle 1(1): loaded http://localhost:58119/tests/dromaeo/cssquery-yui.html (next: http://localhost:58119/tests/dromaeo/cssquery-dojo.html) 06:26:05 INFO - PROCESS | 885 | RSS: Main: 305586176 06:26:05 INFO - PROCESS | 885 | 06:26:05 INFO - PROCESS | 885 | __start_tp_report 06:26:05 INFO - PROCESS | 885 | _x_x_mozilla_page_load 06:26:05 INFO - PROCESS | 885 | _x_x_mozilla_page_load_details 06:26:05 INFO - PROCESS | 885 | |i|pagename|runs| 06:26:05 INFO - PROCESS | 885 | |0;dojo.html;3041.1028746519783;3052.6489453712825;3078.984605076973;3122.1101985934015;3135.623725152982;5194.142966410538;5282.55098316643;5299.390570084442;5356.91964620531;5403.783848646054;5351.919721204185;5367.141257398817;5389.892202155955;5397.730113494329;5431.158170483572;4851.393575803025;4866.683665561744;4927.7536123193695;4955.504449555034;4964.354633897588;5003.449620541737;5048.495150484959;5054.115529782292;5061.139606266925;5063.493650634926;4701.623870090384;4707.905841883177;4747.620190384761;4859.854204373839;5012.248162775576;4895.388076490439;4907.312976183337;4965.478624744386;4978.228374601942;4978.6763860348965;4841.975790121062;4860.8298709545;4876.292937524075;4887.608991280689;4936.531029552187;5256.237845512417;5346.4118946915805;5351.331083614548;5352.652077614943;5354.43778403266;5644.294463192101;5674.148877668342;5720.685362305072;5729.942700573024;5817.941820581782;5762.88474230513;5777.306723193202;5781.566382521328;5783.248177736909;5787.50806181475;5374.355077390699;5374.892502149935;5376.892462150813;5382.67703937765;5385.434529374437;5648.237487939158;5711.286089238845;5739.454751798572;5766.365699773021;5772.538196944234;5574.331080270394;5629.7185140742795;5631.099024156116;5656.179853921159;5705;5721.313442386857;5723;5726.3700992890745;5776.104703770922;5798.768049278066;5653.208550802891;5706.229658996065;5706.3722990471015;5712.028955077563;5715.685637289906;5414.783408663609;5430.701311427831;5445.83662490126;5709.800156994485;5714.942850571524;4419.447569053868;4438.1567502174485;4445.933311000337;4447.911041779211;4490.101979604092;3934.468846705658;3952.5454572724507;3955.4462375267485;3992.121733218687;4002.5597184309704;5265.605079619044;5485.793125512381;5489.1217405214975;5489.39616642169;5493.395726470085;5559.305086864142;5672.602917795714;5676.403977582377;5686.658800471985;5773.787504624076;5324.600654950894;5341.86645333862;5351.973240133774;5357.7589008494715;5361.222622719683;4905.828296009694;4915.11527924977;4925.310456536088;4933.087378834855;4972.65191436596;5750.246174916607;5787.797427090031;5913.994620914455;5952.494038006645;5966.254218222723;5775.306963164448;5792.102224155263;5795.652260864362;5803.39064398249;5842.824715258549;5937.198478205387;5947;5954.10688396744;5955;6001.789937352171;5407.513323800877;5407.567394608501;5607.467290607386;5631.521320687787;5637.154426836008;5765.827025189251;5772.307323121252;5778.422157784188;5806.419358064329;5827.475527202572;5630.718464076862;5633.267675202197;5640.182173584725;5641.182028605747;5649;5342.492463215988;5367.919481207863;5561.443855614406;5628.971855140698;5643.238162847963;5528.80649177293;5544.306961629796;5557.360903495988;5608.551315894801;5609.5512359012;5529.529989950738;5625.043742563856;5630.4369563043365;5633.239512665901;5646.604737668324;5368.114261146867;5403.486668766461;5512.09050506678;5518;5523.5434202219585 06:26:05 INFO - PROCESS | 885 | |1;ext.html;572.3618165745195;597.408565520135;609.2262826210709;610.6214147228717;628.4783629587442;30895.845520772422;42498;43201.56798432015;43288;43331.35002974958;44682.553174468245;44920;45042.32436513447;45072.54927450733;45093.323600146025;8872.201501864847;9006.954965225197;9021.413608115452;9022;9023.097690230958;8827.823443531126;8897.377183597151;8903.332250081237;8917.95541022294;8918.241949434307;8923.509206993613;8963.058878817728;8979.326550508738;8980.730578082668;8983.460992340448;8910.420822646507;8912.197902188802;8918.598663060113;8921.241694455979;8922.643094276189;8787.165219304155;8891.199792018684;8906;8910.732678019669;8915.821683566292;41644.167116657496;42039.36940945888;42142.578574214174;42401.36397954033;42416;3645.7447978641508;3704.9629503705155;3923.195744872294;4105.794710264475;4156.875293741193;31308.53037204444;31454.528182077287;31651.84174079138;31886.840565797254;32548.83725581357;4305.547917468652;4309.461317335333;4366.628836548897;4368.847090351823;4405.867823965286;327579.36210319033;329110;329252;330185;330835;55653.44346556483;55876.161857572166;55946.440535594935;56013.159802603;56045.71977140088;52367.476325237025;53435.19847202295;57685;58769.70615146897;58899.70550147222;16942.322307107825;17067.317307307818;17143.74284385735;17164.656706865793;17226.655466890592;13301.334933253298;13414.798778018338;13415.329233538285;13423.530176443777;13450.86549134496;14587.489437869624;14831.258437078319;15936.282867271;16288.674226515403;16310.26603802808;5773.976640322862;5776.971115144397;5854.326752423526;5888.911666325009;6098.390160983955;892.6875593542338;1059.2108878885126;1086.5925278020743;1092.82514797632;1092.9672109836717;30957.72493464034;31914.680853191636;32715;32876.342473150406;34800.23103887815;1083.712816103733;1092.3118435385816;1117.0784103114963;1129.6272230164027;1179.9233049851732;16030.519084427486;16699.499015029567;16856.494305170865;16943.029226400988;17048.48854534366;466207;468648;468685;472729;473735;55657.72171139118;55732;55750.442495575335;55840.720796395755;56027.71986140043;1357.124654597779;1363.2686488159982;1393.324237744693;1402.57221547429;1413.9505117320844;29199.85400072986;30416.847915761165;31056.689433106283;31169.220769480944;31389.215269618442;49685;50054.249186261506;50096.248556270955;50096.49903500918;50123.24815127849;427538;427642;429345;429448;430218;27213.591796122677;27649.58525622077;27670.308242294104;27748;27748.58377124385;38589;39606.40590391089;39752;49461.30117638819;49533.752331238116;38776.2244755109;39249.60750392459;39602.00994975149;39923.80038099791;41016.794916026425;39764.2047159061;40339.596604033584;41037.58962410457;41703.37444938389;41965.790171048946;39088;39526.802365987984;39831.00422489345;39922.800385997885;40297.194056118125;33766.66233337635;35956;36592.63407365999;36644.81677591702;36997.81501092585;27033.729662703907;27414.862925685244;27636.309092272855;27651.30871728223;27651.86174069117 06:26:05 INFO - PROCESS | 885 | |2;jquery.html;382.8009435093753;391.75319548684325;400.73351221437736;402.94761680981475;415.96140195869185;14219.360128794204;17554;18248.817511824876;19668.114934827914;19703.211871525138;18360.44918652439;19327.806721932775;19858.801411985874;19920.90039549804;20140.89929550354;5629.408912064236;5759.82720518383;5868.970655146718;5897.646141231518;5902.203202567663;5656.123300888369;5798.275215598051;5873.119032145191;5883.735231914553;5888.087346461305;5708.628939118944;5745.597808153431;5774.8267551973295;5798.275215598051;5805.796797112081;5579.69311687861;5741.138829175616;5753.510951569122;5755.482006619382;5760.510356619671;5063.139266324697;5130.410002849682;5131.794728210867;5154.819581314636;5168.534831865112;21619.45951351213;23322.905284376484;23386.766132338627;23606.527869442514;23709.407264818346;2174.7281589801273;2287.433108320806;2299.9310020699404;2663.73362663734;2798.762105221059;10252.64115755945;10533.736656583647;10796.892031079668;10844.566217351297;12117.697057573543;1802.369170790226;1896.6870466373032;1939.0789375046882;1989.1247850945538;2030.2691031228746;78018.6099069501;84405.57797210975;85696;86324.99999999937;88623.85596249122;10849.403282819363;10973.341599504056;11227.607033753942;11791.823122653168;11932.701682458008;13981.65045873841;15740.449084281996;15774.68450630981;15783.447579334668;15799.842001579837;7861.449698521163;7918.287354138155;7937.722179723682;8152.470089444168;8258.917410825936;7628.199039100926;7679.116901556281;7688.692452301957;7913.406494512935;7938.166492518318;4724.314974328772;4776.808927642925;4792.353032340659;4844.975775121173;4919.729414882144;1963.4796778853615;1963.5974625202084;1987.8509111816672;2018.6265540874986;2034.1659919432961;807.3097501636134;900.8423525882944;978.5694294510392;1021.3667526133845;1023.2019025160238;18903.24387024531;19899.204031838854;20125.597488050156;20194.39416817498;20195.899020504803;978.5351957820121;979.4857699707568;981.749653838281;993.4982833669095;1005.3716427232979;10380.470639982292;11090.116569691938;11111.777764444665;11124.443777811077;11189.832152517718;451763;472684;496925;521508;528142;94604.52697736605;96882.88963879515;98213.50893245632;99582.5020874891;100921;965.3194497878978;1011.2971484817981;1014.1329163565193;1024.4979959819634;1037.1961729659424;17392;17491.38780142689;17921.462356129337;18064.819351806313;18313.816861831212;28802.855985719936;32470.07769300774;42189.15621687488;42926.35610465908;43454;59383.70308148432;60191.09713354216;60269.698651506464;60438.39561604328;61823.690881545306;7610.581418022062;7882.054153501387;7990.880136798069;8090.190980902098;8121;12405.813912791134;13410;13578;13696.794548081985;13937.860621393656;8394.58027098655;8722.040575536812;8730.563471826255;11655.18413711066;12269.631911042683;8761;8824.779380515281;8886.511241881759;8900.777480563038;8953.507557084424;9141.77145571366;9252.583633736363;9316.906830931604;9322.394044387092;9357.20463760588;12706.872931270938;12794.296313702836;13031.739365212454;13064.869351306364;13196;12055.457504412498;12654.557090501788;12804.87195128074;12850.742985140057;13156.539521116714 06:26:05 INFO - PROCESS | 885 | |3;mootools.html;1273.4142294544508;1456.7887656289838;1499.902506337088;1611.2588209423664;1770.8494777943881;7272.309130632595;8534.829303413935;8859.335549833764;8898.154675305836;9230.67692630758;9020.097990200977;9094.045125261851;9109.544522773867;9110.13453721897;9114.13415725505;2414;2442.792362649177;2445.8532488050773;2446.522928029035;2451.154351748644;2398.70016247969;2420.5037967216676;2451.3136321829825;2457.066314800373;2462.249014050714;2418.3954011497126;2422.2733180046002;2430.331658793828;2445.058652418814;2455.852648841066;2333.614953532665;2341.262502311766;2345.7067866516686;2358.740538540768;2375.0618505690154;2259.9548009039727;2326.5114325991563;2331.440454290975;2335.9649605255936;2342.2738950925095;7533.849323013564;7712.074551053909;8812.16284452976;9010.549472526413;9072.63709451621;5040.521150490697;5178.533931946143;5186.24799404088;5259;5264.42091369949;3568.125809176742;3589.192431702872;3610.855565777366;3661.6338366163172;3673.412254039341;1165.854268216473;1171.606130382028;1176.8705442401329;1180.7664066996094;1182.0366401382846;12237.755244895141;12241.938790305992;12282.07884408664;12283.93858030713;12295.262284262883;4744.976275118672;4760.127958289183;4840.10458065252;4860.927086093711;4949.059678661043;10482.161427085815;10498.160147188206;10499.107575855985;10501.89498105009;10503.369797812156;5507.228987941691;5526.364468086222;5528.06022976095;5532.806351777668;5536.031194540939;4972.975135124301;4995.075910956485;5034.949650503448;5082.440931497532;5086.21163719624;4111.48606424197;4111.506619205715;4134.049168691157;4195.748255104703;4208.452901122834;1338.9665258368618;1351.8850897673808;1352.4860552989999;1367.2548461088732;1367.8426980897325;873.7160422862595;880.8942926848819;896.0815164456484;901.0763966933945;903.2457897655531;5331.360236771612;5379.973100134553;5422.8373148805595;5480.890382192334;5649.858753531194;447.21960179486354;456.6301295950291;459.16661259814;461.6560662306603;462.71543001054107;3666.7799932004164;3745.9438108428394;3750.4374343848094;3806.7335286530233;3880.902977425587;10706.518206680717;11191.776164476665;11198.496067676973;11206.775864482664;11213.439328033566;10708.1433485321;10835.67492975212;11063.22557420989;11069.72325691848;11108.777824443627;8210;8308.210719981593;8309.501429914224;8311.293540049042;8318.500889946623;5468.972655136858;5490.862728431821;5524.530414914777;5596.468335508121;5673.631213971079;10196.439195844003;10208.387496750218;10221.488925553544;10286.794264114826;10381.740456488647;9429.717108486757;9702.805943881223;9732.318737688576;9956.950215248877;10383.584656613652;7932.206779322021;8096.878546821685;8105.189481051848;8137.471064380797;8152.144024877302;8530.445521041112;8579.35654825878;8629;8678.045415004219;8688.652453901774;7511.549307041595;7579.696812127453;7582.62086895664;7586.165521792709;7849.921500785147;7670.8082297943;7693.230676932262;7718.729844455417;7827.060752709711;7866.9213307866185;6676.732930682719;7102.573845569283;7173.0316407284345;7202.423806095395;7202.71189152449;4539.909201816011;4570.360149578995;4584.037352156084;4899.681520701286;4903.33804936329;4941.085899108532;5023.422306434661;5044.672096313728;5124.385073791169;5197.8440646780655 06:26:05 INFO - PROCESS | 885 | |4;prototype.html;309.5542418916761;329.4711987260447;333.95805088125053;339.4534798973652;341.6259196180182;5160.716160611165;5214.791408343666;5375.731213439332;5376.360210135907;5433.320834895638;5054.1913293872985;5291.523762861341;5299.655522391042;5299.655522391042;5326.893462130755;4066.4916885389325;4118.19695159444;4161.9999999999845;4164.354525048622;4178.791060446965;3934.5081864766903;4050.412690159925;4088.427620133169;4125.525564560069;4152.709310348277;4109.506859176903;4134.689898257628;4145.647619952308;4158.2307273154565;4160.375943608467;4121.793910304487;4167.270727622654;4170.874873753793;4171.998720307135;4173.499180098408;3478.2347883465595;3652.543432070991;3664.633536646341;3668;3672.3022625701033;5183.382391892071;5189;5202.895942081137;5219.243209734567;5220.486059042873;4758.2386818108935;4776.379070720784;4777.235642297251;4782.378290822171;4801.783919723621;5305.283786688786;5451.754671039813;5581.376752926159;5622.887542249132;5625.606207565472;4083.1425400665894;4100.876973690764;4100.958990410088;4134.524529679065;4147.191297696951;5832.387599302055;5881.294244690622;5905.61218113743;5911.728978269693;5926.259217597757;4060.2285565742413;4235.407043013981;4286.742795432284;4291.420658211164;4294.699371044059;4278;4280.636145927631;4289.442372491556;4290.377895205178;4303.440552728188;4607.631389488833;4639.327297541905;4745.7423782697615;4752.263399173134;4763.809047738066;5586.988684073812;5595.804146854841;5608.46719561641;5654.180143879197;5674.914876276859;1459.2484870291808;1467.1930438258914;1481;1488.2186851902838;1501.4519700309527;948.9419297483256;954.1555723184892;961.053362437998;965.3194497878978;983.2429029647132;4821.710697358169;4824.855254342376;4825.3485779419325;4868.294097355934;4884.804607815719;5025.723585202849;5085.923711144335;5090.363704536933;5091.490850914879;5107.438181799999;4687.929681054787;4692.6245900327895;4706.764661766898;4749.95250047502;4881.9511804881495;2642.5903984882375;2656.4687062587172;2684.6845045927357;2689.8702544931175;2700.2574292069444;5117.437081920985;5202.947970520247;5219;5276.445973172762;5290.761915713802;4986.307394007195;5049.838537136479;5114.667546609458;5131.486851314913;5148.794048238028;5098.59211263098;5128.999535271759;5131.1277082895995;5137.6660517066275;5139.897202056013;4974.955259395568;4997.55119991209;5005.524475174852;5012.924806127838;5031.773570189277;5285.445028271976;5653.406392328746;5669.85825354353;5679.914801278067;5701.942980570141;5905.734241959208;5906.70466476683;5920.407959204045;5936.821895343147;5957.255343082114;4042.2521833460905;4118.485189351331;4125.6591607728205;4134.772587507716;4149.066460046465;5496.587755918402;5601.60788744784;5635.380108188178;5791.8552036199435;5807.651540907559;5498.80754173602;5645.350784659652;5653.575981801464;5657.547396208376;5663.216086932603;5522.22688823557;5626.409227031267;5681.0910254359105;5737.770489180386;5750.913736293875;5522.784987302787;5587.3015873015875;5609.691466969192;5612.410696876933;5615;5585.441455854382;5607.243022191952;5638.182463542846;5645.745941432563;5671.291088613923;5340.946590534045;5489.066858634122;5524.447555244604;5536.3114250154085;5571.693556854412 06:26:05 INFO - PROCESS | 885 | |5;yui.html;748.8729462159449;763.6563546404119;768.9384849212064;792.2592376128321;806.8910697055895;15952.920235398822;17202.913985430056;17328.04695741735;17389.91305043473;17485.562860928483;15364.923175384136;15727.213639318046;15868.84131158688;15898.125603091825;15902.284397202126;806.5322113174345;813.1137060603941;813.8290958898639;813.8290958898639;814.9470284431493;667.2860039757461;688.7623769799411;796.6335485676567;814.5927036481759;820.5486982159819;868.9695860644879;869.5782545465444;871.2768402226169;871.8212766382908;872.655301156043;869.1699427047155;869.42183448007;869.6434461870602;869.7999460124144;869.9782505437383;502.2416151611057;517.7825313368394;603.1495591216408;620.3610281410155;636.961782293064;15104.773428398474;15211.391544338214;15213.543593692097;15267.312970916335;15377.154256515887;13718.38267277975;13961.930190349085;14269.286535673175;14462.132272063709;14477.34851931655;12754.61736147917;12839.422225999853;13027.153235039692;13159.53941612039;13478.595642130751;4230.238557059728;4262.552431994627;4421.889452763675;4457.286834106528;4461.754603496806;86433;86640.56679716561;87524;90471;91381.54309228477;10641.840372394421;10645.126034556408;10724.356538607708;10885.129189664807;10977.670669879917;21064.473388165417;21065.368038958855;21085.367438976857;21109.472263193235;21113.577728445343;8084.797880053045;8346.70786522481;8414.36892233085;8562.315014798802;8740.213380795632;4628.514006029319;4649.581537661559;4832.9275060874115;4976.377952755905;4980.950190498049;3320.9501857472155;3433.5879694436326;3453.309338132384;3480.686738193524;3482.6865582097225;579.3279795437356;585.0463744097094;590.2680675961776;594.8572342637735;605.8243109498284;640.8622146238581;669.7421492725335;677.3328271652517;687.1856849633201;706.5795851468368;8971.327150463741;9510.239180865514;9808.9019109808;9841.803163936824;9950.203983681433;612.874360756044;630.4231628060273;630.6153246519619;636.6371168434041;642.6850843086854;4038.5759495253164;4214.873553793391;4235.2800023996;4307.913841723148;4407.625351845129;74112;89805.55097224472;124426.37786811189;127650;128428.72570194362;108933;108974.45512772385;109748;110985;111208.44395778132;63221;95269.52365238268;95628;97020;97025.51487242519;8192.18078192176;8476.618552165044;8505.319574433906;8529.530875801642;8966.372353935161;55932;56682.43317566771;57252.713736431055;57365.713171433876;57375.71312143413;76973.61513192399;80097.59951200207;83539;85756.57121714352;85856.57071714601;11329.943350283196;11450.541978320774;11604.825927610924;11771.11716621274;12093.939530302292;12336.81494777561;12528.436220370288;12706.36468176606;13737.519186828413;13739.587812365646;11924.642260732191;12182.512699492276;12304.446299916346;12712.11015228962;12777.425015874122;11456.713582160513;11542.884571154182;11631.53473861036;11656.417179140839;11988.220765650205;10805.02754752076;10953.890461095605;11210.943945280222;11394.25937314072;11897.167198296036;9591.760205994906;9669.468179250209;9776.413415195111;9970.950145249228;10196.490175491048;9492.382995105296;10144.594216231268;10312.587496500057;10353.482325883526;10442.164626829683 06:26:05 INFO - PROCESS | 885 | __end_tp_report 06:26:05 INFO - PROCESS | 885 | __start_cc_report 06:26:05 INFO - PROCESS | 885 | _x_x_mozilla_cycle_collect,904 06:26:05 INFO - PROCESS | 885 | __end_cc_report 06:26:05 INFO - PROCESS | 885 | __startTimestamp1474377965176__endTimestamp 06:26:05 INFO - PROCESS | 885 | 06:26:05 INFO - PROCESS | 885 | ------- Summary: start ------- 06:26:05 INFO - PROCESS | 885 | Number of tests: 6 06:26:05 INFO - PROCESS | 885 | 06:26:05 INFO - PROCESS | 885 | [#0] dojo.html Cycles:165 Average:5318.41 Median:5489.40 stddev:588.79 (10.7%) stddev-sans-first:562.84 06:26:05 INFO - PROCESS | 885 | Values: 3041.1 3052.6 3079.0 3122.1 3135.6 5194.1 5282.6 5299.4 5356.9 5403.8 5351.9 5367.1 5389.9 5397.7 5431.2 4851.4 4866.7 4927.8 4955.5 4964.4 5003.4 5048.5 5054.1 5061.1 5063.5 4701.6 4707.9 4747.6 4859.9 5012.2 4895.4 4907.3 4965.5 4978.2 4978.7 4842.0 4860.8 4876.3 4887.6 4936.5 5256.2 5346.4 5351.3 5352.7 5354.4 5644.3 5674.1 5720.7 5729.9 5817.9 5762.9 5777.3 5781.6 5783.2 5787.5 5374.4 5374.9 5376.9 5382.7 5385.4 5648.2 5711.3 5739.5 5766.4 5772.5 5574.3 5629.7 5631.1 5656.2 5705.0 5721.3 5723.0 5726.4 5776.1 5798.8 5653.2 5706.2 5706.4 5712.0 5715.7 5414.8 5430.7 5445.8 5709.8 5714.9 4419.4 4438.2 4445.9 4447.9 4490.1 3934.5 3952.5 3955.4 3992.1 4002.6 5265.6 5485.8 5489.1 5489.4 5493.4 5559.3 5672.6 5676.4 5686.7 5773.8 5324.6 5341.9 5352.0 5357.8 5361.2 4905.8 4915.1 4925.3 4933.1 4972.7 5750.2 5787.8 5914.0 5952.5 5966.3 5775.3 5792.1 5795.7 5803.4 5842.8 5937.2 5947.0 5954.1 5955.0 6001.8 5407.5 5407.6 5607.5 5631.5 5637.2 5765.8 5772.3 5778.4 5806.4 5827.5 5630.7 5633.3 5640.2 5641.2 5649.0 5342.5 5367.9 5561.4 5629.0 5643.2 5528.8 5544.3 5557.4 5608.6 5609.6 5529.5 5625.0 5630.4 5633.2 5646.6 5368.1 5403.5 5512.1 5518.0 5523.5 06:26:05 INFO - PROCESS | 885 | 06:26:05 INFO - PROCESS | 885 | [#1] ext.html Cycles:180 Average:56933.73 Median:22900.52 stddev:109345.99 (477.5%) stddev-sans-first:109570.85 06:26:05 INFO - PROCESS | 885 | Values: 572.4 597.4 609.2 610.6 628.5 30895.8 42498.0 43201.6 43288.0 43331.4 44682.6 44920.0 45042.3 45072.5 45093.3 8872.2 9007.0 9021.4 9022.0 9023.1 8827.8 8897.4 8903.3 8918.0 8918.2 8923.5 8963.1 8979.3 8980.7 8983.5 8910.4 8912.2 8918.6 8921.2 8922.6 8787.2 8891.2 8906.0 8910.7 8915.8 41644.2 42039.4 42142.6 42401.4 42416.0 3645.7 3705.0 3923.2 4105.8 4156.9 31308.5 31454.5 31651.8 31886.8 32548.8 4305.5 4309.5 4366.6 4368.8 4405.9 327579.4 329110.0 329252.0 330185.0 330835.0 55653.4 55876.2 55946.4 56013.2 56045.7 52367.5 53435.2 57685.0 58769.7 58899.7 16942.3 17067.3 17143.7 17164.7 17226.7 13301.3 13414.8 13415.3 13423.5 13450.9 14587.5 14831.3 15936.3 16288.7 16310.3 5774.0 5777.0 5854.3 5888.9 6098.4 892.7 1059.2 1086.6 1092.8 1093.0 30957.7 31914.7 32715.0 32876.3 34800.2 1083.7 1092.3 1117.1 1129.6 1179.9 16030.5 16699.5 16856.5 16943.0 17048.5 466207.0 468648.0 468685.0 472729.0 473735.0 55657.7 55732.0 55750.4 55840.7 56027.7 1357.1 1363.3 1393.3 1402.6 1414.0 29199.9 30416.8 31056.7 31169.2 31389.2 49685.0 50054.2 50096.2 50096.5 50123.2 427538.0 427642.0 429345.0 429448.0 430218.0 27213.6 27649.6 27670.3 27748.0 27748.6 38589.0 39606.4 39752.0 49461.3 49533.8 38776.2 39249.6 39602.0 39923.8 41016.8 39764.2 40339.6 41037.6 41703.4 41965.8 39088.0 39526.8 39831.0 39922.8 40297.2 33766.7 35956.0 36592.6 36644.8 36997.8 27033.7 27414.9 27636.3 27651.3 27651.9 06:26:05 INFO - PROCESS | 885 | 06:26:05 INFO - PROCESS | 885 | [#2] jquery.html Cycles:180 Average:29328.06 Median:409.45 stddev:81834.96 (19986.3%) stddev-sans-first:82035.67 06:26:05 INFO - PROCESS | 885 | Values: 382.8 391.8 400.7 402.9 416.0 14219.4 17554.0 18248.8 19668.1 19703.2 18360.4 19327.8 19858.8 19920.9 20140.9 5629.4 5759.8 5869.0 5897.6 5902.2 5656.1 5798.3 5873.1 5883.7 5888.1 5708.6 5745.6 5774.8 5798.3 5805.8 5579.7 5741.1 5753.5 5755.5 5760.5 5063.1 5130.4 5131.8 5154.8 5168.5 21619.5 23322.9 23386.8 23606.5 23709.4 2174.7 2287.4 2299.9 2663.7 2798.8 10252.6 10533.7 10796.9 10844.6 12117.7 1802.4 1896.7 1939.1 1989.1 2030.3 78018.6 84405.6 85696.0 86325.0 88623.9 10849.4 10973.3 11227.6 11791.8 11932.7 13981.7 15740.4 15774.7 15783.4 15799.8 7861.4 7918.3 7937.7 8152.5 8258.9 7628.2 7679.1 7688.7 7913.4 7938.2 4724.3 4776.8 4792.4 4845.0 4919.7 1963.5 1963.6 1987.9 2018.6 2034.2 807.3 900.8 978.6 1021.4 1023.2 18903.2 19899.2 20125.6 20194.4 20195.9 978.5 979.5 981.7 993.5 1005.4 10380.5 11090.1 11111.8 11124.4 11189.8 451763.0 472684.0 496925.0 521508.0 528142.0 94604.5 96882.9 98213.5 99582.5 100921.0 965.3 1011.3 1014.1 1024.5 1037.2 17392.0 17491.4 17921.5 18064.8 18313.8 28802.9 32470.1 42189.2 42926.4 43454.0 59383.7 60191.1 60269.7 60438.4 61823.7 7610.6 7882.1 7990.9 8090.2 8121.0 12405.8 13410.0 13578.0 13696.8 13937.9 8394.6 8722.0 8730.6 11655.2 12269.6 8761.0 8824.8 8886.5 8900.8 8953.5 9141.8 9252.6 9316.9 9322.4 9357.2 12706.9 12794.3 13031.7 13064.9 13196.0 12055.5 12654.6 12804.9 12850.7 13156.5 06:26:05 INFO - PROCESS | 885 | 06:26:05 INFO - PROCESS | 885 | [#3] mootools.html Cycles:180 Average:5797.87 Median:4800.12 stddev:3322.00 (69.2%) stddev-sans-first:3313.92 06:26:05 INFO - PROCESS | 885 | Values: 1273.4 1456.8 1499.9 1611.3 1770.8 7272.3 8534.8 8859.3 8898.2 9230.7 9020.1 9094.0 9109.5 9110.1 9114.1 2414.0 2442.8 2445.9 2446.5 2451.2 2398.7 2420.5 2451.3 2457.1 2462.2 2418.4 2422.3 2430.3 2445.1 2455.9 2333.6 2341.3 2345.7 2358.7 2375.1 2260.0 2326.5 2331.4 2336.0 2342.3 7533.8 7712.1 8812.2 9010.5 9072.6 5040.5 5178.5 5186.2 5259.0 5264.4 3568.1 3589.2 3610.9 3661.6 3673.4 1165.9 1171.6 1176.9 1180.8 1182.0 12237.8 12241.9 12282.1 12283.9 12295.3 4745.0 4760.1 4840.1 4860.9 4949.1 10482.2 10498.2 10499.1 10501.9 10503.4 5507.2 5526.4 5528.1 5532.8 5536.0 4973.0 4995.1 5034.9 5082.4 5086.2 4111.5 4111.5 4134.0 4195.7 4208.5 1339.0 1351.9 1352.5 1367.3 1367.8 873.7 880.9 896.1 901.1 903.2 5331.4 5380.0 5422.8 5480.9 5649.9 447.2 456.6 459.2 461.7 462.7 3666.8 3745.9 3750.4 3806.7 3880.9 10706.5 11191.8 11198.5 11206.8 11213.4 10708.1 10835.7 11063.2 11069.7 11108.8 8210.0 8308.2 8309.5 8311.3 8318.5 5469.0 5490.9 5524.5 5596.5 5673.6 10196.4 10208.4 10221.5 10286.8 10381.7 9429.7 9702.8 9732.3 9957.0 10383.6 7932.2 8096.9 8105.2 8137.5 8152.1 8530.4 8579.4 8629.0 8678.0 8688.7 7511.5 7579.7 7582.6 7586.2 7849.9 7670.8 7693.2 7718.7 7827.1 7866.9 6676.7 7102.6 7173.0 7202.4 7202.7 4539.9 4570.4 4584.0 4899.7 4903.3 4941.1 5023.4 5044.7 5124.4 5197.8 06:26:05 INFO - PROCESS | 885 | 06:26:05 INFO - PROCESS | 885 | [#4] prototype.html Cycles:180 Average:4581.00 Median:5090.93 stddev:1324.01 (26.0%) stddev-sans-first:1288.33 06:26:05 INFO - PROCESS | 885 | Values: 309.6 329.5 334.0 339.5 341.6 5160.7 5214.8 5375.7 5376.4 5433.3 5054.2 5291.5 5299.7 5299.7 5326.9 4066.5 4118.2 4162.0 4164.4 4178.8 3934.5 4050.4 4088.4 4125.5 4152.7 4109.5 4134.7 4145.6 4158.2 4160.4 4121.8 4167.3 4170.9 4172.0 4173.5 3478.2 3652.5 3664.6 3668.0 3672.3 5183.4 5189.0 5202.9 5219.2 5220.5 4758.2 4776.4 4777.2 4782.4 4801.8 5305.3 5451.8 5581.4 5622.9 5625.6 4083.1 4100.9 4101.0 4134.5 4147.2 5832.4 5881.3 5905.6 5911.7 5926.3 4060.2 4235.4 4286.7 4291.4 4294.7 4278.0 4280.6 4289.4 4290.4 4303.4 4607.6 4639.3 4745.7 4752.3 4763.8 5587.0 5595.8 5608.5 5654.2 5674.9 1459.2 1467.2 1481.0 1488.2 1501.5 948.9 954.2 961.1 965.3 983.2 4821.7 4824.9 4825.3 4868.3 4884.8 5025.7 5085.9 5090.4 5091.5 5107.4 4687.9 4692.6 4706.8 4750.0 4882.0 2642.6 2656.5 2684.7 2689.9 2700.3 5117.4 5202.9 5219.0 5276.4 5290.8 4986.3 5049.8 5114.7 5131.5 5148.8 5098.6 5129.0 5131.1 5137.7 5139.9 4975.0 4997.6 5005.5 5012.9 5031.8 5285.4 5653.4 5669.9 5679.9 5701.9 5905.7 5906.7 5920.4 5936.8 5957.3 4042.3 4118.5 4125.7 4134.8 4149.1 5496.6 5601.6 5635.4 5791.9 5807.7 5498.8 5645.4 5653.6 5657.5 5663.2 5522.2 5626.4 5681.1 5737.8 5750.9 5522.8 5587.3 5609.7 5612.4 5615.0 5585.4 5607.2 5638.2 5645.7 5671.3 5340.9 5489.1 5524.4 5536.3 5571.7 06:26:05 INFO - PROCESS | 885 | 06:26:05 INFO - PROCESS | 885 | [#5] yui.html Cycles:180 Average:21484.13 Median:4904.65 stddev:32094.39 (654.4%) stddev-sans-first:32146.66 06:26:05 INFO - PROCESS | 885 | Values: 748.9 763.7 768.9 792.3 806.9 15952.9 17202.9 17328.0 17389.9 17485.6 15364.9 15727.2 15868.8 15898.1 15902.3 806.5 813.1 813.8 813.8 814.9 667.3 688.8 796.6 814.6 820.5 869.0 869.6 871.3 871.8 872.7 869.2 869.4 869.6 869.8 870.0 502.2 517.8 603.1 620.4 637.0 15104.8 15211.4 15213.5 15267.3 15377.2 13718.4 13961.9 14269.3 14462.1 14477.3 12754.6 12839.4 13027.2 13159.5 13478.6 4230.2 4262.6 4421.9 4457.3 4461.8 86433.0 86640.6 87524.0 90471.0 91381.5 10641.8 10645.1 10724.4 10885.1 10977.7 21064.5 21065.4 21085.4 21109.5 21113.6 8084.8 8346.7 8414.4 8562.3 8740.2 4628.5 4649.6 4832.9 4976.4 4981.0 3321.0 3433.6 3453.3 3480.7 3482.7 579.3 585.0 590.3 594.9 605.8 640.9 669.7 677.3 687.2 706.6 8971.3 9510.2 9808.9 9841.8 9950.2 612.9 630.4 630.6 636.6 642.7 4038.6 4214.9 4235.3 4307.9 4407.6 74112.0 89805.6 124426.4 127650.0 128428.7 108933.0 108974.5 109748.0 110985.0 111208.4 63221.0 95269.5 95628.0 97020.0 97025.5 8192.2 8476.6 8505.3 8529.5 8966.4 55932.0 56682.4 57252.7 57365.7 57375.7 76973.6 80097.6 83539.0 85756.6 85856.6 11329.9 11450.5 11604.8 11771.1 12093.9 12336.8 12528.4 12706.4 13737.5 13739.6 11924.6 12182.5 12304.4 12712.1 12777.4 11456.7 11542.9 11631.5 11656.4 11988.2 10805.0 10953.9 11210.9 11394.3 11897.2 9591.8 9669.5 9776.4 9971.0 10196.5 9492.4 10144.6 10312.6 10353.5 10442.2 06:26:05 INFO - PROCESS | 885 | -------- Summary: end -------- 06:26:05 INFO - PROCESS | 885 | 06:26:06 INFO - TEST-INFO | 885: exit 0 06:26:06 INFO - TEST-OK | dromaeo_css | took 1115550ms 06:26:06 INFO - TEST-START | kraken 06:26:06 INFO - operating with platform_type : linux_ 06:26:06 INFO - Initialising browser for kraken test... 06:26:06 INFO - TEST-INFO | started process 1278 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpp4Wm2d/profile http://localhost:58119/getInfo.html) 06:26:10 INFO - PROCESS | 1278 | __metrics Screen width/height:1600/1200 06:26:10 INFO - PROCESS | 1278 | colorDepth:24 06:26:10 INFO - PROCESS | 1278 | Browser inner width/height: 1024/697 06:26:10 INFO - PROCESS | 1278 | __metrics 06:26:10 INFO - PROCESS | 1278 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 06:26:10 INFO - PROCESS | 1278 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:26:11 INFO - PROCESS | 1278 | console.error: PushService: 06:26:11 INFO - PROCESS | 1278 | stateChangeProcessEnqueue: Error transitioning state 06:26:11 INFO - PROCESS | 1278 | UnknownError 06:26:12 INFO - TEST-INFO | 1278: exit 0 06:26:12 INFO - Browser initialized. 06:26:12 INFO - Running cycle 1/1 for kraken test... 06:26:12 INFO - TEST-INFO | started process 1363 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpp4Wm2d/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:26:24 INFO - PROCESS | 1363 | RSS: Main: 173432832 06:26:24 INFO - PROCESS | 1363 | 06:26:26 INFO - PROCESS | 1363 | Cycle 1(1): loaded http://localhost:58119/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58119/tests/kraken/driver.html?testName=audio-beat-detection) 06:26:27 INFO - PROCESS | 1363 | RSS: Main: 232632320 06:26:27 INFO - PROCESS | 1363 | 06:26:30 INFO - PROCESS | 1363 | Cycle 1(1): loaded http://localhost:58119/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58119/tests/kraken/driver.html?testName=audio-dft) 06:26:30 INFO - PROCESS | 1363 | RSS: Main: 217292800 06:26:30 INFO - PROCESS | 1363 | 06:26:33 INFO - PROCESS | 1363 | Cycle 1(1): loaded http://localhost:58119/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58119/tests/kraken/driver.html?testName=audio-fft) 06:26:34 INFO - PROCESS | 1363 | RSS: Main: 298676224 06:26:34 INFO - PROCESS | 1363 | 06:26:36 INFO - PROCESS | 1363 | Cycle 1(1): loaded http://localhost:58119/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58119/tests/kraken/driver.html?testName=audio-oscillator) 06:26:37 INFO - PROCESS | 1363 | RSS: Main: 209281024 06:26:37 INFO - PROCESS | 1363 | 06:26:39 INFO - PROCESS | 1363 | Cycle 1(1): loaded http://localhost:58119/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58119/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:26:39 INFO - PROCESS | 1363 | RSS: Main: 213188608 06:26:39 INFO - PROCESS | 1363 | 06:26:42 INFO - PROCESS | 1363 | Cycle 1(1): loaded http://localhost:58119/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58119/tests/kraken/driver.html?testName=imaging-darkroom) 06:26:43 INFO - PROCESS | 1363 | RSS: Main: 249155584 06:26:43 INFO - PROCESS | 1363 | 06:26:46 INFO - PROCESS | 1363 | Cycle 1(1): loaded http://localhost:58119/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58119/tests/kraken/driver.html?testName=imaging-desaturate) 06:26:47 INFO - PROCESS | 1363 | RSS: Main: 249716736 06:26:47 INFO - PROCESS | 1363 | 06:26:50 INFO - PROCESS | 1363 | Cycle 1(1): loaded http://localhost:58119/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58119/tests/kraken/driver.html?testName=json-parse-financial) 06:26:50 INFO - PROCESS | 1363 | RSS: Main: 248827904 06:26:50 INFO - PROCESS | 1363 | 06:26:52 INFO - PROCESS | 1363 | Cycle 1(1): loaded http://localhost:58119/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58119/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:26:52 INFO - PROCESS | 1363 | RSS: Main: 210714624 06:26:52 INFO - PROCESS | 1363 | 06:26:54 INFO - PROCESS | 1363 | Cycle 1(1): loaded http://localhost:58119/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58119/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:26:55 INFO - PROCESS | 1363 | RSS: Main: 218169344 06:26:55 INFO - PROCESS | 1363 | 06:26:57 INFO - PROCESS | 1363 | Cycle 1(1): loaded http://localhost:58119/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58119/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:26:58 INFO - PROCESS | 1363 | RSS: Main: 222601216 06:26:58 INFO - PROCESS | 1363 | 06:27:00 INFO - PROCESS | 1363 | Cycle 1(1): loaded http://localhost:58119/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58119/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:27:01 INFO - PROCESS | 1363 | RSS: Main: 223608832 06:27:01 INFO - PROCESS | 1363 | 06:27:04 INFO - PROCESS | 1363 | Cycle 1(1): loaded http://localhost:58119/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58119/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:27:04 INFO - PROCESS | 1363 | RSS: Main: 221515776 06:27:04 INFO - PROCESS | 1363 | 06:27:06 INFO - PROCESS | 1363 | Cycle 1(1): loaded http://localhost:58119/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58119/tests/kraken/driver.html?testName=ai-astar) 06:27:06 INFO - PROCESS | 1363 | RSS: Main: 272904192 06:27:06 INFO - PROCESS | 1363 | 06:27:06 INFO - PROCESS | 1363 | __start_tp_report 06:27:06 INFO - PROCESS | 1363 | _x_x_mozilla_page_load 06:27:06 INFO - PROCESS | 1363 | _x_x_mozilla_page_load_details 06:27:06 INFO - PROCESS | 1363 | |i|pagename|runs| 06:27:06 INFO - PROCESS | 1363 | |0;ai-astar;112;114;113;113;113;112;114;112;113;113 06:27:06 INFO - PROCESS | 1363 | |1;audio-beat-detection;150;120;118;163;123;117;121;119;154;122 06:27:06 INFO - PROCESS | 1363 | |2;audio-dft;160;159;160;160;161;161;161;162;161;162 06:27:06 INFO - PROCESS | 1363 | |3;audio-fft;76;76;77;109;76;77;75;76;104;76 06:27:06 INFO - PROCESS | 1363 | |4;audio-oscillator;82;82;108;80;81;81;110;81;81;117 06:27:06 INFO - PROCESS | 1363 | |5;imaging-gaussian-blur;117;117;117;118;117;117;117;117;117;117 06:27:06 INFO - PROCESS | 1363 | |6;imaging-darkroom;146;145;145;145;145;146;145;146;146;145 06:27:06 INFO - PROCESS | 1363 | |7;imaging-desaturate;94;95;95;95;96;95;95;95;95;94 06:27:06 INFO - PROCESS | 1363 | |8;json-parse-financial;53;50;50;51;52;52;51;51;52;51 06:27:06 INFO - PROCESS | 1363 | |9;json-stringify-tinderbox;61;61;62;61;62;61;61;61;62;62 06:27:06 INFO - PROCESS | 1363 | |10;stanford-crypto-aes;84;85;80;80;86;79;78;85;79;79 06:27:06 INFO - PROCESS | 1363 | |11;stanford-crypto-ccm;140;140;123;131;129;125;127;124;160;127 06:27:06 INFO - PROCESS | 1363 | |12;stanford-crypto-pbkdf2;165;164;172;172;166;165;170;171;165;172 06:27:06 INFO - PROCESS | 1363 | |13;stanford-crypto-sha256-iterative;66;66;65;65;64;65;65;66;65;64 06:27:06 INFO - PROCESS | 1363 | __end_tp_report 06:27:06 INFO - PROCESS | 1363 | __start_cc_report 06:27:06 INFO - PROCESS | 1363 | _x_x_mozilla_cycle_collect,1635 06:27:06 INFO - PROCESS | 1363 | __end_cc_report 06:27:06 INFO - PROCESS | 1363 | __startTimestamp1474378026666__endTimestamp 06:27:06 INFO - PROCESS | 1363 | 06:27:06 INFO - PROCESS | 1363 | ------- Summary: start ------- 06:27:06 INFO - PROCESS | 1363 | Number of tests: 14 06:27:06 INFO - PROCESS | 1363 | 06:27:06 INFO - PROCESS | 1363 | [#0] ai-astar Cycles:10 Average:112.90 Median:113.00 stddev:0.74 (0.7%) stddev-sans-first:0.71 06:27:06 INFO - PROCESS | 1363 | Values: 112.0 114.0 113.0 113.0 113.0 112.0 114.0 112.0 113.0 113.0 06:27:06 INFO - PROCESS | 1363 | 06:27:06 INFO - PROCESS | 1363 | [#1] audio-beat-detection Cycles:10 Average:130.70 Median:122.50 stddev:17.60 (14.4%) stddev-sans-first:17.23 06:27:06 INFO - PROCESS | 1363 | Values: 150.0 120.0 118.0 163.0 123.0 117.0 121.0 119.0 154.0 122.0 06:27:06 INFO - PROCESS | 1363 | 06:27:06 INFO - PROCESS | 1363 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:0.95 (0.6%) stddev-sans-first:0.97 06:27:06 INFO - PROCESS | 1363 | Values: 160.0 159.0 160.0 160.0 161.0 161.0 161.0 162.0 161.0 162.0 06:27:06 INFO - PROCESS | 1363 | 06:27:06 INFO - PROCESS | 1363 | [#3] audio-fft Cycles:10 Average:82.20 Median:76.00 stddev:12.87 (16.9%) stddev-sans-first:13.46 06:27:06 INFO - PROCESS | 1363 | Values: 76.0 76.0 77.0 109.0 76.0 77.0 75.0 76.0 104.0 76.0 06:27:06 INFO - PROCESS | 1363 | 06:27:06 INFO - PROCESS | 1363 | [#4] audio-oscillator Cycles:10 Average:90.30 Median:81.00 stddev:14.92 (18.4%) stddev-sans-first:15.52 06:27:06 INFO - PROCESS | 1363 | Values: 82.0 82.0 108.0 80.0 81.0 81.0 110.0 81.0 81.0 117.0 06:27:06 INFO - PROCESS | 1363 | 06:27:06 INFO - PROCESS | 1363 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 06:27:06 INFO - PROCESS | 1363 | Values: 117.0 117.0 117.0 118.0 117.0 117.0 117.0 117.0 117.0 117.0 06:27:06 INFO - PROCESS | 1363 | 06:27:06 INFO - PROCESS | 1363 | [#6] imaging-darkroom Cycles:10 Average:145.40 Median:145.50 stddev:0.52 (0.4%) stddev-sans-first:0.50 06:27:06 INFO - PROCESS | 1363 | Values: 146.0 145.0 145.0 145.0 145.0 146.0 145.0 146.0 146.0 145.0 06:27:06 INFO - PROCESS | 1363 | 06:27:06 INFO - PROCESS | 1363 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.50 06:27:06 INFO - PROCESS | 1363 | Values: 94.0 95.0 95.0 95.0 96.0 95.0 95.0 95.0 95.0 94.0 06:27:06 INFO - PROCESS | 1363 | 06:27:06 INFO - PROCESS | 1363 | [#8] json-parse-financial Cycles:10 Average:51.30 Median:51.50 stddev:0.95 (1.8%) stddev-sans-first:0.78 06:27:06 INFO - PROCESS | 1363 | Values: 53.0 50.0 50.0 51.0 52.0 52.0 51.0 51.0 52.0 51.0 06:27:06 INFO - PROCESS | 1363 | 06:27:06 INFO - PROCESS | 1363 | [#9] json-stringify-tinderbox Cycles:10 Average:61.40 Median:61.50 stddev:0.52 (0.8%) stddev-sans-first:0.53 06:27:06 INFO - PROCESS | 1363 | Values: 61.0 61.0 62.0 61.0 62.0 61.0 61.0 61.0 62.0 62.0 06:27:06 INFO - PROCESS | 1363 | 06:27:06 INFO - PROCESS | 1363 | [#10] stanford-crypto-aes Cycles:10 Average:81.50 Median:82.00 stddev:3.10 (3.8%) stddev-sans-first:3.15 06:27:06 INFO - PROCESS | 1363 | Values: 84.0 85.0 80.0 80.0 86.0 79.0 78.0 85.0 79.0 79.0 06:27:06 INFO - PROCESS | 1363 | 06:27:06 INFO - PROCESS | 1363 | [#11] stanford-crypto-ccm Cycles:10 Average:132.60 Median:130.00 stddev:11.36 (8.7%) stddev-sans-first:11.73 06:27:06 INFO - PROCESS | 1363 | Values: 140.0 140.0 123.0 131.0 129.0 125.0 127.0 124.0 160.0 127.0 06:27:06 INFO - PROCESS | 1363 | 06:27:06 INFO - PROCESS | 1363 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.20 Median:170.50 stddev:3.46 (2.0%) stddev-sans-first:3.47 06:27:06 INFO - PROCESS | 1363 | Values: 165.0 164.0 172.0 172.0 166.0 165.0 170.0 171.0 165.0 172.0 06:27:06 INFO - PROCESS | 1363 | 06:27:06 INFO - PROCESS | 1363 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.10 Median:65.00 stddev:0.74 (1.1%) stddev-sans-first:0.71 06:27:06 INFO - PROCESS | 1363 | Values: 66.0 66.0 65.0 65.0 64.0 65.0 65.0 66.0 65.0 64.0 06:27:06 INFO - PROCESS | 1363 | -------- Summary: end -------- 06:27:06 INFO - PROCESS | 1363 | 06:27:07 INFO - TEST-INFO | 1363: exit 0 06:27:07 INFO - TEST-OK | kraken | took 61728ms 06:27:07 INFO - SUITE-END | took 1177s 06:27:08 INFO - Completed test suite (00:19:37) 06:27:08 INFO - javascript benchmark 06:27:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3041.1028746519783, 3052.6489453712825, 3078.984605076973, 3122.1101985934015, 3135.623725152982, 5194.142966410538, 5282.55098316643, 5299.390570084442, 5356.91964620531, 5403.783848646054, 5351.919721204185, 5367.141257398817, 5389.892202155955, 5397.730113494329, 5431.158170483572, 4851.393575803025, 4866.683665561744, 4927.7536123193695, 4955.504449555034, 4964.354633897588, 5003.449620541737, 5048.495150484959, 5054.115529782292, 5061.139606266925, 5063.493650634926, 4701.623870090384, 4707.905841883177, 4747.620190384761, 4859.854204373839, 5012.248162775576, 4895.388076490439, 4907.312976183337, 4965.478624744386, 4978.228374601942, 4978.6763860348965, 4841.975790121062, 4860.8298709545, 4876.292937524075, 4887.608991280689, 4936.531029552187, 5256.237845512417, 5346.4118946915805, 5351.331083614548, 5352.652077614943, 5354.43778403266, 5644.294463192101, 5674.148877668342, 5720.685362305072, 5729.942700573024, 5817.941820581782, 5762.88474230513, 5777.306723193202, 5781.566382521328, 5783.248177736909, 5787.50806181475, 5374.355077390699, 5374.892502149935, 5376.892462150813, 5382.67703937765, 5385.434529374437, 5648.237487939158, 5711.286089238845, 5739.454751798572, 5766.365699773021, 5772.538196944234, 5574.331080270394, 5629.7185140742795, 5631.099024156116, 5656.179853921159, 5705.0, 5721.313442386857, 5723.0, 5726.3700992890745, 5776.104703770922, 5798.768049278066, 5653.208550802891, 5706.229658996065, 5706.3722990471015, 5712.028955077563, 5715.685637289906, 5414.783408663609, 5430.701311427831, 5445.83662490126, 5709.800156994485, 5714.942850571524, 4419.447569053868, 4438.1567502174485, 4445.933311000337, 4447.911041779211, 4490.101979604092, 3934.468846705658, 3952.5454572724507, 3955.4462375267485, 3992.121733218687, 4002.5597184309704, 5265.605079619044, 5485.793125512381, 5489.1217405214975, 5489.39616642169, 5493.395726470085, 5559.305086864142, 5672.602917795714, 5676.403977582377, 5686.658800471985, 5773.787504624076, 5324.600654950894, 5341.86645333862, 5351.973240133774, 5357.7589008494715, 5361.222622719683, 4905.828296009694, 4915.11527924977, 4925.310456536088, 4933.087378834855, 4972.65191436596, 5750.246174916607, 5787.797427090031, 5913.994620914455, 5952.494038006645, 5966.254218222723, 5775.306963164448, 5792.102224155263, 5795.652260864362, 5803.39064398249, 5842.824715258549, 5937.198478205387, 5947.0, 5954.10688396744, 5955.0, 6001.789937352171, 5407.513323800877, 5407.567394608501, 5607.467290607386, 5631.521320687787, 5637.154426836008, 5765.827025189251, 5772.307323121252, 5778.422157784188, 5806.419358064329, 5827.475527202572, 5630.718464076862, 5633.267675202197, 5640.182173584725, 5641.182028605747, 5649.0, 5342.492463215988, 5367.919481207863, 5561.443855614406, 5628.971855140698, 5643.238162847963, 5528.80649177293, 5544.306961629796, 5557.360903495988, 5608.551315894801, 5609.5512359012, 5529.529989950738, 5625.043742563856, 5630.4369563043365, 5633.239512665901, 5646.604737668324, 5368.114261146867, 5403.486668766461, 5512.09050506678, 5518.0, 5523.5434202219585], "value": 5279.504135580205, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [572.3618165745195, 597.408565520135, 609.2262826210709, 610.6214147228717, 628.4783629587442, 30895.845520772422, 42498.0, 43201.56798432015, 43288.0, 43331.35002974958, 44682.553174468245, 44920.0, 45042.32436513447, 45072.54927450733, 45093.323600146025, 8872.201501864847, 9006.954965225197, 9021.413608115452, 9022.0, 9023.097690230958, 8827.823443531126, 8897.377183597151, 8903.332250081237, 8917.95541022294, 8918.241949434307, 8923.509206993613, 8963.058878817728, 8979.326550508738, 8980.730578082668, 8983.460992340448, 8910.420822646507, 8912.197902188802, 8918.598663060113, 8921.241694455979, 8922.643094276189, 8787.165219304155, 8891.199792018684, 8906.0, 8910.732678019669, 8915.821683566292, 41644.167116657496, 42039.36940945888, 42142.578574214174, 42401.36397954033, 42416.0, 3645.7447978641508, 3704.9629503705155, 3923.195744872294, 4105.794710264475, 4156.875293741193, 31308.53037204444, 31454.528182077287, 31651.84174079138, 31886.840565797254, 32548.83725581357, 4305.547917468652, 4309.461317335333, 4366.628836548897, 4368.847090351823, 4405.867823965286, 327579.36210319033, 329110.0, 329252.0, 330185.0, 330835.0, 55653.44346556483, 55876.161857572166, 55946.440535594935, 56013.159802603, 56045.71977140088, 52367.476325237025, 53435.19847202295, 57685.0, 58769.70615146897, 58899.70550147222, 16942.322307107825, 17067.317307307818, 17143.74284385735, 17164.656706865793, 17226.655466890592, 13301.334933253298, 13414.798778018338, 13415.329233538285, 13423.530176443777, 13450.86549134496, 14587.489437869624, 14831.258437078319, 15936.282867271, 16288.674226515403, 16310.26603802808, 5773.976640322862, 5776.971115144397, 5854.326752423526, 5888.911666325009, 6098.390160983955, 892.6875593542338, 1059.2108878885126, 1086.5925278020743, 1092.82514797632, 1092.9672109836717, 30957.72493464034, 31914.680853191636, 32715.0, 32876.342473150406, 34800.23103887815, 1083.712816103733, 1092.3118435385816, 1117.0784103114963, 1129.6272230164027, 1179.9233049851732, 16030.519084427486, 16699.499015029567, 16856.494305170865, 16943.029226400988, 17048.48854534366, 466207.0, 468648.0, 468685.0, 472729.0, 473735.0, 55657.72171139118, 55732.0, 55750.442495575335, 55840.720796395755, 56027.71986140043, 1357.124654597779, 1363.2686488159982, 1393.324237744693, 1402.57221547429, 1413.9505117320844, 29199.85400072986, 30416.847915761165, 31056.689433106283, 31169.220769480944, 31389.215269618442, 49685.0, 50054.249186261506, 50096.248556270955, 50096.49903500918, 50123.24815127849, 427538.0, 427642.0, 429345.0, 429448.0, 430218.0, 27213.591796122677, 27649.58525622077, 27670.308242294104, 27748.0, 27748.58377124385, 38589.0, 39606.40590391089, 39752.0, 49461.30117638819, 49533.752331238116, 38776.2244755109, 39249.60750392459, 39602.00994975149, 39923.80038099791, 41016.794916026425, 39764.2047159061, 40339.596604033584, 41037.58962410457, 41703.37444938389, 41965.790171048946, 39088.0, 39526.802365987984, 39831.00422489345, 39922.800385997885, 40297.194056118125, 33766.66233337635, 35956.0, 36592.63407365999, 36644.81677591702, 36997.81501092585, 27033.729662703907, 27414.862925685244, 27636.309092272855, 27651.30871728223, 27651.86174069117], "value": 19650.112350352214, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.8009435093753, 391.75319548684325, 400.73351221437736, 402.94761680981475, 415.96140195869185, 14219.360128794204, 17554.0, 18248.817511824876, 19668.114934827914, 19703.211871525138, 18360.44918652439, 19327.806721932775, 19858.801411985874, 19920.90039549804, 20140.89929550354, 5629.408912064236, 5759.82720518383, 5868.970655146718, 5897.646141231518, 5902.203202567663, 5656.123300888369, 5798.275215598051, 5873.119032145191, 5883.735231914553, 5888.087346461305, 5708.628939118944, 5745.597808153431, 5774.8267551973295, 5798.275215598051, 5805.796797112081, 5579.69311687861, 5741.138829175616, 5753.510951569122, 5755.482006619382, 5760.510356619671, 5063.139266324697, 5130.410002849682, 5131.794728210867, 5154.819581314636, 5168.534831865112, 21619.45951351213, 23322.905284376484, 23386.766132338627, 23606.527869442514, 23709.407264818346, 2174.7281589801273, 2287.433108320806, 2299.9310020699404, 2663.73362663734, 2798.762105221059, 10252.64115755945, 10533.736656583647, 10796.892031079668, 10844.566217351297, 12117.697057573543, 1802.369170790226, 1896.6870466373032, 1939.0789375046882, 1989.1247850945538, 2030.2691031228746, 78018.6099069501, 84405.57797210975, 85696.0, 86324.99999999937, 88623.85596249122, 10849.403282819363, 10973.341599504056, 11227.607033753942, 11791.823122653168, 11932.701682458008, 13981.65045873841, 15740.449084281996, 15774.68450630981, 15783.447579334668, 15799.842001579837, 7861.449698521163, 7918.287354138155, 7937.722179723682, 8152.470089444168, 8258.917410825936, 7628.199039100926, 7679.116901556281, 7688.692452301957, 7913.406494512935, 7938.166492518318, 4724.314974328772, 4776.808927642925, 4792.353032340659, 4844.975775121173, 4919.729414882144, 1963.4796778853615, 1963.5974625202084, 1987.8509111816672, 2018.6265540874986, 2034.1659919432961, 807.3097501636134, 900.8423525882944, 978.5694294510392, 1021.3667526133845, 1023.2019025160238, 18903.24387024531, 19899.204031838854, 20125.597488050156, 20194.39416817498, 20195.899020504803, 978.5351957820121, 979.4857699707568, 981.749653838281, 993.4982833669095, 1005.3716427232979, 10380.470639982292, 11090.116569691938, 11111.777764444665, 11124.443777811077, 11189.832152517718, 451763.0, 472684.0, 496925.0, 521508.0, 528142.0, 94604.52697736605, 96882.88963879515, 98213.50893245632, 99582.5020874891, 100921.0, 965.3194497878978, 1011.2971484817981, 1014.1329163565193, 1024.4979959819634, 1037.1961729659424, 17392.0, 17491.38780142689, 17921.462356129337, 18064.819351806313, 18313.816861831212, 28802.855985719936, 32470.07769300774, 42189.15621687488, 42926.35610465908, 43454.0, 59383.70308148432, 60191.09713354216, 60269.698651506464, 60438.39561604328, 61823.690881545306, 7610.581418022062, 7882.054153501387, 7990.880136798069, 8090.190980902098, 8121.0, 12405.813912791134, 13410.0, 13578.0, 13696.794548081985, 13937.860621393656, 8394.58027098655, 8722.040575536812, 8730.563471826255, 11655.18413711066, 12269.631911042683, 8761.0, 8824.779380515281, 8886.511241881759, 8900.777480563038, 8953.507557084424, 9141.77145571366, 9252.583633736363, 9316.906830931604, 9322.394044387092, 9357.20463760588, 12706.872931270938, 12794.296313702836, 13031.739365212454, 13064.869351306364, 13196.0, 12055.457504412498, 12654.557090501788, 12804.87195128074, 12850.742985140057, 13156.539521116714], "value": 9199.160035154844, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1273.4142294544508, 1456.7887656289838, 1499.902506337088, 1611.2588209423664, 1770.8494777943881, 7272.309130632595, 8534.829303413935, 8859.335549833764, 8898.154675305836, 9230.67692630758, 9020.097990200977, 9094.045125261851, 9109.544522773867, 9110.13453721897, 9114.13415725505, 2414.0, 2442.792362649177, 2445.8532488050773, 2446.522928029035, 2451.154351748644, 2398.70016247969, 2420.5037967216676, 2451.3136321829825, 2457.066314800373, 2462.249014050714, 2418.3954011497126, 2422.2733180046002, 2430.331658793828, 2445.058652418814, 2455.852648841066, 2333.614953532665, 2341.262502311766, 2345.7067866516686, 2358.740538540768, 2375.0618505690154, 2259.9548009039727, 2326.5114325991563, 2331.440454290975, 2335.9649605255936, 2342.2738950925095, 7533.849323013564, 7712.074551053909, 8812.16284452976, 9010.549472526413, 9072.63709451621, 5040.521150490697, 5178.533931946143, 5186.24799404088, 5259.0, 5264.42091369949, 3568.125809176742, 3589.192431702872, 3610.855565777366, 3661.6338366163172, 3673.412254039341, 1165.854268216473, 1171.606130382028, 1176.8705442401329, 1180.7664066996094, 1182.0366401382846, 12237.755244895141, 12241.938790305992, 12282.07884408664, 12283.93858030713, 12295.262284262883, 4744.976275118672, 4760.127958289183, 4840.10458065252, 4860.927086093711, 4949.059678661043, 10482.161427085815, 10498.160147188206, 10499.107575855985, 10501.89498105009, 10503.369797812156, 5507.228987941691, 5526.364468086222, 5528.06022976095, 5532.806351777668, 5536.031194540939, 4972.975135124301, 4995.075910956485, 5034.949650503448, 5082.440931497532, 5086.21163719624, 4111.48606424197, 4111.506619205715, 4134.049168691157, 4195.748255104703, 4208.452901122834, 1338.9665258368618, 1351.8850897673808, 1352.4860552989999, 1367.2548461088732, 1367.8426980897325, 873.7160422862595, 880.8942926848819, 896.0815164456484, 901.0763966933945, 903.2457897655531, 5331.360236771612, 5379.973100134553, 5422.8373148805595, 5480.890382192334, 5649.858753531194, 447.21960179486354, 456.6301295950291, 459.16661259814, 461.6560662306603, 462.71543001054107, 3666.7799932004164, 3745.9438108428394, 3750.4374343848094, 3806.7335286530233, 3880.902977425587, 10706.518206680717, 11191.776164476665, 11198.496067676973, 11206.775864482664, 11213.439328033566, 10708.1433485321, 10835.67492975212, 11063.22557420989, 11069.72325691848, 11108.777824443627, 8210.0, 8308.210719981593, 8309.501429914224, 8311.293540049042, 8318.500889946623, 5468.972655136858, 5490.862728431821, 5524.530414914777, 5596.468335508121, 5673.631213971079, 10196.439195844003, 10208.387496750218, 10221.488925553544, 10286.794264114826, 10381.740456488647, 9429.717108486757, 9702.805943881223, 9732.318737688576, 9956.950215248877, 10383.584656613652, 7932.206779322021, 8096.878546821685, 8105.189481051848, 8137.471064380797, 8152.144024877302, 8530.445521041112, 8579.35654825878, 8629.0, 8678.045415004219, 8688.652453901774, 7511.549307041595, 7579.696812127453, 7582.62086895664, 7586.165521792709, 7849.921500785147, 7670.8082297943, 7693.230676932262, 7718.729844455417, 7827.060752709711, 7866.9213307866185, 6676.732930682719, 7102.573845569283, 7173.0316407284345, 7202.423806095395, 7202.71189152449, 4539.909201816011, 4570.360149578995, 4584.037352156084, 4899.681520701286, 4903.33804936329, 4941.085899108532, 5023.422306434661, 5044.672096313728, 5124.385073791169, 5197.8440646780655], "value": 4567.210669856284, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [309.5542418916761, 329.4711987260447, 333.95805088125053, 339.4534798973652, 341.6259196180182, 5160.716160611165, 5214.791408343666, 5375.731213439332, 5376.360210135907, 5433.320834895638, 5054.1913293872985, 5291.523762861341, 5299.655522391042, 5299.655522391042, 5326.893462130755, 4066.4916885389325, 4118.19695159444, 4161.9999999999845, 4164.354525048622, 4178.791060446965, 3934.5081864766903, 4050.412690159925, 4088.427620133169, 4125.525564560069, 4152.709310348277, 4109.506859176903, 4134.689898257628, 4145.647619952308, 4158.2307273154565, 4160.375943608467, 4121.793910304487, 4167.270727622654, 4170.874873753793, 4171.998720307135, 4173.499180098408, 3478.2347883465595, 3652.543432070991, 3664.633536646341, 3668.0, 3672.3022625701033, 5183.382391892071, 5189.0, 5202.895942081137, 5219.243209734567, 5220.486059042873, 4758.2386818108935, 4776.379070720784, 4777.235642297251, 4782.378290822171, 4801.783919723621, 5305.283786688786, 5451.754671039813, 5581.376752926159, 5622.887542249132, 5625.606207565472, 4083.1425400665894, 4100.876973690764, 4100.958990410088, 4134.524529679065, 4147.191297696951, 5832.387599302055, 5881.294244690622, 5905.61218113743, 5911.728978269693, 5926.259217597757, 4060.2285565742413, 4235.407043013981, 4286.742795432284, 4291.420658211164, 4294.699371044059, 4278.0, 4280.636145927631, 4289.442372491556, 4290.377895205178, 4303.440552728188, 4607.631389488833, 4639.327297541905, 4745.7423782697615, 4752.263399173134, 4763.809047738066, 5586.988684073812, 5595.804146854841, 5608.46719561641, 5654.180143879197, 5674.914876276859, 1459.2484870291808, 1467.1930438258914, 1481.0, 1488.2186851902838, 1501.4519700309527, 948.9419297483256, 954.1555723184892, 961.053362437998, 965.3194497878978, 983.2429029647132, 4821.710697358169, 4824.855254342376, 4825.3485779419325, 4868.294097355934, 4884.804607815719, 5025.723585202849, 5085.923711144335, 5090.363704536933, 5091.490850914879, 5107.438181799999, 4687.929681054787, 4692.6245900327895, 4706.764661766898, 4749.95250047502, 4881.9511804881495, 2642.5903984882375, 2656.4687062587172, 2684.6845045927357, 2689.8702544931175, 2700.2574292069444, 5117.437081920985, 5202.947970520247, 5219.0, 5276.445973172762, 5290.761915713802, 4986.307394007195, 5049.838537136479, 5114.667546609458, 5131.486851314913, 5148.794048238028, 5098.59211263098, 5128.999535271759, 5131.1277082895995, 5137.6660517066275, 5139.897202056013, 4974.955259395568, 4997.55119991209, 5005.524475174852, 5012.924806127838, 5031.773570189277, 5285.445028271976, 5653.406392328746, 5669.85825354353, 5679.914801278067, 5701.942980570141, 5905.734241959208, 5906.70466476683, 5920.407959204045, 5936.821895343147, 5957.255343082114, 4042.2521833460905, 4118.485189351331, 4125.6591607728205, 4134.772587507716, 4149.066460046465, 5496.587755918402, 5601.60788744784, 5635.380108188178, 5791.8552036199435, 5807.651540907559, 5498.80754173602, 5645.350784659652, 5653.575981801464, 5657.547396208376, 5663.216086932603, 5522.22688823557, 5626.409227031267, 5681.0910254359105, 5737.770489180386, 5750.913736293875, 5522.784987302787, 5587.3015873015875, 5609.691466969192, 5612.410696876933, 5615.0, 5585.441455854382, 5607.243022191952, 5638.182463542846, 5645.745941432563, 5671.291088613923, 5340.946590534045, 5489.066858634122, 5524.447555244604, 5536.3114250154085, 5571.693556854412], "value": 4164.0945668089325, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [748.8729462159449, 763.6563546404119, 768.9384849212064, 792.2592376128321, 806.8910697055895, 15952.920235398822, 17202.913985430056, 17328.04695741735, 17389.91305043473, 17485.562860928483, 15364.923175384136, 15727.213639318046, 15868.84131158688, 15898.125603091825, 15902.284397202126, 806.5322113174345, 813.1137060603941, 813.8290958898639, 813.8290958898639, 814.9470284431493, 667.2860039757461, 688.7623769799411, 796.6335485676567, 814.5927036481759, 820.5486982159819, 868.9695860644879, 869.5782545465444, 871.2768402226169, 871.8212766382908, 872.655301156043, 869.1699427047155, 869.42183448007, 869.6434461870602, 869.7999460124144, 869.9782505437383, 502.2416151611057, 517.7825313368394, 603.1495591216408, 620.3610281410155, 636.961782293064, 15104.773428398474, 15211.391544338214, 15213.543593692097, 15267.312970916335, 15377.154256515887, 13718.38267277975, 13961.930190349085, 14269.286535673175, 14462.132272063709, 14477.34851931655, 12754.61736147917, 12839.422225999853, 13027.153235039692, 13159.53941612039, 13478.595642130751, 4230.238557059728, 4262.552431994627, 4421.889452763675, 4457.286834106528, 4461.754603496806, 86433.0, 86640.56679716561, 87524.0, 90471.0, 91381.54309228477, 10641.840372394421, 10645.126034556408, 10724.356538607708, 10885.129189664807, 10977.670669879917, 21064.473388165417, 21065.368038958855, 21085.367438976857, 21109.472263193235, 21113.577728445343, 8084.797880053045, 8346.70786522481, 8414.36892233085, 8562.315014798802, 8740.213380795632, 4628.514006029319, 4649.581537661559, 4832.9275060874115, 4976.377952755905, 4980.950190498049, 3320.9501857472155, 3433.5879694436326, 3453.309338132384, 3480.686738193524, 3482.6865582097225, 579.3279795437356, 585.0463744097094, 590.2680675961776, 594.8572342637735, 605.8243109498284, 640.8622146238581, 669.7421492725335, 677.3328271652517, 687.1856849633201, 706.5795851468368, 8971.327150463741, 9510.239180865514, 9808.9019109808, 9841.803163936824, 9950.203983681433, 612.874360756044, 630.4231628060273, 630.6153246519619, 636.6371168434041, 642.6850843086854, 4038.5759495253164, 4214.873553793391, 4235.2800023996, 4307.913841723148, 4407.625351845129, 74112.0, 89805.55097224472, 124426.37786811189, 127650.0, 128428.72570194362, 108933.0, 108974.45512772385, 109748.0, 110985.0, 111208.44395778132, 63221.0, 95269.52365238268, 95628.0, 97020.0, 97025.51487242519, 8192.18078192176, 8476.618552165044, 8505.319574433906, 8529.530875801642, 8966.372353935161, 55932.0, 56682.43317566771, 57252.713736431055, 57365.713171433876, 57375.71312143413, 76973.61513192399, 80097.59951200207, 83539.0, 85756.57121714352, 85856.57071714601, 11329.943350283196, 11450.541978320774, 11604.825927610924, 11771.11716621274, 12093.939530302292, 12336.81494777561, 12528.436220370288, 12706.36468176606, 13737.519186828413, 13739.587812365646, 11924.642260732191, 12182.512699492276, 12304.446299916346, 12712.11015228962, 12777.425015874122, 11456.713582160513, 11542.884571154182, 11631.53473861036, 11656.417179140839, 11988.220765650205, 10805.02754752076, 10953.890461095605, 11210.943945280222, 11394.25937314072, 11897.167198296036, 9591.760205994906, 9669.468179250209, 9776.413415195111, 9970.950145249228, 10196.490175491048, 9492.382995105296, 10144.594216231268, 10312.587496500057, 10353.482325883526, 10442.164626829683], "value": 7416.240725527226, "unit": "score"}], "name": "dromaeo_css", "value": 7158.981145336931, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 114.0, 113.0, 113.0, 113.0, 112.0, 114.0, 112.0, 113.0, 113.0], "value": 112.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 120.0, 118.0, 163.0, 123.0, 117.0, 121.0, 119.0, 154.0, 122.0], "value": 130.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 159.0, 160.0, 160.0, 161.0, 161.0, 161.0, 162.0, 161.0, 162.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 77.0, 109.0, 76.0, 77.0, 75.0, 76.0, 104.0, 76.0], "value": 82.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 108.0, 80.0, 81.0, 81.0, 110.0, 81.0, 81.0, 117.0], "value": 90.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 118.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 145.0, 145.0, 145.0, 146.0, 145.0, 146.0, 146.0, 145.0], "value": 145.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 94.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 50.0, 50.0, 51.0, 52.0, 52.0, 51.0, 51.0, 52.0, 51.0], "value": 51.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 62.0, 61.0, 62.0, 61.0, 61.0, 61.0, 62.0, 62.0], "value": 61.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 80.0, 80.0, 86.0, 79.0, 78.0, 85.0, 79.0, 79.0], "value": 81.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 140.0, 123.0, 131.0, 129.0, 125.0, 127.0, 124.0, 160.0, 127.0], "value": 132.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 164.0, 172.0, 172.0, 166.0, 165.0, 170.0, 171.0, 165.0, 172.0], "value": 168.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 65.0, 65.0, 64.0, 65.0, 65.0, 66.0, 65.0, 64.0], "value": 65.1, "unit": "score"}], "name": "kraken", "value": 1494.2999999999997, "alertThreshold": 2.0}]} 06:27:08 INFO - Return code: 0 06:27:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:27:08 INFO - # TBPL SUCCESS # 06:27:08 INFO - Running post-action listener: _resource_record_post_action 06:27:08 INFO - [mozharness: 2016-09-20 13:27:08.464754Z] Finished run-tests step (success) 06:27:08 INFO - Running post-run listener: _resource_record_post_run 06:27:08 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 8192; Write bytes: 405938176; Read time: 76; Write time: 290544 06:27:08 INFO - TinderboxPrint: CPU usage
13.1% 06:27:08 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 76 06:27:08 INFO - TinderboxPrint: I/O write bytes / time
405,938,176 / 290,544 06:27:08 INFO - TinderboxPrint: CPU idle
8,285.6 (86.9%) 06:27:08 INFO - TinderboxPrint: CPU user
1,196.1 (12.5%) 06:27:08 INFO - TinderboxPrint: Swap in / out
0 / 0 06:27:08 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 115163136; Read time: 0; Write time: 112408 06:27:08 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 4096; Write bytes: 290435072; Read time: 60; Write time: 178132 06:27:09 INFO - Running post-run listener: _upload_blobber_files 06:27:09 INFO - Blob upload gear active. 06:27:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:27:09 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 06:27:09 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:27:09 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:27:09 INFO - (blobuploader) - INFO - Open directory for files ... 06:27:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:27:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:27:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:27:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:27:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:27:10 INFO - (blobuploader) - INFO - Done attempting. 06:27:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:27:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:27:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:27:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:27:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:27:11 INFO - (blobuploader) - INFO - Done attempting. 06:27:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:27:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:27:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:27:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:27:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:27:16 INFO - (blobuploader) - INFO - Done attempting. 06:27:16 INFO - (blobuploader) - INFO - Iteration through files over. 06:27:16 INFO - Return code: 0 06:27:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:27:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:27:16 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/59edc6125df65bb2cb66205e0e90043afbeb43a724484ebfe7d175fdf02e98fdf6daf1277495dd4ab102780373ce852330f8c06d21c02212318b23680a6ec884", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/98fffa80153979d641a93137ac647e8301b6a0e6c5cd77ce0ae18405233dda4e601acda5dcc7fde1bbcd39a6cda9dc88db900cc36a7d9969d04985fd9728c786", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e327d1f4820f4245f3afda48ba15a8419d07da263a5fa6dc9b2de7a4dffe066d46954d0fc8a71c1ecb79e422c2abcf43f254297c1640877f38e318f4153f5f94"} 06:27:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:27:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:27:16 INFO - Contents: 06:27:16 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/59edc6125df65bb2cb66205e0e90043afbeb43a724484ebfe7d175fdf02e98fdf6daf1277495dd4ab102780373ce852330f8c06d21c02212318b23680a6ec884", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/98fffa80153979d641a93137ac647e8301b6a0e6c5cd77ce0ae18405233dda4e601acda5dcc7fde1bbcd39a6cda9dc88db900cc36a7d9969d04985fd9728c786", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e327d1f4820f4245f3afda48ba15a8419d07da263a5fa6dc9b2de7a4dffe066d46954d0fc8a71c1ecb79e422c2abcf43f254297c1640877f38e318f4153f5f94"} 06:27:16 INFO - Running post-run listener: copy_logs_to_upload_dir 06:27:16 INFO - Copying logs to upload dir... 06:27:16 INFO - mkdir: /builds/slave/test/build/upload/logs 06:27:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1240.024043 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-09-20 06:27:16.297424) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-20 06:27:16.302319) ========= 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=6d15e502d8e29d5d2676d664000001c3-1474376794.784953-1940298164 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/59edc6125df65bb2cb66205e0e90043afbeb43a724484ebfe7d175fdf02e98fdf6daf1277495dd4ab102780373ce852330f8c06d21c02212318b23680a6ec884", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/98fffa80153979d641a93137ac647e8301b6a0e6c5cd77ce0ae18405233dda4e601acda5dcc7fde1bbcd39a6cda9dc88db900cc36a7d9969d04985fd9728c786", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e327d1f4820f4245f3afda48ba15a8419d07da263a5fa6dc9b2de7a4dffe066d46954d0fc8a71c1ecb79e422c2abcf43f254297c1640877f38e318f4153f5f94"} build_url:https://queue.taskcluster.net/v1/task/YMqburc_Q9WQoBaqvvA8yA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011198 build_url: 'https://queue.taskcluster.net/v1/task/YMqburc_Q9WQoBaqvvA8yA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/59edc6125df65bb2cb66205e0e90043afbeb43a724484ebfe7d175fdf02e98fdf6daf1277495dd4ab102780373ce852330f8c06d21c02212318b23680a6ec884", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/98fffa80153979d641a93137ac647e8301b6a0e6c5cd77ce0ae18405233dda4e601acda5dcc7fde1bbcd39a6cda9dc88db900cc36a7d9969d04985fd9728c786", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e327d1f4820f4245f3afda48ba15a8419d07da263a5fa6dc9b2de7a4dffe066d46954d0fc8a71c1ecb79e422c2abcf43f254297c1640877f38e318f4153f5f94"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-20 06:27:16.327500) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 06:27:16.327846) ========= 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=6d15e502d8e29d5d2676d664000001c3-1474376794.784953-1940298164 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004405 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 06:27:16.410601) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-20 06:27:16.410932) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-20 06:27:16.411230) ========= ========= Total master_lag: 0.19 =========