builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-023 starttime: 1474427228.53 results: success (0) buildid: 20160920184716 builduid: 337235aed36d402d96b7079daa51fbe2 revision: 18762ffa1e9219b3a114e7f3e00a4127a4209023 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-20 20:07:08.527845) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-20 20:07:08.528349) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-20 20:07:08.528629) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-20 20:07:08.541709) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 20:07:08.542096) ========= 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-1474427227.830356-134478548 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-20 20:07:08-- 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% 81.4M=0s 2016-09-20 20:07:08 (81.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.180814 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 20:07:08.737093) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 20:07:08.737439) ========= 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-1474427227.830356-134478548 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015248 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 20:07:08.787652) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-20 20:07:08.788024) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 18762ffa1e9219b3a114e7f3e00a4127a4209023 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 18762ffa1e9219b3a114e7f3e00a4127a4209023 --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-1474427227.830356-134478548 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-20 20:07:09,062 truncating revision to first 12 chars 2016-09-20 20:07:09,063 Setting DEBUG logging. 2016-09-20 20:07:09,063 attempt 1/10 2016-09-20 20:07:09,063 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/18762ffa1e92?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-20 20:07:10,305 unpacking tar archive at: autoland-18762ffa1e92/testing/mozharness/ program finished with exit code 0 elapsedTime=1.656173 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-20 20:07:10.457508) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-20 20:07:10.457842) ========= script_repo_revision: 18762ffa1e9219b3a114e7f3e00a4127a4209023 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-20 20:07:10.458275) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-20 20:07:10.458544) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-20 20:07:10.467552) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-09-20 20:07:10.467944) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=6d15e502d8e29d5d2676d664000001c3-1474427227.830356-134478548 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 20:07:10 INFO - MultiFileLogger online at 20160920 20:07:10 in /builds/slave/test 20:07:10 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 20:07:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:07:10 INFO - {'append_to_log': False, 20:07:10 INFO - 'base_work_dir': '/builds/slave/test', 20:07:10 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 20:07:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:07:10 INFO - 'branch': 'Autoland-Non-PGO', 20:07:10 INFO - 'buildbot_json_path': 'buildprops.json', 20:07:10 INFO - 'config_files': ('talos/linux_config.py',), 20:07:10 INFO - 'default_actions': ('clobber', 20:07:10 INFO - 'read-buildbot-config', 20:07:10 INFO - 'download-and-extract', 20:07:10 INFO - 'populate-webroot', 20:07:10 INFO - 'create-virtualenv', 20:07:10 INFO - 'install', 20:07:10 INFO - 'run-tests'), 20:07:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:07:10 INFO - 'download_minidump_stackwalk': True, 20:07:10 INFO - 'download_symbols': 'ondemand', 20:07:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:07:10 INFO - 'tooltool.py': '/tools/tooltool.py', 20:07:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:07:10 INFO - '/tools/misc-python/virtualenv.py')}, 20:07:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:07:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:07:10 INFO - 'installer_path': 'installer.exe', 20:07:10 INFO - 'log_level': 'info', 20:07:10 INFO - 'log_name': 'talos', 20:07:10 INFO - 'log_to_console': True, 20:07:10 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 20:07:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 20:07:10 INFO - 'opt_config_files': (), 20:07:10 INFO - 'pip_index': False, 20:07:10 INFO - 'sps_profile': False, 20:07:10 INFO - 'sps_profile_interval': 0, 20:07:10 INFO - 'suite': 'dromaeojs', 20:07:10 INFO - 'system_bits': '32', 20:07:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:07:10 INFO - 'title': 'talos-linux64-ix-023', 20:07:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:07:10 INFO - 'use_talos_json': True, 20:07:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:07:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:07:10 INFO - 'work_dir': 'build'} 20:07:10 INFO - [mozharness: 2016-09-21 03:07:10.842102Z] Running clobber step. 20:07:10 INFO - Running pre-action listener: _resource_record_pre_action 20:07:10 INFO - Running main action method: clobber 20:07:10 INFO - rmtree: /builds/slave/test/build 20:07:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:07:11 INFO - Running post-action listener: _resource_record_post_action 20:07:11 INFO - [mozharness: 2016-09-21 03:07:11.304929Z] Finished clobber step (success) 20:07:11 INFO - [mozharness: 2016-09-21 03:07:11.305031Z] Running read-buildbot-config step. 20:07:11 INFO - Running pre-action listener: _resource_record_pre_action 20:07:11 INFO - Running main action method: read_buildbot_config 20:07:11 INFO - Using buildbot properties: 20:07:11 INFO - { 20:07:11 INFO - "project": "", 20:07:11 INFO - "product": "firefox", 20:07:11 INFO - "script_repo_revision": "production", 20:07:11 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 20:07:11 INFO - "repository": "", 20:07:11 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 20:07:11 INFO - "buildid": "20160920184716", 20:07:11 INFO - "pgo_build": "False", 20:07:11 INFO - "basedir": "/builds/slave/test", 20:07:11 INFO - "buildnumber": 676, 20:07:11 INFO - "slavename": "talos-linux64-ix-023", 20:07:11 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 20:07:11 INFO - "platform": "ubuntu64_hw", 20:07:11 INFO - "scheduler": "tests-autoland-linux64-talos", 20:07:11 INFO - "branch": "autoland", 20:07:11 INFO - "repo_path": "integration/autoland", 20:07:11 INFO - "revision": "18762ffa1e9219b3a114e7f3e00a4127a4209023", 20:07:11 INFO - "stage_platform": "linux64", 20:07:11 INFO - "builduid": "337235aed36d402d96b7079daa51fbe2", 20:07:11 INFO - "slavebuilddir": "test" 20:07:11 INFO - } 20:07:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/Meyd02SLQR27xo4-VBt1JA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2. 20:07:11 INFO - Running post-action listener: _resource_record_post_action 20:07:11 INFO - [mozharness: 2016-09-21 03:07:11.307494Z] Finished read-buildbot-config step (success) 20:07:11 INFO - [mozharness: 2016-09-21 03:07:11.307585Z] Running download-and-extract step. 20:07:11 INFO - Running pre-action listener: _resource_record_pre_action 20:07:11 INFO - Running main action method: download_and_extract 20:07:11 INFO - mkdir: /builds/slave/test/build/tests 20:07:11 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')]} 20:07:11 INFO - trying https://queue.taskcluster.net/v1/task/Meyd02SLQR27xo4-VBt1JA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 20:07:11 INFO - Downloading https://queue.taskcluster.net/v1/task/Meyd02SLQR27xo4-VBt1JA/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 20:07:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Meyd02SLQR27xo4-VBt1JA/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 20:07:11 INFO - Downloaded 1361 bytes. 20:07:11 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 20:07:11 INFO - Using the following test package requirements: 20:07:11 INFO - {u'common': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 20:07:11 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 20:07:11 INFO - u'firefox-52.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 20:07:11 INFO - u'gtest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 20:07:11 INFO - u'firefox-52.0a1.en-US.linux-x86_64.gtest.tests.zip'], 20:07:11 INFO - u'jittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 20:07:11 INFO - u'jsshell-linux-x86_64.zip'], 20:07:11 INFO - u'mochitest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 20:07:11 INFO - u'firefox-52.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 20:07:11 INFO - u'mozbase': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 20:07:11 INFO - u'reftest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 20:07:11 INFO - u'firefox-52.0a1.en-US.linux-x86_64.reftest.tests.zip'], 20:07:11 INFO - u'talos': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 20:07:11 INFO - u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'], 20:07:11 INFO - u'web-platform': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 20:07:11 INFO - u'firefox-52.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 20:07:11 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 20:07:11 INFO - u'firefox-52.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 20:07:11 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 20:07:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Meyd02SLQR27xo4-VBt1JA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 20:07:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Meyd02SLQR27xo4-VBt1JA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 20:07:11 INFO - Fetch https://queue.taskcluster.net/v1/task/Meyd02SLQR27xo4-VBt1JA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 20:07:12 INFO - Expected file size: 20370444 20:07:16 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 20:07:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Meyd02SLQR27xo4-VBt1JA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 20:07:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Meyd02SLQR27xo4-VBt1JA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 20:07:16 INFO - Fetch https://queue.taskcluster.net/v1/task/Meyd02SLQR27xo4-VBt1JA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 20:07:17 INFO - Expected file size: 20370444 20:07:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Meyd02SLQR27xo4-VBt1JA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip 20:07:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Meyd02SLQR27xo4-VBt1JA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 20:07:21 INFO - Fetch https://queue.taskcluster.net/v1/task/Meyd02SLQR27xo4-VBt1JA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip into memory 20:07:22 INFO - Expected file size: 14414059 20:07:23 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')]} 20:07:23 INFO - trying https://queue.taskcluster.net/v1/task/Meyd02SLQR27xo4-VBt1JA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 20:07:23 INFO - Downloading https://queue.taskcluster.net/v1/task/Meyd02SLQR27xo4-VBt1JA/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 20:07:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Meyd02SLQR27xo4-VBt1JA/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 20:07:24 INFO - Downloaded 55608950 bytes. 20:07:24 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Meyd02SLQR27xo4-VBt1JA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 20:07:24 INFO - mkdir: /builds/slave/test/properties 20:07:24 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:07:24 INFO - Writing to file /builds/slave/test/properties/build_url 20:07:24 INFO - Contents: 20:07:24 INFO - build_url:https://queue.taskcluster.net/v1/task/Meyd02SLQR27xo4-VBt1JA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 20:07:24 INFO - Running post-action listener: _resource_record_post_action 20:07:24 INFO - Running post-action listener: set_extra_try_arguments 20:07:24 INFO - [mozharness: 2016-09-21 03:07:24.810070Z] Finished download-and-extract step (success) 20:07:24 INFO - [mozharness: 2016-09-21 03:07:24.810300Z] Running populate-webroot step. 20:07:24 INFO - Running pre-action listener: _resource_record_pre_action 20:07:24 INFO - Running main action method: populate_webroot 20:07:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:07:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:07:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:07:24 INFO - '--tppagecycles', 20:07:24 INFO - '7'], 20:07:24 INFO - 'tests': ['tsvgm']}}, 20:07:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:07:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:07:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:07:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:07:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:07:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:07:24 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:07:24 INFO - 'talos_options': ['--disable-e10s'], 20:07:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:07:24 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:07:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:07:24 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:07:24 INFO - 'talos_options': ['--disable-e10s'], 20:07:24 INFO - 'tests': ['damp', 'tps']}, 20:07:24 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:07:24 INFO - 'tests': ['damp', 'tps']}, 20:07:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:07:24 INFO - 'tests': ['dromaeo_dom']}, 20:07:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:07:24 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:07:24 INFO - 'tests': ['basic_compositor_video']}, 20:07:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:07:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:07:24 INFO - 'tests': ['a11yr', 20:07:24 INFO - 'ts_paint', 20:07:24 INFO - 'tpaint', 20:07:24 INFO - 'sessionrestore', 20:07:24 INFO - 'sessionrestore_no_auto_restore', 20:07:24 INFO - 'tabpaint']}, 20:07:24 INFO - 'other-e10s': {'tests': ['a11yr', 20:07:24 INFO - 'ts_paint', 20:07:24 INFO - 'tpaint', 20:07:24 INFO - 'sessionrestore', 20:07:24 INFO - 'sessionrestore_no_auto_restore', 20:07:24 INFO - 'tabpaint']}, 20:07:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:07:24 INFO - 'ts_paint', 20:07:24 INFO - 'tpaint', 20:07:24 INFO - 'sessionrestore', 20:07:24 INFO - 'sessionrestore_no_auto_restore', 20:07:24 INFO - 'tabpaint']}, 20:07:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:07:24 INFO - 'ts_paint', 20:07:24 INFO - 'tpaint', 20:07:24 INFO - 'sessionrestore', 20:07:24 INFO - 'sessionrestore_no_auto_restore', 20:07:24 INFO - 'tabpaint']}, 20:07:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:07:24 INFO - 'tests': ['a11yr', 20:07:24 INFO - 'ts_paint', 20:07:24 INFO - 'tpaint', 20:07:24 INFO - 'sessionrestore', 20:07:24 INFO - 'sessionrestore_no_auto_restore', 20:07:24 INFO - 'tabpaint']}, 20:07:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:07:24 INFO - 'tests': ['a11yr', 20:07:24 INFO - 'ts_paint', 20:07:24 INFO - 'tpaint', 20:07:24 INFO - 'sessionrestore', 20:07:24 INFO - 'sessionrestore_no_auto_restore', 20:07:24 INFO - 'tabpaint']}, 20:07:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:07:24 INFO - 'tests': ['tsvgx', 20:07:24 INFO - 'tsvgr_opacity', 20:07:24 INFO - 'tart', 20:07:24 INFO - 'tscrollx', 20:07:24 INFO - 'cart']}, 20:07:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:07:24 INFO - 'tsvgr_opacity', 20:07:24 INFO - 'tart', 20:07:24 INFO - 'tscrollx', 20:07:24 INFO - 'cart']}, 20:07:24 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:07:24 INFO - 'talos_options': ['--disable-e10s'], 20:07:24 INFO - 'tests': ['tp5o']}, 20:07:24 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:07:24 INFO - 'tests': ['tp5o']}, 20:07:24 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:07:24 INFO - 'talos_options': ['--disable-e10s', 20:07:24 INFO - '--xperf_path', 20:07:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:07:24 INFO - 'tests': ['tp5n']}, 20:07:24 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:07:24 INFO - 'talos_options': ['--xperf_path', 20:07:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:07:24 INFO - 'tests': ['tp5n']}}, 20:07:24 INFO - 'talos.zip': {'path': '', 20:07:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:07:24 INFO - Running post-action listener: _resource_record_post_action 20:07:24 INFO - [mozharness: 2016-09-21 03:07:24.830416Z] Finished populate-webroot step (success) 20:07:24 INFO - [mozharness: 2016-09-21 03:07:24.830505Z] Running create-virtualenv step. 20:07:24 INFO - Running pre-action listener: _resource_record_pre_action 20:07:24 INFO - Running main action method: create_virtualenv 20:07:24 INFO - Creating virtualenv /builds/slave/test/build/venv 20:07:24 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 20:07:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:07:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:07:24 INFO - Using real prefix '/usr' 20:07:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:07:25 INFO - Installing distribute.............................................................................................................................................................................................done. 20:07:27 INFO - Installing pip.................done. 20:07:27 INFO - Return code: 0 20:07:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:07:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:07:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:07:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:07:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:07:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:07:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:07:27 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 0x7fda0287df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23e28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24afca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24acf60>, '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 0x24add40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24ae1d0>, '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-1474427227.830356-134478548', '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 20:07:27 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 20:07:27 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 20:07:27 INFO - Using env: {'DISPLAY': ':0', 20:07:27 INFO - 'HOME': '/home/cltbld', 20:07:27 INFO - 'LANG': 'en_US.UTF-8', 20:07:27 INFO - 'LANGUAGE': 'en_US:en', 20:07:27 INFO - 'LOGNAME': 'cltbld', 20:07:27 INFO - 'MAIL': '/var/mail/cltbld', 20:07:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:07:27 INFO - 'MOZ_NO_REMOTE': '1', 20:07:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:07:27 INFO - 'NO_EM_RESTART': '1', 20:07:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:07:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:07:27 INFO - 'PWD': '/builds/slave/test', 20:07:27 INFO - 'SHELL': '/bin/bash', 20:07:27 INFO - 'SHLVL': '1', 20:07:27 INFO - 'TERM': 'linux', 20:07:27 INFO - 'TMOUT': '86400', 20:07:27 INFO - 'USER': 'cltbld', 20:07:27 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1474427227.830356-134478548', 20:07:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:07:27 INFO - '_': '/tools/buildbot/bin/python'} 20:07:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:07:27 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 20:07:27 INFO - Cleaning up... 20:07:27 INFO - Return code: 0 20:07:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:07:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:07:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:07:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:07:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:07:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:07:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:07:27 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 0x7fda0287df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23e28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24afca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24acf60>, '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 0x24add40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24ae1d0>, '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-1474427227.830356-134478548', '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 20:07:27 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 20:07:27 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 20:07:27 INFO - Using env: {'DISPLAY': ':0', 20:07:27 INFO - 'HOME': '/home/cltbld', 20:07:27 INFO - 'LANG': 'en_US.UTF-8', 20:07:27 INFO - 'LANGUAGE': 'en_US:en', 20:07:27 INFO - 'LOGNAME': 'cltbld', 20:07:27 INFO - 'MAIL': '/var/mail/cltbld', 20:07:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:07:27 INFO - 'MOZ_NO_REMOTE': '1', 20:07:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:07:27 INFO - 'NO_EM_RESTART': '1', 20:07:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:07:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:07:27 INFO - 'PWD': '/builds/slave/test', 20:07:27 INFO - 'SHELL': '/bin/bash', 20:07:27 INFO - 'SHLVL': '1', 20:07:27 INFO - 'TERM': 'linux', 20:07:27 INFO - 'TMOUT': '86400', 20:07:27 INFO - 'USER': 'cltbld', 20:07:27 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1474427227.830356-134478548', 20:07:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:07:27 INFO - '_': '/tools/buildbot/bin/python'} 20:07:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:07:27 INFO - Downloading/unpacking psutil>=3.1.1 20:07:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:07:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:07:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:07:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:07:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:07:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:07:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:07:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:07:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:07:29 INFO - Installing collected packages: psutil 20:07:29 INFO - Running setup.py install for psutil 20:07:29 INFO - building 'psutil._psutil_linux' extension 20:07:29 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 20:07:30 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 20:07:30 INFO - building 'psutil._psutil_posix' extension 20:07:30 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 20:07:30 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 20:07:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:07:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:07:30 INFO - Successfully installed psutil 20:07:30 INFO - Cleaning up... 20:07:30 INFO - Return code: 0 20:07:30 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20: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')]} 20:07:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:07:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20: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')]} 20:07:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:07:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:07:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda0287df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23e28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24afca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24acf60>, '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 0x24add40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24ae1d0>, '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-1474427227.830356-134478548', '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 20:07:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 20:07:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 20:07:30 INFO - Using env: {'DISPLAY': ':0', 20:07:30 INFO - 'HOME': '/home/cltbld', 20:07:30 INFO - 'LANG': 'en_US.UTF-8', 20:07:30 INFO - 'LANGUAGE': 'en_US:en', 20:07:30 INFO - 'LOGNAME': 'cltbld', 20:07:30 INFO - 'MAIL': '/var/mail/cltbld', 20:07:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:07:30 INFO - 'MOZ_NO_REMOTE': '1', 20:07:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:07:30 INFO - 'NO_EM_RESTART': '1', 20:07:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:07:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:07:30 INFO - 'PWD': '/builds/slave/test', 20:07:30 INFO - 'SHELL': '/bin/bash', 20:07:30 INFO - 'SHLVL': '1', 20:07:30 INFO - 'TERM': 'linux', 20:07:30 INFO - 'TMOUT': '86400', 20:07:30 INFO - 'USER': 'cltbld', 20:07:30 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1474427227.830356-134478548', 20:07:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:07:30 INFO - '_': '/tools/buildbot/bin/python'} 20:07:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:07:30 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:07:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:07:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:07:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:07:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:07:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:07:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:07:32 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:07:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:07:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:07:32 INFO - Installing collected packages: mozsystemmonitor 20:07:32 INFO - Running setup.py install for mozsystemmonitor 20:07:32 INFO - Successfully installed mozsystemmonitor 20:07:32 INFO - Cleaning up... 20:07:32 INFO - Return code: 0 20:07:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:07:32 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')]} 20:07:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:07:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:07:32 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')]} 20:07:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:07:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:07:32 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 0x7fda0287df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23e28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24afca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24acf60>, '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 0x24add40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24ae1d0>, '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-1474427227.830356-134478548', '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 20:07:32 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 20:07:32 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 20:07:32 INFO - Using env: {'DISPLAY': ':0', 20:07:32 INFO - 'HOME': '/home/cltbld', 20:07:32 INFO - 'LANG': 'en_US.UTF-8', 20:07:32 INFO - 'LANGUAGE': 'en_US:en', 20:07:32 INFO - 'LOGNAME': 'cltbld', 20:07:32 INFO - 'MAIL': '/var/mail/cltbld', 20:07:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:07:32 INFO - 'MOZ_NO_REMOTE': '1', 20:07:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:07:32 INFO - 'NO_EM_RESTART': '1', 20:07:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:07:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:07:32 INFO - 'PWD': '/builds/slave/test', 20:07:32 INFO - 'SHELL': '/bin/bash', 20:07:32 INFO - 'SHLVL': '1', 20:07:32 INFO - 'TERM': 'linux', 20:07:32 INFO - 'TMOUT': '86400', 20:07:32 INFO - 'USER': 'cltbld', 20:07:32 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1474427227.830356-134478548', 20:07:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:07:32 INFO - '_': '/tools/buildbot/bin/python'} 20:07:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:07:32 INFO - Downloading/unpacking jsonschema==2.5.1 20:07:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:07:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:07:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:07:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:07:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:07:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:07:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:07:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:07:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:07:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:07:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:07:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:07:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:07:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:07:35 INFO - Downloading functools32-3.2.3-2.tar.gz 20:07:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:07:35 INFO - warning: no files found matching '*.txt' 20:07:35 INFO - no previously-included directories found matching 'build' 20:07:35 INFO - no previously-included directories found matching 'dist' 20:07:35 INFO - no previously-included directories found matching '.git*' 20:07:35 INFO - Installing collected packages: jsonschema, functools32 20:07:35 INFO - Running setup.py install for functools32 20:07:35 INFO - warning: no files found matching '*.txt' 20:07:35 INFO - no previously-included directories found matching 'build' 20:07:35 INFO - no previously-included directories found matching 'dist' 20:07:35 INFO - no previously-included directories found matching '.git*' 20:07:35 INFO - Successfully installed jsonschema functools32 20:07:35 INFO - Cleaning up... 20:07:35 INFO - Return code: 0 20:07:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:07:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:07:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:07:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:07:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:07:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:07:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:07:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fda0287df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23e28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24afca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24acf60>, '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 0x24add40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24ae1d0>, '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-1474427227.830356-134478548', '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 20:07:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 20:07:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 20:07:35 INFO - Using env: {'DISPLAY': ':0', 20:07:35 INFO - 'HOME': '/home/cltbld', 20:07:35 INFO - 'LANG': 'en_US.UTF-8', 20:07:35 INFO - 'LANGUAGE': 'en_US:en', 20:07:35 INFO - 'LOGNAME': 'cltbld', 20:07:35 INFO - 'MAIL': '/var/mail/cltbld', 20:07:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:07:35 INFO - 'MOZ_NO_REMOTE': '1', 20:07:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:07:35 INFO - 'NO_EM_RESTART': '1', 20:07:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:07:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:07:35 INFO - 'PWD': '/builds/slave/test', 20:07:35 INFO - 'SHELL': '/bin/bash', 20:07:35 INFO - 'SHLVL': '1', 20:07:35 INFO - 'TERM': 'linux', 20:07:35 INFO - 'TMOUT': '86400', 20:07:35 INFO - 'USER': 'cltbld', 20:07:35 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1474427227.830356-134478548', 20:07:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:07:35 INFO - '_': '/tools/buildbot/bin/python'} 20:07:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:07:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:07:35 INFO - Cleaning up... 20:07:35 INFO - Return code: 0 20:07:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:07:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:07:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:07:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:07:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:07:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:07:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:07:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fda0287df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23e28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24afca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24acf60>, '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 0x24add40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24ae1d0>, '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-1474427227.830356-134478548', '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 20:07:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 20:07:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 20:07:35 INFO - Using env: {'DISPLAY': ':0', 20:07:35 INFO - 'HOME': '/home/cltbld', 20:07:35 INFO - 'LANG': 'en_US.UTF-8', 20:07:35 INFO - 'LANGUAGE': 'en_US:en', 20:07:35 INFO - 'LOGNAME': 'cltbld', 20:07:35 INFO - 'MAIL': '/var/mail/cltbld', 20:07:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:07:35 INFO - 'MOZ_NO_REMOTE': '1', 20:07:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:07:35 INFO - 'NO_EM_RESTART': '1', 20:07:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:07:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:07:35 INFO - 'PWD': '/builds/slave/test', 20:07:35 INFO - 'SHELL': '/bin/bash', 20:07:35 INFO - 'SHLVL': '1', 20:07:35 INFO - 'TERM': 'linux', 20:07:35 INFO - 'TMOUT': '86400', 20:07:35 INFO - 'USER': 'cltbld', 20:07:35 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1474427227.830356-134478548', 20:07:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:07:35 INFO - '_': '/tools/buildbot/bin/python'} 20:07:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:07:35 INFO - Downloading/unpacking blobuploader==1.2.4 20:07:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:07:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:07:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:07:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:07:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:07:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:07:37 INFO - Downloading blobuploader-1.2.4.tar.gz 20:07:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:07:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:07:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:07:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:07:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:07:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:07:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:07:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:07:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:07:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:07:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:07:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:07:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:07:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:07:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:07:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:07:38 INFO - Downloading docopt-0.6.1.tar.gz 20:07:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:07:38 INFO - Installing collected packages: blobuploader, requests, docopt 20:07:38 INFO - Running setup.py install for blobuploader 20:07:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:07:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:07:38 INFO - Running setup.py install for requests 20:07:38 INFO - Running setup.py install for docopt 20:07:38 INFO - Successfully installed blobuploader requests docopt 20:07:38 INFO - Cleaning up... 20:07:39 INFO - Return code: 0 20:07:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:07:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:07:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:07:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:07:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:07:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:07:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:07:39 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 0x7fda0287df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23e28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24afca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24acf60>, '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 0x24add40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24ae1d0>, '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-1474427227.830356-134478548', '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 20:07:39 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 20:07:39 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 20:07:39 INFO - Using env: {'DISPLAY': ':0', 20:07:39 INFO - 'HOME': '/home/cltbld', 20:07:39 INFO - 'LANG': 'en_US.UTF-8', 20:07:39 INFO - 'LANGUAGE': 'en_US:en', 20:07:39 INFO - 'LOGNAME': 'cltbld', 20:07:39 INFO - 'MAIL': '/var/mail/cltbld', 20:07:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:07:39 INFO - 'MOZ_NO_REMOTE': '1', 20:07:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:07:39 INFO - 'NO_EM_RESTART': '1', 20:07:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:07:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:07:39 INFO - 'PWD': '/builds/slave/test', 20:07:39 INFO - 'SHELL': '/bin/bash', 20:07:39 INFO - 'SHLVL': '1', 20:07:39 INFO - 'TERM': 'linux', 20:07:39 INFO - 'TMOUT': '86400', 20:07:39 INFO - 'USER': 'cltbld', 20:07:39 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1474427227.830356-134478548', 20:07:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:07:39 INFO - '_': '/tools/buildbot/bin/python'} 20:07:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:07:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:07:39 INFO - Running setup.py (path:/tmp/pip-Grwhw4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:07:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:07:39 INFO - Running setup.py (path:/tmp/pip-hNZXyu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:07:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:07:39 INFO - Running setup.py (path:/tmp/pip-pwMoGt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:07:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:07:39 INFO - Running setup.py (path:/tmp/pip-od3lyK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:07:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:07:39 INFO - Running setup.py (path:/tmp/pip-izCyB4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:07:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:07:39 INFO - Running setup.py (path:/tmp/pip-u2jhUp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:07:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:07:39 INFO - Running setup.py (path:/tmp/pip-JxWQDT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:07:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:07:39 INFO - Running setup.py (path:/tmp/pip-mUKnz5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:07:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:07:39 INFO - Running setup.py (path:/tmp/pip-PURcpF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:07:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:07:39 INFO - Running setup.py (path:/tmp/pip-pb27Ri-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:07:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:07:39 INFO - Running setup.py (path:/tmp/pip-Pn_W3x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:07:40 INFO - Running setup.py (path:/tmp/pip-5hBN4V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:07:40 INFO - Running setup.py (path:/tmp/pip-Xn47Xi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:07:40 INFO - Running setup.py (path:/tmp/pip-lnRvyJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:07:40 INFO - Running setup.py (path:/tmp/pip-iLTS8n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:07:40 INFO - Running setup.py (path:/tmp/pip-PUIkPY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:07:40 INFO - Running setup.py (path:/tmp/pip-OGIEQk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:07:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:07:40 INFO - Running setup.py install for manifestparser 20:07:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:07:40 INFO - Running setup.py install for mozcrash 20:07:40 INFO - Running setup.py install for mozdebug 20:07:40 INFO - Running setup.py install for mozdevice 20:07:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:07:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:07:40 INFO - Running setup.py install for mozfile 20:07:41 INFO - Running setup.py install for mozhttpd 20:07:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:07:41 INFO - Running setup.py install for mozinfo 20:07:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:07:41 INFO - Running setup.py install for mozInstall 20:07:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:07:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:07:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:07:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:07:41 INFO - Running setup.py install for mozleak 20:07:41 INFO - Running setup.py install for mozlog 20:07:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:07:41 INFO - Running setup.py install for moznetwork 20:07:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:07:41 INFO - Running setup.py install for mozprocess 20:07:41 INFO - Running setup.py install for mozprofile 20:07:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:07:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:07:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:07:41 INFO - Running setup.py install for mozrunner 20:07:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:07:41 INFO - Running setup.py install for mozscreenshot 20:07:42 INFO - Running setup.py install for moztest 20:07:42 INFO - Running setup.py install for mozversion 20:07:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:07:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:07:42 INFO - Cleaning up... 20:07:42 INFO - Return code: 0 20:07:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:07:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:07:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:07:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:07:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:07:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:07:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:07:42 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 0x7fda0287df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23e28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24afca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24acf60>, '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 0x24add40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24ae1d0>, '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-1474427227.830356-134478548', '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 20:07:42 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 20:07:42 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 20:07:42 INFO - Using env: {'DISPLAY': ':0', 20:07:42 INFO - 'HOME': '/home/cltbld', 20:07:42 INFO - 'LANG': 'en_US.UTF-8', 20:07:42 INFO - 'LANGUAGE': 'en_US:en', 20:07:42 INFO - 'LOGNAME': 'cltbld', 20:07:42 INFO - 'MAIL': '/var/mail/cltbld', 20:07:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:07:42 INFO - 'MOZ_NO_REMOTE': '1', 20:07:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:07:42 INFO - 'NO_EM_RESTART': '1', 20:07:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:07:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:07:42 INFO - 'PWD': '/builds/slave/test', 20:07:42 INFO - 'SHELL': '/bin/bash', 20:07:42 INFO - 'SHLVL': '1', 20:07:42 INFO - 'TERM': 'linux', 20:07:42 INFO - 'TMOUT': '86400', 20:07:42 INFO - 'USER': 'cltbld', 20:07:42 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1474427227.830356-134478548', 20:07:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:07:42 INFO - '_': '/tools/buildbot/bin/python'} 20:07:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:07:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:07:42 INFO - Running setup.py (path:/tmp/pip-j6rl8V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:07:42 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)) 20:07:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:07:42 INFO - Running setup.py (path:/tmp/pip-dVMpNM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:07:42 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)) 20:07:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:07:42 INFO - Running setup.py (path:/tmp/pip-Olv4z5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:07:42 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)) 20:07:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:07:42 INFO - Running setup.py (path:/tmp/pip-XzCJyo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:07:42 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)) 20:07:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:07:42 INFO - Running setup.py (path:/tmp/pip-CUoMvE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:07:42 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)) 20:07:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:07:42 INFO - Running setup.py (path:/tmp/pip-WB5Utj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:07:42 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)) 20:07:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:07:42 INFO - Running setup.py (path:/tmp/pip-XlTnFM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:07:43 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)) 20:07:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:07:43 INFO - Running setup.py (path:/tmp/pip-JGl7yu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:07:43 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)) 20:07:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:07:43 INFO - Running setup.py (path:/tmp/pip-45ok2G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:07:43 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)) 20:07:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:07:43 INFO - Running setup.py (path:/tmp/pip-cmI7NY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:07:43 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)) 20:07:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:07:43 INFO - Running setup.py (path:/tmp/pip-PKFmZo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:07:43 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)) 20:07:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:07:43 INFO - Running setup.py (path:/tmp/pip-1ygnI1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:07:43 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)) 20:07:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:07:43 INFO - Running setup.py (path:/tmp/pip-heGOTH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:07:43 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)) 20:07:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:07:43 INFO - Running setup.py (path:/tmp/pip-YOnfll-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:07:43 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)) 20:07:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:07:43 INFO - Running setup.py (path:/tmp/pip-wLmPZ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:07:43 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)) 20:07:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:07:43 INFO - Running setup.py (path:/tmp/pip-01sSmD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:07:43 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)) 20:07:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:07:43 INFO - Running setup.py (path:/tmp/pip-ZxL86n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:07:43 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)) 20:07:43 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)) 20:07:43 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)) 20:07:43 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)) 20:07:43 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)) 20:07:43 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)) 20:07:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:07:43 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 20:07:43 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 20:07:43 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 20:07:43 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 20:07:43 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 20:07:43 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 20:07:45 INFO - Downloading blessings-1.6.tar.gz 20:07:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:07:45 INFO - Installing collected packages: blessings 20:07:45 INFO - Running setup.py install for blessings 20:07:45 INFO - Successfully installed blessings 20:07:45 INFO - Cleaning up... 20:07:45 INFO - Return code: 0 20:07:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:07:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:07:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:07:46 INFO - Reading from file tmpfile_stdout 20:07:46 INFO - Current package versions: 20:07:46 INFO - argparse == 1.2.1 20:07:46 INFO - blessings == 1.6 20:07:46 INFO - blobuploader == 1.2.4 20:07:46 INFO - docopt == 0.6.1 20:07:46 INFO - functools32 == 3.2.3-2 20:07:46 INFO - jsonschema == 2.5.1 20:07:46 INFO - manifestparser == 1.1 20:07:46 INFO - mozInstall == 1.12 20:07:46 INFO - mozcrash == 0.17 20:07:46 INFO - mozdebug == 0.1 20:07:46 INFO - mozdevice == 0.48 20:07:46 INFO - mozfile == 1.2 20:07:46 INFO - mozhttpd == 0.7 20:07:46 INFO - mozinfo == 0.9 20:07:46 INFO - mozleak == 0.1 20:07:46 INFO - mozlog == 3.4 20:07:46 INFO - moznetwork == 0.27 20:07:46 INFO - mozprocess == 0.23 20:07:46 INFO - mozprofile == 0.28 20:07:46 INFO - mozrunner == 6.12 20:07:46 INFO - mozscreenshot == 0.1 20:07:46 INFO - mozsystemmonitor == 0.3 20:07:46 INFO - moztest == 0.7 20:07:46 INFO - mozversion == 1.4 20:07:46 INFO - psutil == 3.1.1 20:07:46 INFO - requests == 1.2.3 20:07:46 INFO - wsgiref == 0.1.2 20:07:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:07:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:07:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:07:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:07:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:07:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:07:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:07:46 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 0x7fda0287df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23e28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24afca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24acf60>, '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 0x24add40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24ae1d0>, '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-1474427227.830356-134478548', '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 20:07:46 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 20:07:46 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 20:07:46 INFO - Using env: {'DISPLAY': ':0', 20:07:46 INFO - 'HOME': '/home/cltbld', 20:07:46 INFO - 'LANG': 'en_US.UTF-8', 20:07:46 INFO - 'LANGUAGE': 'en_US:en', 20:07:46 INFO - 'LOGNAME': 'cltbld', 20:07:46 INFO - 'MAIL': '/var/mail/cltbld', 20:07:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:07:46 INFO - 'MOZ_NO_REMOTE': '1', 20:07:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:07:46 INFO - 'NO_EM_RESTART': '1', 20:07:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:07:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:07:46 INFO - 'PWD': '/builds/slave/test', 20:07:46 INFO - 'SHELL': '/bin/bash', 20:07:46 INFO - 'SHLVL': '1', 20:07:46 INFO - 'TERM': 'linux', 20:07:46 INFO - 'TMOUT': '86400', 20:07:46 INFO - 'USER': 'cltbld', 20:07:46 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1474427227.830356-134478548', 20:07:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:07:46 INFO - '_': '/tools/buildbot/bin/python'} 20:07:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:07:46 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)) 20:07:46 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)) 20:07:46 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)) 20:07:46 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)) 20:07:46 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)) 20:07:46 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)) 20:07:46 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)) 20:07:46 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)) 20:07:46 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)) 20:07:46 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)) 20:07:46 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)) 20:07:46 INFO - Cleaning up... 20:07:46 INFO - Return code: 0 20:07:46 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:07:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:07:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:07:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:07:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:07:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:07:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:07:46 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 0x7fda0287df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23e28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24afca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24acf60>, '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 0x24add40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24ae1d0>, '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-1474427227.830356-134478548', '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 20:07:46 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 20:07:46 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 20:07:46 INFO - Using env: {'DISPLAY': ':0', 20:07:46 INFO - 'HOME': '/home/cltbld', 20:07:46 INFO - 'LANG': 'en_US.UTF-8', 20:07:46 INFO - 'LANGUAGE': 'en_US:en', 20:07:46 INFO - 'LOGNAME': 'cltbld', 20:07:46 INFO - 'MAIL': '/var/mail/cltbld', 20:07:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:07:46 INFO - 'MOZ_NO_REMOTE': '1', 20:07:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:07:46 INFO - 'NO_EM_RESTART': '1', 20:07:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:07:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:07:46 INFO - 'PWD': '/builds/slave/test', 20:07:46 INFO - 'SHELL': '/bin/bash', 20:07:46 INFO - 'SHLVL': '1', 20:07:46 INFO - 'TERM': 'linux', 20:07:46 INFO - 'TMOUT': '86400', 20:07:46 INFO - 'USER': 'cltbld', 20:07:46 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1474427227.830356-134478548', 20:07:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:07:46 INFO - '_': '/tools/buildbot/bin/python'} 20:07:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:07:46 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:07:46 INFO - Cleaning up... 20:07:46 INFO - Return code: 0 20:07:46 INFO - Running post-action listener: _resource_record_post_action 20:07:46 INFO - Running post-action listener: _start_resource_monitoring 20:07:46 INFO - Starting resource monitoring. 20:07:46 INFO - [mozharness: 2016-09-21 03:07:46.551561Z] Finished create-virtualenv step (success) 20:07:46 INFO - [mozharness: 2016-09-21 03:07:46.552065Z] Running install step. 20:07:46 INFO - Running pre-action listener: _resource_record_pre_action 20:07:46 INFO - Running main action method: install 20:07:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:07:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:07:46 INFO - Reading from file tmpfile_stdout 20:07:46 INFO - Detecting whether we're running mozinstall >=1.0... 20:07:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:07:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:07:46 INFO - Reading from file tmpfile_stdout 20:07:46 INFO - Output received: 20:07:46 INFO - Usage: mozinstall [options] installer 20:07:46 INFO - Options: 20:07:46 INFO - -h, --help show this help message and exit 20:07:46 INFO - -d DEST, --destination=DEST 20:07:46 INFO - Directory to install application into. [default: 20:07:46 INFO - "/builds/slave/test"] 20:07:46 INFO - --app=APP Application being installed. [default: firefox] 20:07:46 INFO - mkdir: /builds/slave/test/build/application 20:07:46 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'] 20:07:46 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 20:08:02 INFO - Reading from file tmpfile_stdout 20:08:02 INFO - Output received: 20:08:02 INFO - /builds/slave/test/build/application/firefox/firefox 20:08:02 INFO - Running post-action listener: _resource_record_post_action 20:08:02 INFO - [mozharness: 2016-09-21 03:08:02.926092Z] Finished install step (success) 20:08:02 INFO - [mozharness: 2016-09-21 03:08:02.926336Z] Running run-tests step. 20:08:02 INFO - Running pre-action listener: _resource_record_pre_action 20:08:02 INFO - Running main action method: run_tests 20:08:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:08:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:08:02 INFO - Python 2.7.3 20:08:02 INFO - Return code: 0 20:08:02 INFO - grabbing minidump binary from tooltool 20:08: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')]} 20:08:02 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 0x24acf60>, '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 0x24add40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24ae1d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:08:02 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 20:08:02 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 20:08:02 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 20:08:02 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:08:03 INFO - Return code: 0 20:08:03 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 20:08:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:08:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:08:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 20:08:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:08:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Meyd02SLQR27xo4-VBt1JA/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 20:08:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Meyd02SLQR27xo4-VBt1JA/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 20:08:03 INFO - Using env: {'DISPLAY': ':0', 20:08:03 INFO - 'HOME': '/home/cltbld', 20:08:03 INFO - 'LANG': 'en_US.UTF-8', 20:08:03 INFO - 'LANGUAGE': 'en_US:en', 20:08:03 INFO - 'LOGNAME': 'cltbld', 20:08:03 INFO - 'MAIL': '/var/mail/cltbld', 20:08:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:08:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 20:08:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:08:03 INFO - 'MOZ_NO_REMOTE': '1', 20:08:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:08:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:08:03 INFO - 'NO_EM_RESTART': '1', 20:08:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:08:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:08:03 INFO - 'PWD': '/builds/slave/test', 20:08:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:08:03 INFO - 'SHELL': '/bin/bash', 20:08:03 INFO - 'SHLVL': '1', 20:08:03 INFO - 'TERM': 'linux', 20:08:03 INFO - 'TMOUT': '86400', 20:08:03 INFO - 'USER': 'cltbld', 20:08:03 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1474427227.830356-134478548', 20:08:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:08:03 INFO - '_': '/tools/buildbot/bin/python'} 20:08:03 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Meyd02SLQR27xo4-VBt1JA/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 20:08:03 INFO - mozversion application_buildid: 20160920184716 20:08:03 INFO - mozversion application_changeset: 18762ffa1e9219b3a114e7f3e00a4127a4209023 20:08:03 INFO - mozversion application_display_name: Nightly 20:08:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:08:03 INFO - mozversion application_name: Firefox 20:08:03 INFO - mozversion application_remotingname: firefox 20:08:03 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 20:08:03 INFO - mozversion application_vendor: Mozilla 20:08:03 INFO - mozversion application_version: 52.0a1 20:08:03 INFO - mozversion platform_buildid: 20160920184716 20:08:03 INFO - mozversion platform_changeset: 18762ffa1e9219b3a114e7f3e00a4127a4209023 20:08:03 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 20:08:03 INFO - mozversion platform_version: 52.0a1 20:08:03 INFO - using testdate: 1474427283 20:08:03 INFO - actual date: 1474427283 20:08:03 INFO - starting webserver on 'localhost:42861' 20:08:03 INFO - SUITE-START | Running 2 tests 20:08:03 INFO - TEST-START | dromaeo_css 20:08:03 INFO - operating with platform_type : linux_ 20:08:03 INFO - Initialising browser for dromaeo_css test... 20:08:03 INFO - TEST-INFO | started process 31369 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpADtJuB/profile http://localhost:42861/getInfo.html) 20:08:07 INFO - PROCESS | 31369 | __metrics Screen width/height:1600/1200 20:08:07 INFO - PROCESS | 31369 | colorDepth:24 20:08:07 INFO - PROCESS | 31369 | Browser inner width/height: 1024/697 20:08:07 INFO - PROCESS | 31369 | __metrics 20:08:08 INFO - PROCESS | 31369 | 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" 20:08:08 INFO - PROCESS | 31369 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:08:08 INFO - PROCESS | 31369 | console.error: PushService: 20:08:08 INFO - PROCESS | 31369 | stateChangeProcessEnqueue: Error transitioning state 20:08:08 INFO - PROCESS | 31369 | UnknownError 20:08:09 INFO - TEST-INFO | 31369: exit 0 20:08:09 INFO - Browser initialized. 20:08:09 INFO - Running cycle 1/1 for dromaeo_css test... 20:08:09 INFO - TEST-INFO | started process 31450 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpADtJuB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:08:21 INFO - PROCESS | 31450 | RSS: Main: 175054848 20:08:21 INFO - PROCESS | 31450 | 20:08:33 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.05005 seconds. 20:08:44 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 20:09:02 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 20:09:04 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.03341 seconds. 20:09:06 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.01675 seconds. 20:09:13 INFO - PROCESS | 31450 | 1474427353518 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] 20:09:21 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.01666 seconds. 20:09:29 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 20:09:31 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 20:09:36 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.01666 seconds. 20:09:41 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 20:09:45 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 20:09:47 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.03342 seconds. 20:09:49 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 20:09:51 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.16673 seconds. 20:09:56 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.05005 seconds. 20:10:03 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 20:10:12 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 2.1001 seconds. 20:10:13 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 20:10:15 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.05005 seconds. 20:10:30 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 20:10:32 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.05005 seconds. 20:10:42 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 20:10:53 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.05008 seconds. 20:10:55 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 20:10:59 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.06671 seconds. 20:11:04 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.05005 seconds. 20:11:12 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.68342 seconds. 20:11:14 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 20:11:15 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 20:11:23 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.08337 seconds. 20:11:26 INFO - PROCESS | 31450 | Cycle 1(1): loaded http://localhost:42861/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42861/tests/dromaeo/cssquery-ext.html) 20:11:27 INFO - PROCESS | 31450 | RSS: Main: 306720768 20:11:27 INFO - PROCESS | 31450 | 20:11:34 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 20:11:36 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.01662 seconds. 20:11:41 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 20:11:44 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 20:11:46 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 20:11:54 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 20:12:00 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 20:12:05 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 20:12:17 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 20:12:19 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 20:12:23 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.01675 seconds. 20:12:25 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 20:12:37 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 20:12:41 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.01678 seconds. 20:12:55 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 20:13:18 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 20:13:19 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.01676 seconds. 20:13:21 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 20:13:34 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 20:13:41 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 20:13:52 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 20:13:54 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.01678 seconds. 20:13:56 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.01675 seconds. 20:13:58 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 20:14:02 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 20:14:12 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 20:14:16 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 20:14:18 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 20:14:24 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 20:14:26 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 20:14:28 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 20:14:30 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 20:14:30 INFO - PROCESS | 31450 | Cycle 1(1): loaded http://localhost:42861/tests/dromaeo/cssquery-ext.html (next: http://localhost:42861/tests/dromaeo/cssquery-jquery.html) 20:14:31 INFO - PROCESS | 31450 | RSS: Main: 275890176 20:14:31 INFO - PROCESS | 31450 | 20:14:34 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 20:14:39 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 20:14:41 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 20:14:51 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 20:15:12 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 20:15:24 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 20:15:31 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 20:15:36 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 20:15:48 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 20:16:07 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 20:16:26 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 20:16:38 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 20:17:09 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 20:17:13 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 20:17:31 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 20:17:33 INFO - PROCESS | 31450 | Cycle 1(1): loaded http://localhost:42861/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42861/tests/dromaeo/cssquery-mootools.html) 20:17:33 INFO - PROCESS | 31450 | RSS: Main: 252313600 20:17:33 INFO - PROCESS | 31450 | 20:17:37 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.03339 seconds. 20:18:01 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 20:18:02 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 20:18:07 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 20:18:27 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.01666 seconds. 20:18:29 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 20:18:43 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 20:18:49 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 20:18:51 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 20:18:59 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 20:19:03 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 20:19:05 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 20:19:07 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 20:19:09 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 20:19:11 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 20:19:43 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 20:19:59 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 20:20:01 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 20:20:11 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 20:20:22 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 20:20:24 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 20:20:28 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 20:20:31 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.0001 seconds. 20:20:32 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 20:20:36 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 20:20:36 INFO - PROCESS | 31450 | Cycle 1(1): loaded http://localhost:42861/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42861/tests/dromaeo/cssquery-prototype.html) 20:20:36 INFO - PROCESS | 31450 | RSS: Main: 219455488 20:20:36 INFO - PROCESS | 31450 | 20:20:59 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 20:21:01 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.01675 seconds. 20:21:03 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 20:21:14 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 20:21:42 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 20:21:45 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 20:22:02 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.01675 seconds. 20:22:04 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 20:22:09 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.03343 seconds. 20:22:20 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 20:22:25 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 20:22:26 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 2.00008 seconds. 20:22:27 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 20:22:29 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 20:22:31 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 20:22:34 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 20:22:38 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 20:22:43 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 20:22:55 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 20:22:58 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.01675 seconds. 20:23:00 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.01678 seconds. 20:23:03 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 20:23:05 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 20:23:07 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 20:23:09 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 20:23:19 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 20:23:21 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 20:23:27 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 20:23:37 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 20:23:39 INFO - PROCESS | 31450 | Cycle 1(1): loaded http://localhost:42861/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42861/tests/dromaeo/cssquery-yui.html) 20:23:40 INFO - PROCESS | 31450 | RSS: Main: 219500544 20:23:40 INFO - PROCESS | 31450 | 20:23:43 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.01661 seconds. 20:23:44 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 20:23:46 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.01676 seconds. 20:23:49 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 20:24:03 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 20:24:09 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 20:24:20 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 20:24:27 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 20:24:37 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 20:24:41 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 20:25:22 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 20:25:23 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 2.03342 seconds. 20:25:32 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 20:25:34 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 20:25:36 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 20:25:38 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 20:25:42 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 20:25:44 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 20:25:46 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 20:25:48 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00008 seconds. 20:25:50 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 20:25:56 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 20:25:58 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 20:26:08 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 20:26:18 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 20:26:33 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.05006 seconds. 20:26:36 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 20:26:38 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 20:26:42 INFO - PROCESS | 31450 | [GFX1-]: Refresh driver waiting for the compositor for 1.00013 seconds. 20:26:43 INFO - PROCESS | 31450 | Cycle 1(1): loaded http://localhost:42861/tests/dromaeo/cssquery-yui.html (next: http://localhost:42861/tests/dromaeo/cssquery-dojo.html) 20:26:43 INFO - PROCESS | 31450 | RSS: Main: 309469184 20:26:43 INFO - PROCESS | 31450 | 20:26:43 INFO - PROCESS | 31450 | __start_tp_report 20:26:43 INFO - PROCESS | 31450 | _x_x_mozilla_page_load 20:26:43 INFO - PROCESS | 31450 | _x_x_mozilla_page_load_details 20:26:43 INFO - PROCESS | 31450 | |i|pagename|runs| 20:26:43 INFO - PROCESS | 31450 | |0;dojo.html;2586.61200819877;2610;2637.6307316975626;2646.2855029142124;2661.707212206657;4603.332516785064;4661.487236403993;4711.717296962184;4727.598154156896;4738.265568836831;4680.297955306705;4696.50686677899;4705.388299521066;4706.482286948424;4763.118823017744;4353.172897149548;4365.36702178184;4373.212821692094;4398.538153493885;4474.865754027384;4442.244818380883;4451.88870278244;4464.17412778637;4469.284914413678;4491.528389519102;4275.273203555403;4317.525072242051;4440.089781594781;4468.530804265538;4468.865934021985;4356.607905288539;4362.912741745179;4370.934435983463;4373.803178856927;4377.846775362879;4342.0013396918885;4345.435093437833;4345.565443455629;4351.042770590497;4354.346847972798;4649.325847752091;4665.88335291617;4679.438467383901;4686.789094490756;4693.342931989524;4969.875753106165;5022.698638081691;5026.974865125688;5027.371578552681;5028.874278143039;4976.751162441863;5021.748912554394;5025.874353141164;5058.544730974193;5122.974385128088;4676.485586585472;4685.765711714401;4686.273627587695;4696.694714843524;4749.64377671676;5106.131957567222;5143.254228136899;5152.690838549699;5156;5218.217267409843;4856.077345304381;4886.291487734258;4905.5094490550655;4916.926246106311;4923.975380123148;5034.647574669812;5038.5969122470115;5045.495450454999;5045.949540504622;5101.591872650253;5012.1980483122525;5014.623903207274;5015.871428928462;5018.447970723218;5066.138756411419;4890.486498917634;4943.110240156734;5001.674891132065;5006.549410553068;5017.071841709296;4143.854965076208;4152.501699795984;4163.188178305262;4235.152969406131;4290.592393722592;3661.377565813818;3667.9633203668154;3672.59601443841;3714.275716235308;3720.776753394805;4325.4593175853015;4359.629431498295;4362.7818609070055;4377.277749171351;4384.05742765307;5083.491650834887;5087.720175390389;5089.05852417304;5090.796368145307;5159.664621799571;4347.956520434818;4354.433923589913;4376.824927002948;4430.401895744098;4431.268840641323;4431.556844315607;4478;4510.481294651091;4519.525449827786;4551.340055691906;4808.663393562557;4812.109759694467;4823.614110871052;4874.90250194987;4884.951150488591;5087.313212716236;5107.10625640504;5109.565686916654;5116;5178.559822415137;4678.321643361625;4718.127146477913;4810.062037902575;4864.92702609454;4922.384701912261;4971.701697898137;4996.950030499794;5028.949710502849;5039.496050394931;5092.261622064854;5086.21163719624;5094.159463688598;5096.082705112967;5100.79596816138;5160.870978225425;4811.951880481291;4853.684510506806;4959.7272150032095;4966.180580204225;4977.925331119964;4915.852524424273;4957.182064959241;4957.553820156203;4961.727105009115;5026.195808670595;4880.41435027799;4880.877978050577;4884.438289596742;4890.877728056827;4946.3817022872145;4862.07620552094;4994.200927851526;5006.87482812918;5009.148444764472;5062.088824011713;4707.811687532598;4812.735299558418;4825.420949486084;4853.51464853526;4924.138275801792 20:26:43 INFO - PROCESS | 31450 | |1;ext.html;541.9647722898011;554.938956714761;559.9584772323481;578.0548802707572;578.6585914310558;27018;27738.16785496436;29671.851640741766;29679.851600741968;29711.96008139713;37948;39245.41131883024;39250.8037459813;42835.57164428355;43026.78486607563;7427.3315401613845;7575.507592006502;7581.545107293552;7583.279588439117;7586.165521792598;7632.580208088553;7706.344960678349;7710.7686769397005;7713;7713.151553329158;7742.341900938427;7778.4166187535875;7780.377569794403;7782.0661520617605;7783.066032076131;7697.615119244043;7718.189590093071;7750.883736743954;7753.8449231015065;7754.0695116586085;7606.125295591022;7669.578173200472;7672;7674.155842857282;7675.616219189074;37011.81494092512;37299;37349.626503734886;37354.8132259337;37356.81321593375;2902.9274268143254;2903.840288784116;2916.7958242923005;3065.4635438798346;3486.8430920608635;27440;27504.679439758045;27912.860435697894;28207.43585128286;29153.56269655957;3234.685491935547;3262.755293353008;3276.3283526876935;3339.0984434202874;3344.866205351783;244386;246144.76927615426;247384;248388;249209;47854;48098.27852582214;48144;48161.277580836315;48220.7588962053;31116;31632.841835790674;31764.364712705617;33514.83242583772;50771.746141269796;13559.322033898463;13703.383347749375;13729;13738.313084345942;13748.93125534386;10115.645952391631;10152.949235253776;10183.796324073477;10207.591696332063;10237.59049638021;16805.831941680426;17018.31926722942;17025.574360640836;17122.743158852627;17155.7426638598;6512;6543.509236807258;6579.440747536513;6625.271220165778;6632.204135503771;697.2765432589994;884.3941899798658;912.5346073502561;968.7578105473632;975.7072878136672;24532;26562.46875062488;26625.866870665912;26788.59817102745;26983;758.7951253161615;918.7749001494543;921.8064206516573;950.4107453378949;962.3326864706258;13494;14134.717305653829;14437.711245775026;14465.27673616315;14499.275036248146;354284;358617;358973;359439;360613;46832.29751553729;47298.29052564214;47311;47350;47375;1045.994770026145;1086.6739978006567;1130.5986374836814;1135.239389608964;1158.2529268621693;24799.752002479745;27071.45857082887;27661.446771064064;28459;28584.714152858207;38922.2215555693;39424.2115157701;39478;39501.60498395094;39510;288070;289507;289735;289800;290400;19675.70486442676;19945.401637950883;20058.598828023067;20078.397648070582;20156.697649534974;39923.00192495211;40013.799931001326;40392.596074038884;40425;41646.58353416548;34330.14174645654;34975.65024349724;35138.12154696153;35180.29639407146;35274.294514110086;34177;34660.30679386348;34758.13104672403;34912.825435872655;34994.300113998084;29177;29223.415531689672;29276.56085158767;29341.413171736873;29928;29720.256993575334;32067.839660801546;32355.676443236207;32617.510737338485;32734.34531309408;20913.1634734615;21216;21216.681749773456;21238.46903827355;21249.7875021254 20:26:43 INFO - PROCESS | 31450 | |2;jquery.html;344.30965913343744;361.77389131792614;369.44952021487984;377.9886603401898;392.46820558143713;12550.937245313771;16018.919905400486;16395.590110247234;17619.295228190887;17795.644087118282;15338.539843804703;15371.154586497738;15469.690606187898;16233.350665973376;16351.34594616217;4209.074003719177;4961.082199793041;5164.173732202848;5178.404483484391;5221.477852214767;4978.253262010691;5057.393112826448;5061.038402703474;5061.367329083864;5061.9240711389175;4858.1983972644475;5023.7236951967625;5053.36832895888;5059.974700126494;5064.037832811772;5038.387715930903;5092.567131793802;5098.362704661918;5102.107131252016;5103.489651034867;4367.759773212472;4397.692161548693;4403.933940990855;4582.770861456914;4590.517995610479;20632.69050964237;20724.48188795277;20846.47883802902;20901.477463063395;21020.474488137766;1920.6350793349218;2096.423483542023;2196.791304826039;2407.650890620868;2430.878456077207;12938.935305323508;13033.41349639259;13294.468221271138;13502.122362046533;13654.180749155083;1701.1797376806821;1723.0523212233347;1786.4729904678152;1791.5431564950984;1841.1714728372285;68877;69643.30356696469;69854.30145698528;70422.64788676024;70477.64761176161;9157;9242.3530352876;9300.441973481613;9726.70819875405;10133.391996480235;13571;13613.86386136126;13617.931910340385;13620.93189534046;13648.931755341358;6779;6992.720291188295;7039.26087760788;7171.103612048494;7209;6520.250171230275;6586.7035983380865;6768.526203165731;6783.525153239325;6787.151606049244;6049.122877182784;6103.328633850273;6155.969220153871;6212.254529456494;6269.7178626961895;1659.2533359988054;1718.518814731877;1720.4666553368495;1725.767021452113;1792.8476079533125;823.5058964621179;846.8602680557763;851.6125163050797;875.1948207648851;876.750126214026;16352.918235408746;16644;17452.214650340764;17530;17613;809.3201710563155;823.8434697407473;838.6603425612636;872.2062922740275;897.0491279244022;9451.243900487945;10002.49987500622;10037.247206459544;10072.143867771321;10177.389356638625;369014;375085;376572;379138;383762;46544.7672761634;64773.67613161904;68416.6579167101;68953.3104668957;69262.30737692559;834.2283387866199;842.4692443760392;876.0363600039905;880.8018195905998;883.3728053082255;11401.600943966921;15012.399504020153;15884.920575397511;16685.74971375406;16841.91579042146;35978.28043439064;36192.457113142795;36725.08187295339;36828.447573285885;38269.4259586112;43311;43526.782366087966;43548.346774799036;45850;45868.77065614651;6470.320616335217;6851.417629501549;6966.268541803038;6997.300269972962;7205.819854503469;9697.6605818796;11074.833877491683;11279;11300.660980170607;11302.43487825622;4566.908661826678;4657;8074.354051675734;9330.482907434243;9646.228301735984;4674.415698037746;7657.923420765944;8501.914980850112;9617.807643847224;9717.85423218638;8799.956000219958;9336.159745622926;9399.85900211511;9667.226621870093;10010.499475026074;6991.615461149482;7054.823629409306;7132.572045677276;7142.357187853118;7381.741639042608;9531.807684232526;9553.140217380469;9665.516724163905;9684.225261979165;9979.45113018791 20:26:43 INFO - PROCESS | 31450 | |3;mootools.html;1329.2024785128924;1360.2110775750064;1375.944962201512;1585.3737773579444;1649.5463747469444;6714.328567143295;6991.300869913006;7439.181690014094;8080.111187769348;8086.7573972780765;7563.205863384348;7807.882881756778;7871.92128078719;8124.309433698143;8128.55292958887;2183.541456294176;2210.071769856664;2215.94460138497;2230.7769223077644;2237.038073628339;1860.9255629774793;2200.5378870437225;2233.3746550965757;2236.7297193348763;2242.9775702242932;1918;2073.06711979609;2140.1332460353583;2150.6021386043553;2151.290074275493;2137.4015275722822;2156.137544982004;2161.7513985891587;2163.350994701591;2164.729408823897;2152.817010554105;2174.826013918883;2177.6080305544997;2178.411828806229;2181.6618424144285;8018.158093400168;8084.878726819103;8087.757367278932;8087.838243235103;8088.11030786613;4479.843205487825;4590.931136032962;4634.698744581625;4671.976640116812;4675.859724208279;2772.043644942512;2934.5891575179494;2975.687552806946;2980.5529170624363;2995.6105706258263;922.5525620074259;974.2303580171656;990.0495524296684;993.1310103659298;994.164901482758;10768.084712799422;10803.189760767973;10811.513481893333;10818.02637762605;10833.187510936712;3992.301347264217;4234.703570750018;4342.69601127918;4393.51671316155;4427.601515863524;9110.453372797654;9128.315376346667;9132.634694612274;9133.90866091344;9136.6802161924;4526.6152377047665;4529.909401812011;4531.161735079021;4542.568455996675;4543.659225558096;4127.33962565994;4142.440770495945;4148.875533733993;4154.729942553785;4159.625633692982;3650.6166852480637;3668.3763760160837;3774.1885494618787;3797.5442946846556;3821.6942644588366;1201.4293210724836;1211.8909298163207;1228.1893949992962;1229.3484453239798;1232.2729589542034;551.93697235864;640.8205702403242;763.8548675751589;793.8412317536515;794.039212552806;5016.724080175625;5143.948560514422;5199.714015729096;5267.473252674702;5332.600054995891;768.3430666779823;782.7964729170343;804.5494523067101;808.002117385029;827.4414770030205;3504.159001839591;3632.3461776880417;3665.138692407282;3689.206820533598;3783.981080094582;9549.761255968657;9901.108900199017;9962.701118966443;9973.950130249303;10015.549300281506;9621.470819105016;9934.403935763878;9936.751581210383;9938.850917236246;9969.451680157516;7250.057492525938;7340.669669864869;7341.632918354061;7344.228855970046;7424.071991001125;5026.34657494538;5105.540501354896;5106.259592359162;5156.793728250828;5388.218708287355;8829.763833063505;8940.329475289249;9050.954745226232;9077.092290771134;9115.453072815651;9060.139286767746;9199.126083022102;9228.492432916253;9283.39657922233;9312.72061838146;6657.068010478633;6666.799996000128;6723.4285085766305;6796.626185559841;6803.081583986296;7471.663775130023;7521.135069467081;7532.585707786125;7539.924600753922;7542.245775422414;6453.645049522322;6518;6628.53600247978;6637.30308317639;7000.474964377794;6477.481801455967;6526.216853977553;6546.18172728409;6549.508786841101;6575.342465753386;5790.536757059509;5822.2140011099655;5833.533317334688;5857.619254748428;5870.7945221918735;4383.736975781463;4443.955560444483;4535.206338890747;4587.334836448763;4597.195490789031;4395.099004703964;4459.308807134899;4515.126222617577;4520.615747661485;4660.603848672901 20:26:43 INFO - PROCESS | 31450 | |4;prototype.html;295.27215414004473;298.7609912070344;305.3465583650986;309.82959372345204;312.7154289596468;4699.647526435519;4719.811207551702;4730.9763451182735;4745.644076694244;4756.120117778205;4653.302004699297;4672.159011377959;4697.553732395417;4708.411448568929;4714.50497697742;3564.3227786720504;3662.6520480554436;3670.6329367063213;3722.813859307024;3758.2483503299313;3755.605661405554;3768.6608205261523;3768.905777355574;3769.3780526213277;3779.640934111269;3741.513603231576;3747.1943532140585;3747.231817477424;3751.8124093795336;3764.926995806196;3541.698955588778;3629.201575653352;3658.1586235165796;3722.181120153562;3757.9936051158966;3306.702396784277;3315.8507867146036;3325.551050608161;3336.6996970272794;3340.880804930351;4575.542445755431;4586.954130458719;4593.150267200545;4595.264757638795;4634.559716826896;4149.838045347307;4238.576142385737;4248.34150706641;4264.530901600852;4267.1892340455215;4911.926321105187;4924.8276310328965;4935.692041608976;4940.654154209206;4954.281629163751;3608.386574282352;3614.548181477315;3626.528551288342;3628.963710362889;3634.2186429917697;4978.352814134176;5170.586353091744;5175.275461435402;5181.927229108312;5186.795611864943;3616.9276614467562;3623.2391197848747;3624.4563315502887;3625.728070394731;3630.8910732678064;3664;3668.3030224257304;3673.540807399075;3682.5028621135802;3683.208110256308;4091.936096614842;4142.192272506893;4196.433481480022;4199;4210.894727631834;4899.951000490021;4923.556879880828;4929.13740095489;4929.7781599828095;4958.231474121517;1255.9889289122161;1269.3018839638162;1273.6497463197509;1274.241826113461;1275.3177050278243;761.3452430909414;804.9637766300531;812.7240232834512;822.1367564057715;827.2596026556319;4249.213895429355;4299.871003869889;4403.229434848889;4420.889477763081;4425.756583387944;5063.3417655704525;5080.891430056559;5089.745512724349;5151.433342332415;5156.278121063054;4417.933730994037;4451.1765323415275;4459.996500787297;4467.955320446819;4471.32930060487;2581.7409766454352;2710.8509032003035;2733.6992930777596;2744.807863449579;2746.4232511172477;4890.608751299887;4896.167651499253;4898.730569818694;4902.166631672624;4904.6321525885705;4967.627427942919;5191.5846732261325;5204.895902082013;5256.789728410898;5269.78920843162;4847.321375007502;4851.199552073868;4863.197572400514;4863.319135321129;4869.610431165569;5081.593472522263;5082.720450375265;5096.439391666988;5099.495648783692;5122.206058061007;4753.477117517001;4987.975060124676;4995.950040499694;5015.799368025384;5029;4822.324874517501;4844.61243100544;4859.392575928009;4863.465018847854;4868.245421959602;3633.02000409772;3648.726345524043;3652.2330310635066;3662.981685091664;3669.5229620150276;4996.051539899515;5097.425799970991;5101.489851014869;5129.153689641316;5145.511176438233;4910.06708725341;4935.235038569028;4941.369348115185;4942.4810394909455;4971.875703107451;4922.507749225192;4925.581325587365;4928.6057115431395;4939.75301234944;4950.8267210647455;4731.574158325767;4852.781624826821;4871.171900776805;4878.853634390974;4885.926711099408;4782;4843.612510999182;4881.633142720044;4966.453690094015;4992.5756310714;4844.757762111951;4847.200211964986;4847.321375007431;4857.878553036202;4885.5358740919555 20:26:43 INFO - PROCESS | 31450 | |5;yui.html;592.3780030967483;626.0984182776801;638.8793817552317;643.0258158889284;666.2271764752882;12312.507499700012;13067.411966461508;13309.933450332735;13341.13282636629;13348.666283342922;13072.607821765363;13122.409491572878;13190.538331158412;13233.13984591001;13321.666958326046;708.6881772020321;730.101974571275;733.7065173930417;734.8016035670365;734.988975165373;581.0992960910572;598.3986093975549;714.3677845107065;727.316322656701;732.7252280394852;764.1899586438393;770.5569297653843;772.2162005564329;772.4091070331201;773.2847116417291;762.2682225063945;763.602926478231;764.7896828372186;764.9655765490567;764.9732259370895;447.4094195661728;456.7670488051118;538.849122245768;554.8529639645498;561.5788158880839;11654.184207105505;11681.532738690443;12050.397480126047;12084.093692972974;12086.939565302206;11536.826947595793;12282.385880706019;12347.93826030873;12420.130590858644;12604.521523691572;11326.71683207918;11352.205345625809;11378.544858205662;11565.76868462626;12185.87814121865;4018.758874467512;4057.066874618825;4124.52567954686;4131.5455299916985;4175.582441755831;61468.692656536434;62271.37728622701;62408;63962.36037639657;64159.67920160416;8725.607347669371;8837.867431988525;8852.38033337673;8899.466032038099;8914.598843052077;17598.560035998948;17730.82269177292;17764.490018348326;17981.910090449463;18001.819981800276;6011.6693581853415;6527.379898909596;6897.793066208022;6899.344562266677;6912;3595.982020089883;3605.639436056426;3609.5488063992;3618.5838628557526;3686;2796.678381986057;2868.7131286871145;2887.321479452327;2887.3936473340414;2887.898923537666;483.48508838087326;485.44174199669953;487.3007234618334;489.08541028277347;493.7087118600043;502.20400664945737;509.52868596548785;526.081986932797;530.5039787798421;539.9217113518517;7029.22678505364;7434.784563057743;7534.54792712439;7583.317501424898;7783.4162437817395;392.6407337286408;407.48249722852404;462.71543001054783;477.8900852803805;535.1170568561904;3008.984955075254;3343.38147442719;3413.7269018478464;3490.89527314181;3591.120175557005;59825.401745982854;80965;104173;109595;110516.44741776239;51595;86079;87645.56177219073;87905;88627;78436.60781696055;79611;80948.59525702333;81220.59389703014;82394.58802706069;6417.582857114273;6553.361659585104;6802.523823332517;7010.614416207158;7081.362677359062;46512.302315465975;47509;47556.097756089366;49731.254031190285;50030.24954625756;62944.68527657333;68254.65872670605;68354;70396;72781.63609181921;9169.679061232824;9208.861867072133;9386.389884657476;9418;9800.166985806287;10188.949055254676;10308.639197628047;10436.217283703905;10447.58209671635;10713.625023124154;9003.63985440575;9215.677451289173;9421.38760980534;9429.717108486757;9534.469096671894;9204.874986297906;9299.907000930174;9515.809683806423;9944.403335799585;10001.949990250003;9112.134347237003;9255.629774809202;9462.479563624067;9476.147146756824;9979;8120.634571444181;8178.5092894426525;8255.752327430188;8446;8645.394822362374;7685.538867667958;8192.713255036046;8204.302634276153;8412.831743365221;8488.363372746946 20:26:43 INFO - PROCESS | 31450 | __end_tp_report 20:26:43 INFO - PROCESS | 31450 | __start_cc_report 20:26:43 INFO - PROCESS | 31450 | _x_x_mozilla_cycle_collect,1101 20:26:43 INFO - PROCESS | 31450 | __end_cc_report 20:26:43 INFO - PROCESS | 31450 | __startTimestamp1474428403515__endTimestamp 20:26:43 INFO - PROCESS | 31450 | 20:26:43 INFO - PROCESS | 31450 | ------- Summary: start ------- 20:26:43 INFO - PROCESS | 31450 | Number of tests: 6 20:26:43 INFO - PROCESS | 31450 | 20:26:43 INFO - PROCESS | 31450 | [#0] dojo.html Cycles:165 Average:4673.98 Median:4812.74 stddev:493.71 (10.3%) stddev-sans-first:467.28 20:26:43 INFO - PROCESS | 31450 | Values: 2586.6 2610.0 2637.6 2646.3 2661.7 4603.3 4661.5 4711.7 4727.6 4738.3 4680.3 4696.5 4705.4 4706.5 4763.1 4353.2 4365.4 4373.2 4398.5 4474.9 4442.2 4451.9 4464.2 4469.3 4491.5 4275.3 4317.5 4440.1 4468.5 4468.9 4356.6 4362.9 4370.9 4373.8 4377.8 4342.0 4345.4 4345.6 4351.0 4354.3 4649.3 4665.9 4679.4 4686.8 4693.3 4969.9 5022.7 5027.0 5027.4 5028.9 4976.8 5021.7 5025.9 5058.5 5123.0 4676.5 4685.8 4686.3 4696.7 4749.6 5106.1 5143.3 5152.7 5156.0 5218.2 4856.1 4886.3 4905.5 4916.9 4924.0 5034.6 5038.6 5045.5 5045.9 5101.6 5012.2 5014.6 5015.9 5018.4 5066.1 4890.5 4943.1 5001.7 5006.5 5017.1 4143.9 4152.5 4163.2 4235.2 4290.6 3661.4 3668.0 3672.6 3714.3 3720.8 4325.5 4359.6 4362.8 4377.3 4384.1 5083.5 5087.7 5089.1 5090.8 5159.7 4348.0 4354.4 4376.8 4430.4 4431.3 4431.6 4478.0 4510.5 4519.5 4551.3 4808.7 4812.1 4823.6 4874.9 4885.0 5087.3 5107.1 5109.6 5116.0 5178.6 4678.3 4718.1 4810.1 4864.9 4922.4 4971.7 4997.0 5028.9 5039.5 5092.3 5086.2 5094.2 5096.1 5100.8 5160.9 4812.0 4853.7 4959.7 4966.2 4977.9 4915.9 4957.2 4957.6 4961.7 5026.2 4880.4 4880.9 4884.4 4890.9 4946.4 4862.1 4994.2 5006.9 5009.1 5062.1 4707.8 4812.7 4825.4 4853.5 4924.1 20:26:43 INFO - PROCESS | 31450 | 20:26:43 INFO - PROCESS | 31450 | [#1] ext.html Cycles:180 Average:43780.89 Median:34709.22 stddev:79413.73 (228.8%) stddev-sans-first:79570.14 20:26:43 INFO - PROCESS | 31450 | Values: 542.0 554.9 560.0 578.1 578.7 27018.0 27738.2 29671.9 29679.9 29712.0 37948.0 39245.4 39250.8 42835.6 43026.8 7427.3 7575.5 7581.5 7583.3 7586.2 7632.6 7706.3 7710.8 7713.0 7713.2 7742.3 7778.4 7780.4 7782.1 7783.1 7697.6 7718.2 7750.9 7753.8 7754.1 7606.1 7669.6 7672.0 7674.2 7675.6 37011.8 37299.0 37349.6 37354.8 37356.8 2902.9 2903.8 2916.8 3065.5 3486.8 27440.0 27504.7 27912.9 28207.4 29153.6 3234.7 3262.8 3276.3 3339.1 3344.9 244386.0 246144.8 247384.0 248388.0 249209.0 47854.0 48098.3 48144.0 48161.3 48220.8 31116.0 31632.8 31764.4 33514.8 50771.7 13559.3 13703.4 13729.0 13738.3 13748.9 10115.6 10152.9 10183.8 10207.6 10237.6 16805.8 17018.3 17025.6 17122.7 17155.7 6512.0 6543.5 6579.4 6625.3 6632.2 697.3 884.4 912.5 968.8 975.7 24532.0 26562.5 26625.9 26788.6 26983.0 758.8 918.8 921.8 950.4 962.3 13494.0 14134.7 14437.7 14465.3 14499.3 354284.0 358617.0 358973.0 359439.0 360613.0 46832.3 47298.3 47311.0 47350.0 47375.0 1046.0 1086.7 1130.6 1135.2 1158.3 24799.8 27071.5 27661.4 28459.0 28584.7 38922.2 39424.2 39478.0 39501.6 39510.0 288070.0 289507.0 289735.0 289800.0 290400.0 19675.7 19945.4 20058.6 20078.4 20156.7 39923.0 40013.8 40392.6 40425.0 41646.6 34330.1 34975.7 35138.1 35180.3 35274.3 34177.0 34660.3 34758.1 34912.8 34994.3 29177.0 29223.4 29276.6 29341.4 29928.0 29720.3 32067.8 32355.7 32617.5 32734.3 20913.2 21216.0 21216.7 21238.5 21249.8 20:26:43 INFO - PROCESS | 31450 | 20:26:43 INFO - PROCESS | 31450 | [#2] jquery.html Cycles:180 Average:23198.39 Median:4909.64 stddev:62020.95 (1263.2%) stddev-sans-first:62171.19 20:26:43 INFO - PROCESS | 31450 | Values: 344.3 361.8 369.4 378.0 392.5 12550.9 16018.9 16395.6 17619.3 17795.6 15338.5 15371.2 15469.7 16233.4 16351.3 4209.1 4961.1 5164.2 5178.4 5221.5 4978.3 5057.4 5061.0 5061.4 5061.9 4858.2 5023.7 5053.4 5060.0 5064.0 5038.4 5092.6 5098.4 5102.1 5103.5 4367.8 4397.7 4403.9 4582.8 4590.5 20632.7 20724.5 20846.5 20901.5 21020.5 1920.6 2096.4 2196.8 2407.7 2430.9 12938.9 13033.4 13294.5 13502.1 13654.2 1701.2 1723.1 1786.5 1791.5 1841.2 68877.0 69643.3 69854.3 70422.6 70477.6 9157.0 9242.4 9300.4 9726.7 10133.4 13571.0 13613.9 13617.9 13620.9 13648.9 6779.0 6992.7 7039.3 7171.1 7209.0 6520.3 6586.7 6768.5 6783.5 6787.2 6049.1 6103.3 6156.0 6212.3 6269.7 1659.3 1718.5 1720.5 1725.8 1792.8 823.5 846.9 851.6 875.2 876.8 16352.9 16644.0 17452.2 17530.0 17613.0 809.3 823.8 838.7 872.2 897.0 9451.2 10002.5 10037.2 10072.1 10177.4 369014.0 375085.0 376572.0 379138.0 383762.0 46544.8 64773.7 68416.7 68953.3 69262.3 834.2 842.5 876.0 880.8 883.4 11401.6 15012.4 15884.9 16685.7 16841.9 35978.3 36192.5 36725.1 36828.4 38269.4 43311.0 43526.8 43548.3 45850.0 45868.8 6470.3 6851.4 6966.3 6997.3 7205.8 9697.7 11074.8 11279.0 11300.7 11302.4 4566.9 4657.0 8074.4 9330.5 9646.2 4674.4 7657.9 8501.9 9617.8 9717.9 8800.0 9336.2 9399.9 9667.2 10010.5 6991.6 7054.8 7132.6 7142.4 7381.7 9531.8 9553.1 9665.5 9684.2 9979.5 20:26:43 INFO - PROCESS | 31450 | 20:26:43 INFO - PROCESS | 31450 | [#3] mootools.html Cycles:180 Average:5143.78 Median:5150.37 stddev:2923.16 (56.8%) stddev-sans-first:2917.30 20:26:43 INFO - PROCESS | 31450 | Values: 1329.2 1360.2 1375.9 1585.4 1649.5 6714.3 6991.3 7439.2 8080.1 8086.8 7563.2 7807.9 7871.9 8124.3 8128.6 2183.5 2210.1 2215.9 2230.8 2237.0 1860.9 2200.5 2233.4 2236.7 2243.0 1918.0 2073.1 2140.1 2150.6 2151.3 2137.4 2156.1 2161.8 2163.4 2164.7 2152.8 2174.8 2177.6 2178.4 2181.7 8018.2 8084.9 8087.8 8087.8 8088.1 4479.8 4590.9 4634.7 4672.0 4675.9 2772.0 2934.6 2975.7 2980.6 2995.6 922.6 974.2 990.0 993.1 994.2 10768.1 10803.2 10811.5 10818.0 10833.2 3992.3 4234.7 4342.7 4393.5 4427.6 9110.5 9128.3 9132.6 9133.9 9136.7 4526.6 4529.9 4531.2 4542.6 4543.7 4127.3 4142.4 4148.9 4154.7 4159.6 3650.6 3668.4 3774.2 3797.5 3821.7 1201.4 1211.9 1228.2 1229.3 1232.3 551.9 640.8 763.9 793.8 794.0 5016.7 5143.9 5199.7 5267.5 5332.6 768.3 782.8 804.5 808.0 827.4 3504.2 3632.3 3665.1 3689.2 3784.0 9549.8 9901.1 9962.7 9974.0 10015.5 9621.5 9934.4 9936.8 9938.9 9969.5 7250.1 7340.7 7341.6 7344.2 7424.1 5026.3 5105.5 5106.3 5156.8 5388.2 8829.8 8940.3 9051.0 9077.1 9115.5 9060.1 9199.1 9228.5 9283.4 9312.7 6657.1 6666.8 6723.4 6796.6 6803.1 7471.7 7521.1 7532.6 7539.9 7542.2 6453.6 6518.0 6628.5 6637.3 7000.5 6477.5 6526.2 6546.2 6549.5 6575.3 5790.5 5822.2 5833.5 5857.6 5870.8 4383.7 4444.0 4535.2 4587.3 4597.2 4395.1 4459.3 4515.1 4520.6 4660.6 20:26:43 INFO - PROCESS | 31450 | 20:26:43 INFO - PROCESS | 31450 | [#4] prototype.html Cycles:180 Average:4127.26 Median:4717.16 stddev:1191.18 (25.3%) stddev-sans-first:1159.27 20:26:43 INFO - PROCESS | 31450 | Values: 295.3 298.8 305.3 309.8 312.7 4699.6 4719.8 4731.0 4745.6 4756.1 4653.3 4672.2 4697.6 4708.4 4714.5 3564.3 3662.7 3670.6 3722.8 3758.2 3755.6 3768.7 3768.9 3769.4 3779.6 3741.5 3747.2 3747.2 3751.8 3764.9 3541.7 3629.2 3658.2 3722.2 3758.0 3306.7 3315.9 3325.6 3336.7 3340.9 4575.5 4587.0 4593.2 4595.3 4634.6 4149.8 4238.6 4248.3 4264.5 4267.2 4911.9 4924.8 4935.7 4940.7 4954.3 3608.4 3614.5 3626.5 3629.0 3634.2 4978.4 5170.6 5175.3 5181.9 5186.8 3616.9 3623.2 3624.5 3625.7 3630.9 3664.0 3668.3 3673.5 3682.5 3683.2 4091.9 4142.2 4196.4 4199.0 4210.9 4900.0 4923.6 4929.1 4929.8 4958.2 1256.0 1269.3 1273.6 1274.2 1275.3 761.3 805.0 812.7 822.1 827.3 4249.2 4299.9 4403.2 4420.9 4425.8 5063.3 5080.9 5089.7 5151.4 5156.3 4417.9 4451.2 4460.0 4468.0 4471.3 2581.7 2710.9 2733.7 2744.8 2746.4 4890.6 4896.2 4898.7 4902.2 4904.6 4967.6 5191.6 5204.9 5256.8 5269.8 4847.3 4851.2 4863.2 4863.3 4869.6 5081.6 5082.7 5096.4 5099.5 5122.2 4753.5 4988.0 4996.0 5015.8 5029.0 4822.3 4844.6 4859.4 4863.5 4868.2 3633.0 3648.7 3652.2 3663.0 3669.5 4996.1 5097.4 5101.5 5129.2 5145.5 4910.1 4935.2 4941.4 4942.5 4971.9 4922.5 4925.6 4928.6 4939.8 4950.8 4731.6 4852.8 4871.2 4878.9 4885.9 4782.0 4843.6 4881.6 4966.5 4992.6 4844.8 4847.2 4847.3 4857.9 4885.5 20:26:43 INFO - PROCESS | 31450 | 20:26:43 INFO - PROCESS | 31450 | [#5] yui.html Cycles:180 Average:17425.70 Median:61870.03 stddev:26012.38 (42.0%) stddev-sans-first:26054.64 20:26:43 INFO - PROCESS | 31450 | Values: 592.4 626.1 638.9 643.0 666.2 12312.5 13067.4 13309.9 13341.1 13348.7 13072.6 13122.4 13190.5 13233.1 13321.7 708.7 730.1 733.7 734.8 735.0 581.1 598.4 714.4 727.3 732.7 764.2 770.6 772.2 772.4 773.3 762.3 763.6 764.8 765.0 765.0 447.4 456.8 538.8 554.9 561.6 11654.2 11681.5 12050.4 12084.1 12086.9 11536.8 12282.4 12347.9 12420.1 12604.5 11326.7 11352.2 11378.5 11565.8 12185.9 4018.8 4057.1 4124.5 4131.5 4175.6 61468.7 62271.4 62408.0 63962.4 64159.7 8725.6 8837.9 8852.4 8899.5 8914.6 17598.6 17730.8 17764.5 17981.9 18001.8 6011.7 6527.4 6897.8 6899.3 6912.0 3596.0 3605.6 3609.5 3618.6 3686.0 2796.7 2868.7 2887.3 2887.4 2887.9 483.5 485.4 487.3 489.1 493.7 502.2 509.5 526.1 530.5 539.9 7029.2 7434.8 7534.5 7583.3 7783.4 392.6 407.5 462.7 477.9 535.1 3009.0 3343.4 3413.7 3490.9 3591.1 59825.4 80965.0 104173.0 109595.0 110516.4 51595.0 86079.0 87645.6 87905.0 88627.0 78436.6 79611.0 80948.6 81220.6 82394.6 6417.6 6553.4 6802.5 7010.6 7081.4 46512.3 47509.0 47556.1 49731.3 50030.2 62944.7 68254.7 68354.0 70396.0 72781.6 9169.7 9208.9 9386.4 9418.0 9800.2 10188.9 10308.6 10436.2 10447.6 10713.6 9003.6 9215.7 9421.4 9429.7 9534.5 9204.9 9299.9 9515.8 9944.4 10001.9 9112.1 9255.6 9462.5 9476.1 9979.0 8120.6 8178.5 8255.8 8446.0 8645.4 7685.5 8192.7 8204.3 8412.8 8488.4 20:26:43 INFO - PROCESS | 31450 | -------- Summary: end -------- 20:26:43 INFO - PROCESS | 31450 | 20:26:44 INFO - TEST-INFO | 31450: exit 0 20:26:44 INFO - TEST-OK | dromaeo_css | took 1121357ms 20:26:44 INFO - TEST-START | kraken 20:26:44 INFO - operating with platform_type : linux_ 20:26:44 INFO - Initialising browser for kraken test... 20:26:44 INFO - TEST-INFO | started process 31757 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6mwWfr/profile http://localhost:42861/getInfo.html) 20:26:49 INFO - PROCESS | 31757 | __metrics Screen width/height:1600/1200 20:26:49 INFO - PROCESS | 31757 | colorDepth:24 20:26:49 INFO - PROCESS | 31757 | Browser inner width/height: 1024/697 20:26:49 INFO - PROCESS | 31757 | __metrics 20:26:49 INFO - PROCESS | 31757 | 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" 20:26:49 INFO - PROCESS | 31757 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:26:50 INFO - PROCESS | 31757 | console.error: PushService: 20:26:50 INFO - PROCESS | 31757 | stateChangeProcessEnqueue: Error transitioning state 20:26:50 INFO - PROCESS | 31757 | UnknownError 20:26:50 INFO - TEST-INFO | 31757: exit 0 20:26:50 INFO - Browser initialized. 20:26:50 INFO - Running cycle 1/1 for kraken test... 20:26:50 INFO - TEST-INFO | started process 31839 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6mwWfr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:27:03 INFO - PROCESS | 31839 | RSS: Main: 175755264 20:27:03 INFO - PROCESS | 31839 | 20:27:05 INFO - PROCESS | 31839 | Cycle 1(1): loaded http://localhost:42861/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42861/tests/kraken/driver.html?testName=audio-beat-detection) 20:27:06 INFO - PROCESS | 31839 | RSS: Main: 238403584 20:27:06 INFO - PROCESS | 31839 | 20:27:09 INFO - PROCESS | 31839 | Cycle 1(1): loaded http://localhost:42861/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42861/tests/kraken/driver.html?testName=audio-dft) 20:27:10 INFO - PROCESS | 31839 | RSS: Main: 218828800 20:27:10 INFO - PROCESS | 31839 | 20:27:13 INFO - PROCESS | 31839 | Cycle 1(1): loaded http://localhost:42861/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42861/tests/kraken/driver.html?testName=audio-fft) 20:27:14 INFO - PROCESS | 31839 | RSS: Main: 300564480 20:27:14 INFO - PROCESS | 31839 | 20:27:16 INFO - PROCESS | 31839 | Cycle 1(1): loaded http://localhost:42861/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42861/tests/kraken/driver.html?testName=audio-oscillator) 20:27:16 INFO - PROCESS | 31839 | RSS: Main: 210321408 20:27:16 INFO - PROCESS | 31839 | 20:27:19 INFO - PROCESS | 31839 | Cycle 1(1): loaded http://localhost:42861/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42861/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:27:19 INFO - PROCESS | 31839 | RSS: Main: 214646784 20:27:19 INFO - PROCESS | 31839 | 20:27:22 INFO - PROCESS | 31839 | Cycle 1(1): loaded http://localhost:42861/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42861/tests/kraken/driver.html?testName=imaging-darkroom) 20:27:23 INFO - PROCESS | 31839 | RSS: Main: 251375616 20:27:23 INFO - PROCESS | 31839 | 20:27:27 INFO - PROCESS | 31839 | Cycle 1(1): loaded http://localhost:42861/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42861/tests/kraken/driver.html?testName=imaging-desaturate) 20:27:27 INFO - PROCESS | 31839 | RSS: Main: 245215232 20:27:27 INFO - PROCESS | 31839 | 20:27:30 INFO - PROCESS | 31839 | Cycle 1(1): loaded http://localhost:42861/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42861/tests/kraken/driver.html?testName=json-parse-financial) 20:27:31 INFO - PROCESS | 31839 | RSS: Main: 248508416 20:27:31 INFO - PROCESS | 31839 | 20:27:32 INFO - PROCESS | 31839 | Cycle 1(1): loaded http://localhost:42861/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42861/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:27:33 INFO - PROCESS | 31839 | RSS: Main: 211562496 20:27:33 INFO - PROCESS | 31839 | 20:27:35 INFO - PROCESS | 31839 | Cycle 1(1): loaded http://localhost:42861/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42861/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:27:36 INFO - PROCESS | 31839 | RSS: Main: 220336128 20:27:36 INFO - PROCESS | 31839 | 20:27:38 INFO - PROCESS | 31839 | Cycle 1(1): loaded http://localhost:42861/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42861/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:27:39 INFO - PROCESS | 31839 | RSS: Main: 223518720 20:27:39 INFO - PROCESS | 31839 | 20:27:41 INFO - PROCESS | 31839 | Cycle 1(1): loaded http://localhost:42861/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42861/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:27:42 INFO - PROCESS | 31839 | RSS: Main: 224071680 20:27:42 INFO - PROCESS | 31839 | 20:27:45 INFO - PROCESS | 31839 | Cycle 1(1): loaded http://localhost:42861/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42861/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:27:45 INFO - PROCESS | 31839 | RSS: Main: 222056448 20:27:45 INFO - PROCESS | 31839 | 20:27:47 INFO - PROCESS | 31839 | Cycle 1(1): loaded http://localhost:42861/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42861/tests/kraken/driver.html?testName=ai-astar) 20:27:48 INFO - PROCESS | 31839 | RSS: Main: 274612224 20:27:48 INFO - PROCESS | 31839 | 20:27:48 INFO - PROCESS | 31839 | __start_tp_report 20:27:48 INFO - PROCESS | 31839 | _x_x_mozilla_page_load 20:27:48 INFO - PROCESS | 31839 | _x_x_mozilla_page_load_details 20:27:48 INFO - PROCESS | 31839 | |i|pagename|runs| 20:27:48 INFO - PROCESS | 31839 | |0;ai-astar;112;115;115;115;116;116;115;116;115;115 20:27:48 INFO - PROCESS | 31839 | |1;audio-beat-detection;154;129;126;181;126;124;123;125;162;124 20:27:48 INFO - PROCESS | 31839 | |2;audio-dft;161;162;161;163;161;162;162;164;161;160 20:27:48 INFO - PROCESS | 31839 | |3;audio-fft;75;77;77;107;76;76;76;76;97;75 20:27:48 INFO - PROCESS | 31839 | |4;audio-oscillator;86;84;115;84;83;83;105;83;90;121 20:27:48 INFO - PROCESS | 31839 | |5;imaging-gaussian-blur;117;117;116;117;116;116;116;116;118;117 20:27:48 INFO - PROCESS | 31839 | |6;imaging-darkroom;146;148;146;145;146;146;146;146;146;146 20:27:48 INFO - PROCESS | 31839 | |7;imaging-desaturate;96;96;96;95;96;96;96;96;96;96 20:27:48 INFO - PROCESS | 31839 | |8;json-parse-financial;64;63;63;63;64;64;65;62;63;65 20:27:48 INFO - PROCESS | 31839 | |9;json-stringify-tinderbox;67;67;67;67;69;67;67;67;71;67 20:27:48 INFO - PROCESS | 31839 | |10;stanford-crypto-aes;82;83;83;82;82;82;82;82;82;81 20:27:48 INFO - PROCESS | 31839 | |11;stanford-crypto-ccm;140;136;151;136;134;134;135;133;177;142 20:27:48 INFO - PROCESS | 31839 | |12;stanford-crypto-pbkdf2;183;178;175;177;180;179;178;175;172;175 20:27:48 INFO - PROCESS | 31839 | |13;stanford-crypto-sha256-iterative;70;68;69;67;70;69;70;69;67;71 20:27:48 INFO - PROCESS | 31839 | __end_tp_report 20:27:48 INFO - PROCESS | 31839 | __start_cc_report 20:27:48 INFO - PROCESS | 31839 | _x_x_mozilla_cycle_collect,1937 20:27:48 INFO - PROCESS | 31839 | __end_cc_report 20:27:48 INFO - PROCESS | 31839 | __startTimestamp1474428468006__endTimestamp 20:27:48 INFO - PROCESS | 31839 | 20:27:48 INFO - PROCESS | 31839 | ------- Summary: start ------- 20:27:48 INFO - PROCESS | 31839 | Number of tests: 14 20:27:48 INFO - PROCESS | 31839 | 20:27:48 INFO - PROCESS | 31839 | [#0] ai-astar Cycles:10 Average:115.00 Median:115.00 stddev:1.15 (1.0%) stddev-sans-first:0.50 20:27:48 INFO - PROCESS | 31839 | Values: 112.0 115.0 115.0 115.0 116.0 116.0 115.0 116.0 115.0 115.0 20:27:48 INFO - PROCESS | 31839 | 20:27:48 INFO - PROCESS | 31839 | [#1] audio-beat-detection Cycles:10 Average:137.40 Median:127.50 stddev:20.64 (16.2%) stddev-sans-first:20.99 20:27:48 INFO - PROCESS | 31839 | Values: 154.0 129.0 126.0 181.0 126.0 124.0 123.0 125.0 162.0 124.0 20:27:48 INFO - PROCESS | 31839 | 20:27:48 INFO - PROCESS | 31839 | [#2] audio-dft Cycles:10 Average:161.70 Median:162.00 stddev:1.16 (0.7%) stddev-sans-first:1.20 20:27:48 INFO - PROCESS | 31839 | Values: 161.0 162.0 161.0 163.0 161.0 162.0 162.0 164.0 161.0 160.0 20:27:48 INFO - PROCESS | 31839 | 20:27:48 INFO - PROCESS | 31839 | [#3] audio-fft Cycles:10 Average:81.20 Median:76.00 stddev:11.23 (14.8%) stddev-sans-first:11.69 20:27:48 INFO - PROCESS | 31839 | Values: 75.0 77.0 77.0 107.0 76.0 76.0 76.0 76.0 97.0 75.0 20:27:48 INFO - PROCESS | 31839 | 20:27:48 INFO - PROCESS | 31839 | [#4] audio-oscillator Cycles:10 Average:93.40 Median:83.50 stddev:14.65 (17.5%) stddev-sans-first:15.29 20:27:48 INFO - PROCESS | 31839 | Values: 86.0 84.0 115.0 84.0 83.0 83.0 105.0 83.0 90.0 121.0 20:27:48 INFO - PROCESS | 31839 | 20:27:48 INFO - PROCESS | 31839 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.70 (0.6%) stddev-sans-first:0.73 20:27:48 INFO - PROCESS | 31839 | Values: 117.0 117.0 116.0 117.0 116.0 116.0 116.0 116.0 118.0 117.0 20:27:48 INFO - PROCESS | 31839 | 20:27:48 INFO - PROCESS | 31839 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.74 (0.5%) stddev-sans-first:0.78 20:27:48 INFO - PROCESS | 31839 | Values: 146.0 148.0 146.0 145.0 146.0 146.0 146.0 146.0 146.0 146.0 20:27:48 INFO - PROCESS | 31839 | 20:27:48 INFO - PROCESS | 31839 | [#7] imaging-desaturate Cycles:10 Average:95.90 Median:96.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 20:27:48 INFO - PROCESS | 31839 | Values: 96.0 96.0 96.0 95.0 96.0 96.0 96.0 96.0 96.0 96.0 20:27:48 INFO - PROCESS | 31839 | 20:27:48 INFO - PROCESS | 31839 | [#8] json-parse-financial Cycles:10 Average:63.60 Median:64.00 stddev:0.97 (1.5%) stddev-sans-first:1.01 20:27:48 INFO - PROCESS | 31839 | Values: 64.0 63.0 63.0 63.0 64.0 64.0 65.0 62.0 63.0 65.0 20:27:48 INFO - PROCESS | 31839 | 20:27:48 INFO - PROCESS | 31839 | [#9] json-stringify-tinderbox Cycles:10 Average:67.60 Median:67.00 stddev:1.35 (2.0%) stddev-sans-first:1.41 20:27:48 INFO - PROCESS | 31839 | Values: 67.0 67.0 67.0 67.0 69.0 67.0 67.0 67.0 71.0 67.0 20:27:48 INFO - PROCESS | 31839 | 20:27:48 INFO - PROCESS | 31839 | [#10] stanford-crypto-aes Cycles:10 Average:82.10 Median:82.00 stddev:0.57 (0.7%) stddev-sans-first:0.60 20:27:48 INFO - PROCESS | 31839 | Values: 82.0 83.0 83.0 82.0 82.0 82.0 82.0 82.0 82.0 81.0 20:27:48 INFO - PROCESS | 31839 | 20:27:48 INFO - PROCESS | 31839 | [#11] stanford-crypto-ccm Cycles:10 Average:141.80 Median:138.00 stddev:13.50 (9.8%) stddev-sans-first:14.30 20:27:48 INFO - PROCESS | 31839 | Values: 140.0 136.0 151.0 136.0 134.0 134.0 135.0 133.0 177.0 142.0 20:27:48 INFO - PROCESS | 31839 | 20:27:48 INFO - PROCESS | 31839 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.20 Median:178.00 stddev:3.12 (1.8%) stddev-sans-first:2.51 20:27:48 INFO - PROCESS | 31839 | Values: 183.0 178.0 175.0 177.0 180.0 179.0 178.0 175.0 172.0 175.0 20:27:48 INFO - PROCESS | 31839 | 20:27:48 INFO - PROCESS | 31839 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.00 Median:69.50 stddev:1.33 (1.9%) stddev-sans-first:1.36 20:27:48 INFO - PROCESS | 31839 | Values: 70.0 68.0 69.0 67.0 70.0 69.0 70.0 69.0 67.0 71.0 20:27:48 INFO - PROCESS | 31839 | -------- Summary: end -------- 20:27:48 INFO - PROCESS | 31839 | 20:27:49 INFO - TEST-INFO | 31839: exit 0 20:27:49 INFO - TEST-OK | kraken | took 64779ms 20:27:49 INFO - SUITE-END | took 1186s 20:27:49 INFO - Completed test suite (00:19:46) 20:27:49 INFO - javascript benchmark 20:27:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2586.61200819877, 2610.0, 2637.6307316975626, 2646.2855029142124, 2661.707212206657, 4603.332516785064, 4661.487236403993, 4711.717296962184, 4727.598154156896, 4738.265568836831, 4680.297955306705, 4696.50686677899, 4705.388299521066, 4706.482286948424, 4763.118823017744, 4353.172897149548, 4365.36702178184, 4373.212821692094, 4398.538153493885, 4474.865754027384, 4442.244818380883, 4451.88870278244, 4464.17412778637, 4469.284914413678, 4491.528389519102, 4275.273203555403, 4317.525072242051, 4440.089781594781, 4468.530804265538, 4468.865934021985, 4356.607905288539, 4362.912741745179, 4370.934435983463, 4373.803178856927, 4377.846775362879, 4342.0013396918885, 4345.435093437833, 4345.565443455629, 4351.042770590497, 4354.346847972798, 4649.325847752091, 4665.88335291617, 4679.438467383901, 4686.789094490756, 4693.342931989524, 4969.875753106165, 5022.698638081691, 5026.974865125688, 5027.371578552681, 5028.874278143039, 4976.751162441863, 5021.748912554394, 5025.874353141164, 5058.544730974193, 5122.974385128088, 4676.485586585472, 4685.765711714401, 4686.273627587695, 4696.694714843524, 4749.64377671676, 5106.131957567222, 5143.254228136899, 5152.690838549699, 5156.0, 5218.217267409843, 4856.077345304381, 4886.291487734258, 4905.5094490550655, 4916.926246106311, 4923.975380123148, 5034.647574669812, 5038.5969122470115, 5045.495450454999, 5045.949540504622, 5101.591872650253, 5012.1980483122525, 5014.623903207274, 5015.871428928462, 5018.447970723218, 5066.138756411419, 4890.486498917634, 4943.110240156734, 5001.674891132065, 5006.549410553068, 5017.071841709296, 4143.854965076208, 4152.501699795984, 4163.188178305262, 4235.152969406131, 4290.592393722592, 3661.377565813818, 3667.9633203668154, 3672.59601443841, 3714.275716235308, 3720.776753394805, 4325.4593175853015, 4359.629431498295, 4362.7818609070055, 4377.277749171351, 4384.05742765307, 5083.491650834887, 5087.720175390389, 5089.05852417304, 5090.796368145307, 5159.664621799571, 4347.956520434818, 4354.433923589913, 4376.824927002948, 4430.401895744098, 4431.268840641323, 4431.556844315607, 4478.0, 4510.481294651091, 4519.525449827786, 4551.340055691906, 4808.663393562557, 4812.109759694467, 4823.614110871052, 4874.90250194987, 4884.951150488591, 5087.313212716236, 5107.10625640504, 5109.565686916654, 5116.0, 5178.559822415137, 4678.321643361625, 4718.127146477913, 4810.062037902575, 4864.92702609454, 4922.384701912261, 4971.701697898137, 4996.950030499794, 5028.949710502849, 5039.496050394931, 5092.261622064854, 5086.21163719624, 5094.159463688598, 5096.082705112967, 5100.79596816138, 5160.870978225425, 4811.951880481291, 4853.684510506806, 4959.7272150032095, 4966.180580204225, 4977.925331119964, 4915.852524424273, 4957.182064959241, 4957.553820156203, 4961.727105009115, 5026.195808670595, 4880.41435027799, 4880.877978050577, 4884.438289596742, 4890.877728056827, 4946.3817022872145, 4862.07620552094, 4994.200927851526, 5006.87482812918, 5009.148444764472, 5062.088824011713, 4707.811687532598, 4812.735299558418, 4825.420949486084, 4853.51464853526, 4924.138275801792], "value": 4642.041449848831, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [541.9647722898011, 554.938956714761, 559.9584772323481, 578.0548802707572, 578.6585914310558, 27018.0, 27738.16785496436, 29671.851640741766, 29679.851600741968, 29711.96008139713, 37948.0, 39245.41131883024, 39250.8037459813, 42835.57164428355, 43026.78486607563, 7427.3315401613845, 7575.507592006502, 7581.545107293552, 7583.279588439117, 7586.165521792598, 7632.580208088553, 7706.344960678349, 7710.7686769397005, 7713.0, 7713.151553329158, 7742.341900938427, 7778.4166187535875, 7780.377569794403, 7782.0661520617605, 7783.066032076131, 7697.615119244043, 7718.189590093071, 7750.883736743954, 7753.8449231015065, 7754.0695116586085, 7606.125295591022, 7669.578173200472, 7672.0, 7674.155842857282, 7675.616219189074, 37011.81494092512, 37299.0, 37349.626503734886, 37354.8132259337, 37356.81321593375, 2902.9274268143254, 2903.840288784116, 2916.7958242923005, 3065.4635438798346, 3486.8430920608635, 27440.0, 27504.679439758045, 27912.860435697894, 28207.43585128286, 29153.56269655957, 3234.685491935547, 3262.755293353008, 3276.3283526876935, 3339.0984434202874, 3344.866205351783, 244386.0, 246144.76927615426, 247384.0, 248388.0, 249209.0, 47854.0, 48098.27852582214, 48144.0, 48161.277580836315, 48220.7588962053, 31116.0, 31632.841835790674, 31764.364712705617, 33514.83242583772, 50771.746141269796, 13559.322033898463, 13703.383347749375, 13729.0, 13738.313084345942, 13748.93125534386, 10115.645952391631, 10152.949235253776, 10183.796324073477, 10207.591696332063, 10237.59049638021, 16805.831941680426, 17018.31926722942, 17025.574360640836, 17122.743158852627, 17155.7426638598, 6512.0, 6543.509236807258, 6579.440747536513, 6625.271220165778, 6632.204135503771, 697.2765432589994, 884.3941899798658, 912.5346073502561, 968.7578105473632, 975.7072878136672, 24532.0, 26562.46875062488, 26625.866870665912, 26788.59817102745, 26983.0, 758.7951253161615, 918.7749001494543, 921.8064206516573, 950.4107453378949, 962.3326864706258, 13494.0, 14134.717305653829, 14437.711245775026, 14465.27673616315, 14499.275036248146, 354284.0, 358617.0, 358973.0, 359439.0, 360613.0, 46832.29751553729, 47298.29052564214, 47311.0, 47350.0, 47375.0, 1045.994770026145, 1086.6739978006567, 1130.5986374836814, 1135.239389608964, 1158.2529268621693, 24799.752002479745, 27071.45857082887, 27661.446771064064, 28459.0, 28584.714152858207, 38922.2215555693, 39424.2115157701, 39478.0, 39501.60498395094, 39510.0, 288070.0, 289507.0, 289735.0, 289800.0, 290400.0, 19675.70486442676, 19945.401637950883, 20058.598828023067, 20078.397648070582, 20156.697649534974, 39923.00192495211, 40013.799931001326, 40392.596074038884, 40425.0, 41646.58353416548, 34330.14174645654, 34975.65024349724, 35138.12154696153, 35180.29639407146, 35274.294514110086, 34177.0, 34660.30679386348, 34758.13104672403, 34912.825435872655, 34994.300113998084, 29177.0, 29223.415531689672, 29276.56085158767, 29341.413171736873, 29928.0, 29720.256993575334, 32067.839660801546, 32355.676443236207, 32617.510737338485, 32734.34531309408, 20913.1634734615, 21216.0, 21216.681749773456, 21238.46903827355, 21249.7875021254], "value": 16283.410958879536, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.30965913343744, 361.77389131792614, 369.44952021487984, 377.9886603401898, 392.46820558143713, 12550.937245313771, 16018.919905400486, 16395.590110247234, 17619.295228190887, 17795.644087118282, 15338.539843804703, 15371.154586497738, 15469.690606187898, 16233.350665973376, 16351.34594616217, 4209.074003719177, 4961.082199793041, 5164.173732202848, 5178.404483484391, 5221.477852214767, 4978.253262010691, 5057.393112826448, 5061.038402703474, 5061.367329083864, 5061.9240711389175, 4858.1983972644475, 5023.7236951967625, 5053.36832895888, 5059.974700126494, 5064.037832811772, 5038.387715930903, 5092.567131793802, 5098.362704661918, 5102.107131252016, 5103.489651034867, 4367.759773212472, 4397.692161548693, 4403.933940990855, 4582.770861456914, 4590.517995610479, 20632.69050964237, 20724.48188795277, 20846.47883802902, 20901.477463063395, 21020.474488137766, 1920.6350793349218, 2096.423483542023, 2196.791304826039, 2407.650890620868, 2430.878456077207, 12938.935305323508, 13033.41349639259, 13294.468221271138, 13502.122362046533, 13654.180749155083, 1701.1797376806821, 1723.0523212233347, 1786.4729904678152, 1791.5431564950984, 1841.1714728372285, 68877.0, 69643.30356696469, 69854.30145698528, 70422.64788676024, 70477.64761176161, 9157.0, 9242.3530352876, 9300.441973481613, 9726.70819875405, 10133.391996480235, 13571.0, 13613.86386136126, 13617.931910340385, 13620.93189534046, 13648.931755341358, 6779.0, 6992.720291188295, 7039.26087760788, 7171.103612048494, 7209.0, 6520.250171230275, 6586.7035983380865, 6768.526203165731, 6783.525153239325, 6787.151606049244, 6049.122877182784, 6103.328633850273, 6155.969220153871, 6212.254529456494, 6269.7178626961895, 1659.2533359988054, 1718.518814731877, 1720.4666553368495, 1725.767021452113, 1792.8476079533125, 823.5058964621179, 846.8602680557763, 851.6125163050797, 875.1948207648851, 876.750126214026, 16352.918235408746, 16644.0, 17452.214650340764, 17530.0, 17613.0, 809.3201710563155, 823.8434697407473, 838.6603425612636, 872.2062922740275, 897.0491279244022, 9451.243900487945, 10002.49987500622, 10037.247206459544, 10072.143867771321, 10177.389356638625, 369014.0, 375085.0, 376572.0, 379138.0, 383762.0, 46544.7672761634, 64773.67613161904, 68416.6579167101, 68953.3104668957, 69262.30737692559, 834.2283387866199, 842.4692443760392, 876.0363600039905, 880.8018195905998, 883.3728053082255, 11401.600943966921, 15012.399504020153, 15884.920575397511, 16685.74971375406, 16841.91579042146, 35978.28043439064, 36192.457113142795, 36725.08187295339, 36828.447573285885, 38269.4259586112, 43311.0, 43526.782366087966, 43548.346774799036, 45850.0, 45868.77065614651, 6470.320616335217, 6851.417629501549, 6966.268541803038, 6997.300269972962, 7205.819854503469, 9697.6605818796, 11074.833877491683, 11279.0, 11300.660980170607, 11302.43487825622, 4566.908661826678, 4657.0, 8074.354051675734, 9330.482907434243, 9646.228301735984, 4674.415698037746, 7657.923420765944, 8501.914980850112, 9617.807643847224, 9717.85423218638, 8799.956000219958, 9336.159745622926, 9399.85900211511, 9667.226621870093, 10010.499475026074, 6991.615461149482, 7054.823629409306, 7132.572045677276, 7142.357187853118, 7381.741639042608, 9531.807684232526, 9553.140217380469, 9665.516724163905, 9684.225261979165, 9979.45113018791], "value": 7930.956981972666, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1329.2024785128924, 1360.2110775750064, 1375.944962201512, 1585.3737773579444, 1649.5463747469444, 6714.328567143295, 6991.300869913006, 7439.181690014094, 8080.111187769348, 8086.7573972780765, 7563.205863384348, 7807.882881756778, 7871.92128078719, 8124.309433698143, 8128.55292958887, 2183.541456294176, 2210.071769856664, 2215.94460138497, 2230.7769223077644, 2237.038073628339, 1860.9255629774793, 2200.5378870437225, 2233.3746550965757, 2236.7297193348763, 2242.9775702242932, 1918.0, 2073.06711979609, 2140.1332460353583, 2150.6021386043553, 2151.290074275493, 2137.4015275722822, 2156.137544982004, 2161.7513985891587, 2163.350994701591, 2164.729408823897, 2152.817010554105, 2174.826013918883, 2177.6080305544997, 2178.411828806229, 2181.6618424144285, 8018.158093400168, 8084.878726819103, 8087.757367278932, 8087.838243235103, 8088.11030786613, 4479.843205487825, 4590.931136032962, 4634.698744581625, 4671.976640116812, 4675.859724208279, 2772.043644942512, 2934.5891575179494, 2975.687552806946, 2980.5529170624363, 2995.6105706258263, 922.5525620074259, 974.2303580171656, 990.0495524296684, 993.1310103659298, 994.164901482758, 10768.084712799422, 10803.189760767973, 10811.513481893333, 10818.02637762605, 10833.187510936712, 3992.301347264217, 4234.703570750018, 4342.69601127918, 4393.51671316155, 4427.601515863524, 9110.453372797654, 9128.315376346667, 9132.634694612274, 9133.90866091344, 9136.6802161924, 4526.6152377047665, 4529.909401812011, 4531.161735079021, 4542.568455996675, 4543.659225558096, 4127.33962565994, 4142.440770495945, 4148.875533733993, 4154.729942553785, 4159.625633692982, 3650.6166852480637, 3668.3763760160837, 3774.1885494618787, 3797.5442946846556, 3821.6942644588366, 1201.4293210724836, 1211.8909298163207, 1228.1893949992962, 1229.3484453239798, 1232.2729589542034, 551.93697235864, 640.8205702403242, 763.8548675751589, 793.8412317536515, 794.039212552806, 5016.724080175625, 5143.948560514422, 5199.714015729096, 5267.473252674702, 5332.600054995891, 768.3430666779823, 782.7964729170343, 804.5494523067101, 808.002117385029, 827.4414770030205, 3504.159001839591, 3632.3461776880417, 3665.138692407282, 3689.206820533598, 3783.981080094582, 9549.761255968657, 9901.108900199017, 9962.701118966443, 9973.950130249303, 10015.549300281506, 9621.470819105016, 9934.403935763878, 9936.751581210383, 9938.850917236246, 9969.451680157516, 7250.057492525938, 7340.669669864869, 7341.632918354061, 7344.228855970046, 7424.071991001125, 5026.34657494538, 5105.540501354896, 5106.259592359162, 5156.793728250828, 5388.218708287355, 8829.763833063505, 8940.329475289249, 9050.954745226232, 9077.092290771134, 9115.453072815651, 9060.139286767746, 9199.126083022102, 9228.492432916253, 9283.39657922233, 9312.72061838146, 6657.068010478633, 6666.799996000128, 6723.4285085766305, 6796.626185559841, 6803.081583986296, 7471.663775130023, 7521.135069467081, 7532.585707786125, 7539.924600753922, 7542.245775422414, 6453.645049522322, 6518.0, 6628.53600247978, 6637.30308317639, 7000.474964377794, 6477.481801455967, 6526.216853977553, 6546.18172728409, 6549.508786841101, 6575.342465753386, 5790.536757059509, 5822.2140011099655, 5833.533317334688, 5857.619254748428, 5870.7945221918735, 4383.736975781463, 4443.955560444483, 4535.206338890747, 4587.334836448763, 4597.195490789031, 4395.099004703964, 4459.308807134899, 4515.126222617577, 4520.615747661485, 4660.603848672901], "value": 4117.208974632901, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [295.27215414004473, 298.7609912070344, 305.3465583650986, 309.82959372345204, 312.7154289596468, 4699.647526435519, 4719.811207551702, 4730.9763451182735, 4745.644076694244, 4756.120117778205, 4653.302004699297, 4672.159011377959, 4697.553732395417, 4708.411448568929, 4714.50497697742, 3564.3227786720504, 3662.6520480554436, 3670.6329367063213, 3722.813859307024, 3758.2483503299313, 3755.605661405554, 3768.6608205261523, 3768.905777355574, 3769.3780526213277, 3779.640934111269, 3741.513603231576, 3747.1943532140585, 3747.231817477424, 3751.8124093795336, 3764.926995806196, 3541.698955588778, 3629.201575653352, 3658.1586235165796, 3722.181120153562, 3757.9936051158966, 3306.702396784277, 3315.8507867146036, 3325.551050608161, 3336.6996970272794, 3340.880804930351, 4575.542445755431, 4586.954130458719, 4593.150267200545, 4595.264757638795, 4634.559716826896, 4149.838045347307, 4238.576142385737, 4248.34150706641, 4264.530901600852, 4267.1892340455215, 4911.926321105187, 4924.8276310328965, 4935.692041608976, 4940.654154209206, 4954.281629163751, 3608.386574282352, 3614.548181477315, 3626.528551288342, 3628.963710362889, 3634.2186429917697, 4978.352814134176, 5170.586353091744, 5175.275461435402, 5181.927229108312, 5186.795611864943, 3616.9276614467562, 3623.2391197848747, 3624.4563315502887, 3625.728070394731, 3630.8910732678064, 3664.0, 3668.3030224257304, 3673.540807399075, 3682.5028621135802, 3683.208110256308, 4091.936096614842, 4142.192272506893, 4196.433481480022, 4199.0, 4210.894727631834, 4899.951000490021, 4923.556879880828, 4929.13740095489, 4929.7781599828095, 4958.231474121517, 1255.9889289122161, 1269.3018839638162, 1273.6497463197509, 1274.241826113461, 1275.3177050278243, 761.3452430909414, 804.9637766300531, 812.7240232834512, 822.1367564057715, 827.2596026556319, 4249.213895429355, 4299.871003869889, 4403.229434848889, 4420.889477763081, 4425.756583387944, 5063.3417655704525, 5080.891430056559, 5089.745512724349, 5151.433342332415, 5156.278121063054, 4417.933730994037, 4451.1765323415275, 4459.996500787297, 4467.955320446819, 4471.32930060487, 2581.7409766454352, 2710.8509032003035, 2733.6992930777596, 2744.807863449579, 2746.4232511172477, 4890.608751299887, 4896.167651499253, 4898.730569818694, 4902.166631672624, 4904.6321525885705, 4967.627427942919, 5191.5846732261325, 5204.895902082013, 5256.789728410898, 5269.78920843162, 4847.321375007502, 4851.199552073868, 4863.197572400514, 4863.319135321129, 4869.610431165569, 5081.593472522263, 5082.720450375265, 5096.439391666988, 5099.495648783692, 5122.206058061007, 4753.477117517001, 4987.975060124676, 4995.950040499694, 5015.799368025384, 5029.0, 4822.324874517501, 4844.61243100544, 4859.392575928009, 4863.465018847854, 4868.245421959602, 3633.02000409772, 3648.726345524043, 3652.2330310635066, 3662.981685091664, 3669.5229620150276, 4996.051539899515, 5097.425799970991, 5101.489851014869, 5129.153689641316, 5145.511176438233, 4910.06708725341, 4935.235038569028, 4941.369348115185, 4942.4810394909455, 4971.875703107451, 4922.507749225192, 4925.581325587365, 4928.6057115431395, 4939.75301234944, 4950.8267210647455, 4731.574158325767, 4852.781624826821, 4871.171900776805, 4878.853634390974, 4885.926711099408, 4782.0, 4843.612510999182, 4881.633142720044, 4966.453690094015, 4992.5756310714, 4844.757762111951, 4847.200211964986, 4847.321375007431, 4857.878553036202, 4885.5358740919555], "value": 3747.8919117860014, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [592.3780030967483, 626.0984182776801, 638.8793817552317, 643.0258158889284, 666.2271764752882, 12312.507499700012, 13067.411966461508, 13309.933450332735, 13341.13282636629, 13348.666283342922, 13072.607821765363, 13122.409491572878, 13190.538331158412, 13233.13984591001, 13321.666958326046, 708.6881772020321, 730.101974571275, 733.7065173930417, 734.8016035670365, 734.988975165373, 581.0992960910572, 598.3986093975549, 714.3677845107065, 727.316322656701, 732.7252280394852, 764.1899586438393, 770.5569297653843, 772.2162005564329, 772.4091070331201, 773.2847116417291, 762.2682225063945, 763.602926478231, 764.7896828372186, 764.9655765490567, 764.9732259370895, 447.4094195661728, 456.7670488051118, 538.849122245768, 554.8529639645498, 561.5788158880839, 11654.184207105505, 11681.532738690443, 12050.397480126047, 12084.093692972974, 12086.939565302206, 11536.826947595793, 12282.385880706019, 12347.93826030873, 12420.130590858644, 12604.521523691572, 11326.71683207918, 11352.205345625809, 11378.544858205662, 11565.76868462626, 12185.87814121865, 4018.758874467512, 4057.066874618825, 4124.52567954686, 4131.5455299916985, 4175.582441755831, 61468.692656536434, 62271.37728622701, 62408.0, 63962.36037639657, 64159.67920160416, 8725.607347669371, 8837.867431988525, 8852.38033337673, 8899.466032038099, 8914.598843052077, 17598.560035998948, 17730.82269177292, 17764.490018348326, 17981.910090449463, 18001.819981800276, 6011.6693581853415, 6527.379898909596, 6897.793066208022, 6899.344562266677, 6912.0, 3595.982020089883, 3605.639436056426, 3609.5488063992, 3618.5838628557526, 3686.0, 2796.678381986057, 2868.7131286871145, 2887.321479452327, 2887.3936473340414, 2887.898923537666, 483.48508838087326, 485.44174199669953, 487.3007234618334, 489.08541028277347, 493.7087118600043, 502.20400664945737, 509.52868596548785, 526.081986932797, 530.5039787798421, 539.9217113518517, 7029.22678505364, 7434.784563057743, 7534.54792712439, 7583.317501424898, 7783.4162437817395, 392.6407337286408, 407.48249722852404, 462.71543001054783, 477.8900852803805, 535.1170568561904, 3008.984955075254, 3343.38147442719, 3413.7269018478464, 3490.89527314181, 3591.120175557005, 59825.401745982854, 80965.0, 104173.0, 109595.0, 110516.44741776239, 51595.0, 86079.0, 87645.56177219073, 87905.0, 88627.0, 78436.60781696055, 79611.0, 80948.59525702333, 81220.59389703014, 82394.58802706069, 6417.582857114273, 6553.361659585104, 6802.523823332517, 7010.614416207158, 7081.362677359062, 46512.302315465975, 47509.0, 47556.097756089366, 49731.254031190285, 50030.24954625756, 62944.68527657333, 68254.65872670605, 68354.0, 70396.0, 72781.63609181921, 9169.679061232824, 9208.861867072133, 9386.389884657476, 9418.0, 9800.166985806287, 10188.949055254676, 10308.639197628047, 10436.217283703905, 10447.58209671635, 10713.625023124154, 9003.63985440575, 9215.677451289173, 9421.38760980534, 9429.717108486757, 9534.469096671894, 9204.874986297906, 9299.907000930174, 9515.809683806423, 9944.403335799585, 10001.949990250003, 9112.134347237003, 9255.629774809202, 9462.479563624067, 9476.147146756824, 9979.0, 8120.634571444181, 8178.5092894426525, 8255.752327430188, 8446.0, 8645.394822362374, 7685.538867667958, 8192.713255036046, 8204.302634276153, 8412.831743365221, 8488.363372746946], "value": 6085.028428148821, "unit": "score"}], "name": "dromaeo_css", "value": 6190.796963375013, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 115.0, 115.0, 115.0, 116.0, 116.0, 115.0, 116.0, 115.0, 115.0], "value": 115.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 129.0, 126.0, 181.0, 126.0, 124.0, 123.0, 125.0, 162.0, 124.0], "value": 137.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 161.0, 163.0, 161.0, 162.0, 162.0, 164.0, 161.0, 160.0], "value": 161.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 77.0, 107.0, 76.0, 76.0, 76.0, 76.0, 97.0, 75.0], "value": 81.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 115.0, 84.0, 83.0, 83.0, 105.0, 83.0, 90.0, 121.0], "value": 93.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 116.0, 116.0, 116.0, 116.0, 118.0, 117.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 148.0, 146.0, 145.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 95.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0], "value": 95.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 63.0, 63.0, 64.0, 64.0, 65.0, 62.0, 63.0, 65.0], "value": 63.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 67.0, 69.0, 67.0, 67.0, 67.0, 71.0, 67.0], "value": 67.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 83.0, 82.0, 82.0, 82.0, 82.0, 82.0, 82.0, 81.0], "value": 82.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 136.0, 151.0, 136.0, 134.0, 134.0, 135.0, 133.0, 177.0, 142.0], "value": 141.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 178.0, 175.0, 177.0, 180.0, 179.0, 178.0, 175.0, 172.0, 175.0], "value": 177.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 68.0, 69.0, 67.0, 70.0, 69.0, 70.0, 69.0, 67.0, 71.0], "value": 69.0, "unit": "score"}], "name": "kraken", "value": 1548.6, "alertThreshold": 2.0}]} 20:27:49 INFO - Return code: 0 20:27:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:27:49 INFO - # TBPL SUCCESS # 20:27:49 INFO - Running post-action listener: _resource_record_post_action 20:27:49 INFO - [mozharness: 2016-09-21 03:27:49.832247Z] Finished run-tests step (success) 20:27:49 INFO - Running post-run listener: _resource_record_post_run 20:27:49 INFO - Total resource usage - Wall time: 1203s; CPU: 13.0%; Read bytes: 4096; Write bytes: 392110080; Read time: 12; Write time: 282776 20:27:49 INFO - TinderboxPrint: CPU usage
13.0% 20:27:49 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 20:27:49 INFO - TinderboxPrint: I/O write bytes / time
392,110,080 / 282,776 20:27:49 INFO - TinderboxPrint: CPU idle
8,347.1 (87.0%) 20:27:49 INFO - TinderboxPrint: CPU user
1,199.9 (12.5%) 20:27:49 INFO - TinderboxPrint: Swap in / out
0 / 0 20:27:49 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 98996224; Read time: 0; Write time: 64652 20:27:49 INFO - run-tests - Wall time: 1187s; CPU: 13.0%; Read bytes: 0; Write bytes: 293113856; Read time: 0; Write time: 218124 20:27:50 INFO - Running post-run listener: _upload_blobber_files 20:27:50 INFO - Blob upload gear active. 20:27:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:27:50 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 20:27:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 20:27:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 20:27:50 INFO - (blobuploader) - INFO - Open directory for files ... 20:27:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:27:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:27:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:27:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:27:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:27:51 INFO - (blobuploader) - INFO - Done attempting. 20:27:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:27:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:27:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:27:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:27:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:27:52 INFO - (blobuploader) - INFO - Done attempting. 20:27:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:27:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:27:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:27:55 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:27:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:27:55 INFO - (blobuploader) - INFO - Done attempting. 20:27:55 INFO - (blobuploader) - INFO - Iteration through files over. 20:27:55 INFO - Return code: 0 20:27:55 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:27:55 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:27:55 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/304d7b02e323d749346a2592e4a40a70935323f272dcb47b2db613eef017e6a9938c828263ede6bc65c38b2128459e6ed457b4d912e9aa2148432715017a7a3f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/8014a39621fbb5c0805ada9d50fe147585f29e0537f4ec57850d92a391143da995eca95dd7b679241777811bba650d188e2c4b441fa0bf568a5b0c574898cfcf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/6276b9413fed8428142618a35c6b5c10128f820be83136b27b3afdb88b1b53e2e349ba450e7643cfafda961380762bd4a307f05ffc1550e74d83089abaf8b7ac"} 20:27:55 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:27:55 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:27:55 INFO - Contents: 20:27:55 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/304d7b02e323d749346a2592e4a40a70935323f272dcb47b2db613eef017e6a9938c828263ede6bc65c38b2128459e6ed457b4d912e9aa2148432715017a7a3f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/8014a39621fbb5c0805ada9d50fe147585f29e0537f4ec57850d92a391143da995eca95dd7b679241777811bba650d188e2c4b441fa0bf568a5b0c574898cfcf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/6276b9413fed8428142618a35c6b5c10128f820be83136b27b3afdb88b1b53e2e349ba450e7643cfafda961380762bd4a307f05ffc1550e74d83089abaf8b7ac"} 20:27:55 INFO - Running post-run listener: copy_logs_to_upload_dir 20:27:55 INFO - Copying logs to upload dir... 20:27:55 INFO - mkdir: /builds/slave/test/build/upload/logs 20:27:55 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1245.301594 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-09-20 20:27:55.786165) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-20 20:27:55.790147) ========= 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-1474427227.830356-134478548 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/304d7b02e323d749346a2592e4a40a70935323f272dcb47b2db613eef017e6a9938c828263ede6bc65c38b2128459e6ed457b4d912e9aa2148432715017a7a3f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/8014a39621fbb5c0805ada9d50fe147585f29e0537f4ec57850d92a391143da995eca95dd7b679241777811bba650d188e2c4b441fa0bf568a5b0c574898cfcf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/6276b9413fed8428142618a35c6b5c10128f820be83136b27b3afdb88b1b53e2e349ba450e7643cfafda961380762bd4a307f05ffc1550e74d83089abaf8b7ac"} build_url:https://queue.taskcluster.net/v1/task/Meyd02SLQR27xo4-VBt1JA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011339 build_url: 'https://queue.taskcluster.net/v1/task/Meyd02SLQR27xo4-VBt1JA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/304d7b02e323d749346a2592e4a40a70935323f272dcb47b2db613eef017e6a9938c828263ede6bc65c38b2128459e6ed457b4d912e9aa2148432715017a7a3f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/8014a39621fbb5c0805ada9d50fe147585f29e0537f4ec57850d92a391143da995eca95dd7b679241777811bba650d188e2c4b441fa0bf568a5b0c574898cfcf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/6276b9413fed8428142618a35c6b5c10128f820be83136b27b3afdb88b1b53e2e349ba450e7643cfafda961380762bd4a307f05ffc1550e74d83089abaf8b7ac"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-20 20:27:55.818331) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 20:27:55.819007) ========= 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-1474427227.830356-134478548 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004404 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 20:27:55.902054) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-20 20:27:55.902404) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-20 20:27:55.902831) ========= ========= Total master_lag: 0.17 =========