builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-011 starttime: 1474228569.22 results: success (0) buildid: 20160918114018 builduid: 63792f427c614759802f981894152676 revision: f0f15b7c6aa77a0c5750918aa0a1cb3dc82185bc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-18 12:56:09.218531) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-18 12:56:09.219389) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-18 12:56:09.219738) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-18 12:56:09.292954) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-18 12:56:09.293343) ========= 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=5cf47e208febbcd81da23b2e0000026f-1474204125.502405-684267107 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-18 12:56:09-- 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% 36.4M=0s 2016-09-18 12:56:09 (36.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.076195 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-18 12:56:09.426567) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-18 12:56:09.427126) ========= 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=5cf47e208febbcd81da23b2e0000026f-1474204125.502405-684267107 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022587 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-18 12:56:09.497027) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-18 12:56:09.497362) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f0f15b7c6aa77a0c5750918aa0a1cb3dc82185bc --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev f0f15b7c6aa77a0c5750918aa0a1cb3dc82185bc --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=5cf47e208febbcd81da23b2e0000026f-1474204125.502405-684267107 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-18 12:56:10,074 truncating revision to first 12 chars 2016-09-18 12:56:10,074 Setting DEBUG logging. 2016-09-18 12:56:10,074 attempt 1/10 2016-09-18 12:56:10,074 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f0f15b7c6aa7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-18 12:56:10,825 unpacking tar archive at: mozilla-central-f0f15b7c6aa7/testing/mozharness/ program finished with exit code 0 elapsedTime=1.093654 ========= master_lag: 0.14 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-18 12:56:10.734450) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-18 12:56:10.734756) ========= script_repo_revision: f0f15b7c6aa77a0c5750918aa0a1cb3dc82185bc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-18 12:56:10.735148) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-18 12:56:10.735418) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-18 12:56:10.810854) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2016-09-18 12:56:10.811114) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-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', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-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=5cf47e208febbcd81da23b2e0000026f-1474204125.502405-684267107 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:56:11 INFO - MultiFileLogger online at 20160918 12:56:11 in /builds/slave/test 12:56:11 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 12:56:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:56:11 INFO - {'append_to_log': False, 12:56:11 INFO - 'base_work_dir': '/builds/slave/test', 12:56:11 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 12:56:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:56:11 INFO - 'branch': 'Firefox-Non-PGO', 12:56:11 INFO - 'buildbot_json_path': 'buildprops.json', 12:56:11 INFO - 'config_files': ('talos/linux_config.py',), 12:56:11 INFO - 'default_actions': ('clobber', 12:56:11 INFO - 'read-buildbot-config', 12:56:11 INFO - 'download-and-extract', 12:56:11 INFO - 'populate-webroot', 12:56:11 INFO - 'create-virtualenv', 12:56:11 INFO - 'install', 12:56:11 INFO - 'run-tests'), 12:56:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:56:11 INFO - 'download_minidump_stackwalk': True, 12:56:11 INFO - 'download_symbols': 'ondemand', 12:56:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:56:11 INFO - 'tooltool.py': '/tools/tooltool.py', 12:56:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:56:11 INFO - '/tools/misc-python/virtualenv.py')}, 12:56:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:56:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:56:11 INFO - 'installer_path': 'installer.exe', 12:56:11 INFO - 'log_level': 'info', 12:56:11 INFO - 'log_name': 'talos', 12:56:11 INFO - 'log_to_console': True, 12:56:11 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:56:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:56:11 INFO - 'opt_config_files': (), 12:56:11 INFO - 'pip_index': False, 12:56:11 INFO - 'sps_profile': False, 12:56:11 INFO - 'sps_profile_interval': 0, 12:56:11 INFO - 'suite': 'dromaeojs', 12:56:11 INFO - 'system_bits': '32', 12:56:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:56:11 INFO - 'title': 'talos-linux64-ix-011', 12:56:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:56:11 INFO - 'use_talos_json': True, 12:56:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:56:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:56:11 INFO - 'work_dir': 'build'} 12:56:11 INFO - [mozharness: 2016-09-18 19:56:11.482916Z] Running clobber step. 12:56:11 INFO - Running pre-action listener: _resource_record_pre_action 12:56:11 INFO - Running main action method: clobber 12:56:11 INFO - rmtree: /builds/slave/test/build 12:56:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:56:11 INFO - Running post-action listener: _resource_record_post_action 12:56:11 INFO - [mozharness: 2016-09-18 19:56:11.946469Z] Finished clobber step (success) 12:56:11 INFO - [mozharness: 2016-09-18 19:56:11.946569Z] Running read-buildbot-config step. 12:56:11 INFO - Running pre-action listener: _resource_record_pre_action 12:56:11 INFO - Running main action method: read_buildbot_config 12:56:11 INFO - Using buildbot properties: 12:56:11 INFO - { 12:56:11 INFO - "project": "", 12:56:11 INFO - "product": "firefox", 12:56:11 INFO - "script_repo_revision": "production", 12:56:11 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 12:56:11 INFO - "repository": "", 12:56:11 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 12:56:11 INFO - "buildid": "20160918114018", 12:56:11 INFO - "pgo_build": "False", 12:56:11 INFO - "basedir": "/builds/slave/test", 12:56:11 INFO - "buildnumber": 747, 12:56:11 INFO - "slavename": "talos-linux64-ix-011", 12:56:11 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 12:56:11 INFO - "platform": "ubuntu64_hw", 12:56:11 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 12:56:11 INFO - "branch": "mozilla-central", 12:56:11 INFO - "repo_path": "mozilla-central", 12:56:11 INFO - "revision": "f0f15b7c6aa77a0c5750918aa0a1cb3dc82185bc", 12:56:11 INFO - "stage_platform": "linux64", 12:56:11 INFO - "builduid": "63792f427c614759802f981894152676", 12:56:11 INFO - "slavebuilddir": "test" 12:56:11 INFO - } 12:56:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/TRmaxGIpRxW8NsN-NXJeEw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 12:56:11 INFO - Running post-action listener: _resource_record_post_action 12:56:11 INFO - [mozharness: 2016-09-18 19:56:11.949014Z] Finished read-buildbot-config step (success) 12:56:11 INFO - [mozharness: 2016-09-18 19:56:11.949103Z] Running download-and-extract step. 12:56:11 INFO - Running pre-action listener: _resource_record_pre_action 12:56:11 INFO - Running main action method: download_and_extract 12:56:11 INFO - mkdir: /builds/slave/test/build/tests 12:56: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')]} 12:56:11 INFO - trying https://queue.taskcluster.net/v1/task/TRmaxGIpRxW8NsN-NXJeEw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 12:56:11 INFO - Downloading https://queue.taskcluster.net/v1/task/TRmaxGIpRxW8NsN-NXJeEw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 12:56:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TRmaxGIpRxW8NsN-NXJeEw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 12:56:12 INFO - Downloaded 1361 bytes. 12:56:12 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 12:56:12 INFO - Using the following test package requirements: 12:56:12 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 12:56:12 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 12:56:12 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 12:56:12 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 12:56:12 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 12:56:12 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 12:56:12 INFO - u'jsshell-linux-x86_64.zip'], 12:56:12 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 12:56:12 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 12:56:12 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 12:56:12 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 12:56:12 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 12:56:12 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 12:56:12 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 12:56:12 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 12:56:12 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 12:56:12 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 12:56:12 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 12:56:12 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 12:56:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TRmaxGIpRxW8NsN-NXJeEw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 12:56:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TRmaxGIpRxW8NsN-NXJeEw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 12:56:12 INFO - Fetch https://queue.taskcluster.net/v1/task/TRmaxGIpRxW8NsN-NXJeEw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 12:56:13 INFO - Expected file size: 20364975 12:56:18 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 12:56:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TRmaxGIpRxW8NsN-NXJeEw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 12:56:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TRmaxGIpRxW8NsN-NXJeEw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 12:56:18 INFO - Fetch https://queue.taskcluster.net/v1/task/TRmaxGIpRxW8NsN-NXJeEw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 12:56:19 INFO - Expected file size: 20364975 12:56:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TRmaxGIpRxW8NsN-NXJeEw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 12:56:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TRmaxGIpRxW8NsN-NXJeEw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 12:56:23 INFO - Fetch https://queue.taskcluster.net/v1/task/TRmaxGIpRxW8NsN-NXJeEw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip into memory 12:56:24 INFO - Expected file size: 14414048 12:56:25 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')]} 12:56:25 INFO - trying https://queue.taskcluster.net/v1/task/TRmaxGIpRxW8NsN-NXJeEw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 12:56:25 INFO - Downloading https://queue.taskcluster.net/v1/task/TRmaxGIpRxW8NsN-NXJeEw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 12:56:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TRmaxGIpRxW8NsN-NXJeEw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 12:56:27 INFO - Downloaded 55593415 bytes. 12:56:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TRmaxGIpRxW8NsN-NXJeEw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 12:56:27 INFO - mkdir: /builds/slave/test/properties 12:56:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:56:27 INFO - Writing to file /builds/slave/test/properties/build_url 12:56:27 INFO - Contents: 12:56:27 INFO - build_url:https://queue.taskcluster.net/v1/task/TRmaxGIpRxW8NsN-NXJeEw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 12:56:28 INFO - Running post-action listener: _resource_record_post_action 12:56:28 INFO - Running post-action listener: set_extra_try_arguments 12:56:28 INFO - [mozharness: 2016-09-18 19:56:28.461741Z] Finished download-and-extract step (success) 12:56:28 INFO - [mozharness: 2016-09-18 19:56:28.461969Z] Running populate-webroot step. 12:56:28 INFO - Running pre-action listener: _resource_record_pre_action 12:56:28 INFO - Running main action method: populate_webroot 12:56:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:56:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:56:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:56:28 INFO - '--tppagecycles', 12:56:28 INFO - '7'], 12:56:28 INFO - 'tests': ['tsvgm']}}, 12:56:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:56:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:56:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:56:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:56:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:56:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:56:28 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:56:28 INFO - 'talos_options': ['--disable-e10s'], 12:56:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:56:28 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:56:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:56:28 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:56:28 INFO - 'talos_options': ['--disable-e10s'], 12:56:28 INFO - 'tests': ['damp', 'tps']}, 12:56:28 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:56:28 INFO - 'tests': ['damp', 'tps']}, 12:56:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:56:28 INFO - 'tests': ['dromaeo_dom']}, 12:56:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:56:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:56:28 INFO - 'tests': ['basic_compositor_video']}, 12:56:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:56:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:56:28 INFO - 'tests': ['a11yr', 12:56:28 INFO - 'ts_paint', 12:56:28 INFO - 'tpaint', 12:56:28 INFO - 'sessionrestore', 12:56:28 INFO - 'sessionrestore_no_auto_restore', 12:56:28 INFO - 'tabpaint']}, 12:56:28 INFO - 'other-e10s': {'tests': ['a11yr', 12:56:28 INFO - 'ts_paint', 12:56:28 INFO - 'tpaint', 12:56:28 INFO - 'sessionrestore', 12:56:28 INFO - 'sessionrestore_no_auto_restore', 12:56:28 INFO - 'tabpaint']}, 12:56:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:56:28 INFO - 'ts_paint', 12:56:28 INFO - 'tpaint', 12:56:28 INFO - 'sessionrestore', 12:56:28 INFO - 'sessionrestore_no_auto_restore', 12:56:28 INFO - 'tabpaint']}, 12:56:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:56:28 INFO - 'ts_paint', 12:56:28 INFO - 'tpaint', 12:56:28 INFO - 'sessionrestore', 12:56:28 INFO - 'sessionrestore_no_auto_restore', 12:56:28 INFO - 'tabpaint']}, 12:56:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:56:28 INFO - 'tests': ['a11yr', 12:56:28 INFO - 'ts_paint', 12:56:28 INFO - 'tpaint', 12:56:28 INFO - 'sessionrestore', 12:56:28 INFO - 'sessionrestore_no_auto_restore', 12:56:28 INFO - 'tabpaint']}, 12:56:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:56:28 INFO - 'tests': ['a11yr', 12:56:28 INFO - 'ts_paint', 12:56:28 INFO - 'tpaint', 12:56:28 INFO - 'sessionrestore', 12:56:28 INFO - 'sessionrestore_no_auto_restore', 12:56:28 INFO - 'tabpaint']}, 12:56:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:56:28 INFO - 'tests': ['tsvgx', 12:56:28 INFO - 'tsvgr_opacity', 12:56:28 INFO - 'tart', 12:56:28 INFO - 'tscrollx', 12:56:28 INFO - 'cart']}, 12:56:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:56:28 INFO - 'tsvgr_opacity', 12:56:28 INFO - 'tart', 12:56:28 INFO - 'tscrollx', 12:56:28 INFO - 'cart']}, 12:56:28 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:56:28 INFO - 'talos_options': ['--disable-e10s'], 12:56:28 INFO - 'tests': ['tp5o']}, 12:56:28 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:56:28 INFO - 'tests': ['tp5o']}, 12:56:28 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:56:28 INFO - 'talos_options': ['--disable-e10s', 12:56:28 INFO - '--xperf_path', 12:56:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:56:28 INFO - 'tests': ['tp5n']}, 12:56:28 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:56:28 INFO - 'talos_options': ['--xperf_path', 12:56:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:56:28 INFO - 'tests': ['tp5n']}}, 12:56:28 INFO - 'talos.zip': {'path': '', 12:56:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:56:28 INFO - Running post-action listener: _resource_record_post_action 12:56:28 INFO - [mozharness: 2016-09-18 19:56:28.482628Z] Finished populate-webroot step (success) 12:56:28 INFO - [mozharness: 2016-09-18 19:56:28.482716Z] Running create-virtualenv step. 12:56:28 INFO - Running pre-action listener: _resource_record_pre_action 12:56:28 INFO - Running main action method: create_virtualenv 12:56:28 INFO - Creating virtualenv /builds/slave/test/build/venv 12:56:28 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 12:56:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:56:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:56:28 INFO - Using real prefix '/usr' 12:56:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:56:29 INFO - Installing distribute.............................................................................................................................................................................................done. 12:56:31 INFO - Installing pip.................done. 12:56:31 INFO - Return code: 0 12:56:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:56:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:56:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:56:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:56:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:56:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:56:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:56:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbcc7556f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x296c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a39ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a36f60>, '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 0x2a37d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a381d0>, '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': '5cf47e208febbcd81da23b2e0000026f-1474204125.502405-684267107', '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 12:56:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:56:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:56:31 INFO - Using env: {'DISPLAY': ':0', 12:56:31 INFO - 'HOME': '/home/cltbld', 12:56:31 INFO - 'LANG': 'en_US.UTF-8', 12:56:31 INFO - 'LANGUAGE': 'en_US:en', 12:56:31 INFO - 'LOGNAME': 'cltbld', 12:56:31 INFO - 'MAIL': '/var/mail/cltbld', 12:56:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:56:31 INFO - 'MOZ_NO_REMOTE': '1', 12:56:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:56:31 INFO - 'NO_EM_RESTART': '1', 12:56:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:56:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:56:31 INFO - 'PWD': '/builds/slave/test', 12:56:31 INFO - 'SHELL': '/bin/bash', 12:56:31 INFO - 'SHLVL': '1', 12:56:31 INFO - 'TERM': 'linux', 12:56:31 INFO - 'TMOUT': '86400', 12:56:31 INFO - 'USER': 'cltbld', 12:56:31 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474204125.502405-684267107', 12:56:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:56:31 INFO - '_': '/tools/buildbot/bin/python'} 12:56:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:56:31 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 12:56:31 INFO - Cleaning up... 12:56:31 INFO - Return code: 0 12:56:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:56:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:56:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:56:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:56:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:56:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:56:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:56:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbcc7556f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x296c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a39ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a36f60>, '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 0x2a37d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a381d0>, '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': '5cf47e208febbcd81da23b2e0000026f-1474204125.502405-684267107', '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 12:56:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 12:56:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 12:56:31 INFO - Using env: {'DISPLAY': ':0', 12:56:31 INFO - 'HOME': '/home/cltbld', 12:56:31 INFO - 'LANG': 'en_US.UTF-8', 12:56:31 INFO - 'LANGUAGE': 'en_US:en', 12:56:31 INFO - 'LOGNAME': 'cltbld', 12:56:31 INFO - 'MAIL': '/var/mail/cltbld', 12:56:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:56:31 INFO - 'MOZ_NO_REMOTE': '1', 12:56:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:56:31 INFO - 'NO_EM_RESTART': '1', 12:56:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:56:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:56:31 INFO - 'PWD': '/builds/slave/test', 12:56:31 INFO - 'SHELL': '/bin/bash', 12:56:31 INFO - 'SHLVL': '1', 12:56:31 INFO - 'TERM': 'linux', 12:56:31 INFO - 'TMOUT': '86400', 12:56:31 INFO - 'USER': 'cltbld', 12:56:31 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474204125.502405-684267107', 12:56:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:56:31 INFO - '_': '/tools/buildbot/bin/python'} 12:56:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:56:31 INFO - Downloading/unpacking psutil>=3.1.1 12:56:31 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 12:56:31 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 12:56:31 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 12:56:31 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 12:56:31 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 12:56:31 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 12:56:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:56:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:56:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:56:33 INFO - Installing collected packages: psutil 12:56:33 INFO - Running setup.py install for psutil 12:56:33 INFO - building 'psutil._psutil_linux' extension 12:56:33 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 12:56:33 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 12:56:33 INFO - building 'psutil._psutil_posix' extension 12:56:33 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 12:56:33 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 12:56:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:56:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:56:34 INFO - Successfully installed psutil 12:56:34 INFO - Cleaning up... 12:56:34 INFO - Return code: 0 12:56:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:56:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:56:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:56:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:56:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:56:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:56:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:56:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbcc7556f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x296c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a39ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a36f60>, '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 0x2a37d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a381d0>, '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': '5cf47e208febbcd81da23b2e0000026f-1474204125.502405-684267107', '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 12:56:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:56:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 12:56:34 INFO - Using env: {'DISPLAY': ':0', 12:56:34 INFO - 'HOME': '/home/cltbld', 12:56:34 INFO - 'LANG': 'en_US.UTF-8', 12:56:34 INFO - 'LANGUAGE': 'en_US:en', 12:56:34 INFO - 'LOGNAME': 'cltbld', 12:56:34 INFO - 'MAIL': '/var/mail/cltbld', 12:56:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:56:34 INFO - 'MOZ_NO_REMOTE': '1', 12:56:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:56:34 INFO - 'NO_EM_RESTART': '1', 12:56:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:56:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:56:34 INFO - 'PWD': '/builds/slave/test', 12:56:34 INFO - 'SHELL': '/bin/bash', 12:56:34 INFO - 'SHLVL': '1', 12:56:34 INFO - 'TERM': 'linux', 12:56:34 INFO - 'TMOUT': '86400', 12:56:34 INFO - 'USER': 'cltbld', 12:56:34 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474204125.502405-684267107', 12:56:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:56:34 INFO - '_': '/tools/buildbot/bin/python'} 12:56:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:56:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:56: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 12:56: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 12:56: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 12:56: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 12:56: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 12:56: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 12:56:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:56:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:56:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:56:36 INFO - Installing collected packages: mozsystemmonitor 12:56:36 INFO - Running setup.py install for mozsystemmonitor 12:56:36 INFO - Successfully installed mozsystemmonitor 12:56:36 INFO - Cleaning up... 12:56:36 INFO - Return code: 0 12:56:36 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:56:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:56:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:56:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:56:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:56:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:56:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:56:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbcc7556f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x296c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a39ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a36f60>, '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 0x2a37d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a381d0>, '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': '5cf47e208febbcd81da23b2e0000026f-1474204125.502405-684267107', '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 12:56:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 12:56:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 12:56:36 INFO - Using env: {'DISPLAY': ':0', 12:56:36 INFO - 'HOME': '/home/cltbld', 12:56:36 INFO - 'LANG': 'en_US.UTF-8', 12:56:36 INFO - 'LANGUAGE': 'en_US:en', 12:56:36 INFO - 'LOGNAME': 'cltbld', 12:56:36 INFO - 'MAIL': '/var/mail/cltbld', 12:56:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:56:36 INFO - 'MOZ_NO_REMOTE': '1', 12:56:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:56:36 INFO - 'NO_EM_RESTART': '1', 12:56:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:56:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:56:36 INFO - 'PWD': '/builds/slave/test', 12:56:36 INFO - 'SHELL': '/bin/bash', 12:56:36 INFO - 'SHLVL': '1', 12:56:36 INFO - 'TERM': 'linux', 12:56:36 INFO - 'TMOUT': '86400', 12:56:36 INFO - 'USER': 'cltbld', 12:56:36 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474204125.502405-684267107', 12:56:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:56:36 INFO - '_': '/tools/buildbot/bin/python'} 12:56:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:56:36 INFO - Downloading/unpacking jsonschema==2.5.1 12:56:36 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 12:56:36 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 12:56:36 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 12:56:36 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 12:56:36 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 12:56:36 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 12:56:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:56:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:56: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 12:56: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 12:56: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 12:56: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 12:56: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 12:56: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 12:56:38 INFO - Downloading functools32-3.2.3-2.tar.gz 12:56:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:56:38 INFO - warning: no files found matching '*.txt' 12:56:38 INFO - no previously-included directories found matching 'build' 12:56:38 INFO - no previously-included directories found matching 'dist' 12:56:38 INFO - no previously-included directories found matching '.git*' 12:56:38 INFO - Installing collected packages: jsonschema, functools32 12:56:38 INFO - Running setup.py install for functools32 12:56:38 INFO - warning: no files found matching '*.txt' 12:56:38 INFO - no previously-included directories found matching 'build' 12:56:38 INFO - no previously-included directories found matching 'dist' 12:56:38 INFO - no previously-included directories found matching '.git*' 12:56:38 INFO - Successfully installed jsonschema functools32 12:56:38 INFO - Cleaning up... 12:56:39 INFO - Return code: 0 12:56:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:56: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')]} 12:56:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:56:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:56: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')]} 12:56:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:56:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:56:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbcc7556f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x296c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a39ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a36f60>, '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 0x2a37d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a381d0>, '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': '5cf47e208febbcd81da23b2e0000026f-1474204125.502405-684267107', '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 12:56:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:56:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 12:56:39 INFO - Using env: {'DISPLAY': ':0', 12:56:39 INFO - 'HOME': '/home/cltbld', 12:56:39 INFO - 'LANG': 'en_US.UTF-8', 12:56:39 INFO - 'LANGUAGE': 'en_US:en', 12:56:39 INFO - 'LOGNAME': 'cltbld', 12:56:39 INFO - 'MAIL': '/var/mail/cltbld', 12:56:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:56:39 INFO - 'MOZ_NO_REMOTE': '1', 12:56:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:56:39 INFO - 'NO_EM_RESTART': '1', 12:56:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:56:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:56:39 INFO - 'PWD': '/builds/slave/test', 12:56:39 INFO - 'SHELL': '/bin/bash', 12:56:39 INFO - 'SHLVL': '1', 12:56:39 INFO - 'TERM': 'linux', 12:56:39 INFO - 'TMOUT': '86400', 12:56:39 INFO - 'USER': 'cltbld', 12:56:39 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474204125.502405-684267107', 12:56:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:56:39 INFO - '_': '/tools/buildbot/bin/python'} 12:56:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:56:39 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:56:39 INFO - Cleaning up... 12:56:39 INFO - Return code: 0 12:56:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:56: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')]} 12:56:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:56:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:56: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')]} 12:56:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:56:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:56:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbcc7556f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x296c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a39ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a36f60>, '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 0x2a37d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a381d0>, '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': '5cf47e208febbcd81da23b2e0000026f-1474204125.502405-684267107', '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 12:56:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:56:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:56:39 INFO - Using env: {'DISPLAY': ':0', 12:56:39 INFO - 'HOME': '/home/cltbld', 12:56:39 INFO - 'LANG': 'en_US.UTF-8', 12:56:39 INFO - 'LANGUAGE': 'en_US:en', 12:56:39 INFO - 'LOGNAME': 'cltbld', 12:56:39 INFO - 'MAIL': '/var/mail/cltbld', 12:56:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:56:39 INFO - 'MOZ_NO_REMOTE': '1', 12:56:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:56:39 INFO - 'NO_EM_RESTART': '1', 12:56:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:56:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:56:39 INFO - 'PWD': '/builds/slave/test', 12:56:39 INFO - 'SHELL': '/bin/bash', 12:56:39 INFO - 'SHLVL': '1', 12:56:39 INFO - 'TERM': 'linux', 12:56:39 INFO - 'TMOUT': '86400', 12:56:39 INFO - 'USER': 'cltbld', 12:56:39 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474204125.502405-684267107', 12:56:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:56:39 INFO - '_': '/tools/buildbot/bin/python'} 12:56:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:56:39 INFO - Downloading/unpacking blobuploader==1.2.4 12:56:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:56:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:56:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:56:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:56:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:56:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:56:41 INFO - Downloading blobuploader-1.2.4.tar.gz 12:56:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:56:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:56:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:56:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:56:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:56:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:56:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:56:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:56:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:56:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:56:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:56:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:56:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:56:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:56:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:56:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:56:42 INFO - Downloading docopt-0.6.1.tar.gz 12:56:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:56:42 INFO - Installing collected packages: blobuploader, requests, docopt 12:56:42 INFO - Running setup.py install for blobuploader 12:56:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:56:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:56:42 INFO - Running setup.py install for requests 12:56:42 INFO - Running setup.py install for docopt 12:56:42 INFO - Successfully installed blobuploader requests docopt 12:56:42 INFO - Cleaning up... 12:56:42 INFO - Return code: 0 12:56:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:56: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')]} 12:56:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:56:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:56: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')]} 12:56:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:56:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:56:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbcc7556f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x296c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a39ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a36f60>, '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 0x2a37d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a381d0>, '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': '5cf47e208febbcd81da23b2e0000026f-1474204125.502405-684267107', '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 12:56:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:56:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:56:42 INFO - Using env: {'DISPLAY': ':0', 12:56:42 INFO - 'HOME': '/home/cltbld', 12:56:42 INFO - 'LANG': 'en_US.UTF-8', 12:56:42 INFO - 'LANGUAGE': 'en_US:en', 12:56:42 INFO - 'LOGNAME': 'cltbld', 12:56:42 INFO - 'MAIL': '/var/mail/cltbld', 12:56:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:56:42 INFO - 'MOZ_NO_REMOTE': '1', 12:56:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:56:42 INFO - 'NO_EM_RESTART': '1', 12:56:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:56:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:56:42 INFO - 'PWD': '/builds/slave/test', 12:56:42 INFO - 'SHELL': '/bin/bash', 12:56:42 INFO - 'SHLVL': '1', 12:56:42 INFO - 'TERM': 'linux', 12:56:42 INFO - 'TMOUT': '86400', 12:56:42 INFO - 'USER': 'cltbld', 12:56:42 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474204125.502405-684267107', 12:56:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:56:42 INFO - '_': '/tools/buildbot/bin/python'} 12:56:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:56:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:56:42 INFO - Running setup.py (path:/tmp/pip-3OseZc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:56:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:56:42 INFO - Running setup.py (path:/tmp/pip-9BblUl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:56:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:56:43 INFO - Running setup.py (path:/tmp/pip-TASVqQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:56:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:56:43 INFO - Running setup.py (path:/tmp/pip-HeAUVM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:56:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:56:43 INFO - Running setup.py (path:/tmp/pip-fJlRyo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:56:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:56:43 INFO - Running setup.py (path:/tmp/pip-5Aw2Mt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:56:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:56:43 INFO - Running setup.py (path:/tmp/pip-vIV7H4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:56:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:56:43 INFO - Running setup.py (path:/tmp/pip-YSBrRn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:56:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:56:43 INFO - Running setup.py (path:/tmp/pip-OkqHon-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:56:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:56:43 INFO - Running setup.py (path:/tmp/pip-VZbhYr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:56:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:56:43 INFO - Running setup.py (path:/tmp/pip-BRjAd8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:56:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:56:43 INFO - Running setup.py (path:/tmp/pip-b3ocZU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:56:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:56:43 INFO - Running setup.py (path:/tmp/pip-7dgKwh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:56:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:56:43 INFO - Running setup.py (path:/tmp/pip-Go0kYn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:56:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:56:43 INFO - Running setup.py (path:/tmp/pip-T9nsja-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:56:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:56:43 INFO - Running setup.py (path:/tmp/pip-IDkrbN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:56:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:56:44 INFO - Running setup.py (path:/tmp/pip-7kFzs3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:56:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:56:44 INFO - Running setup.py install for manifestparser 12:56:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:56:44 INFO - Running setup.py install for mozcrash 12:56:44 INFO - Running setup.py install for mozdebug 12:56:44 INFO - Running setup.py install for mozdevice 12:56:44 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:56:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:56:44 INFO - Running setup.py install for mozfile 12:56:44 INFO - Running setup.py install for mozhttpd 12:56:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:56:44 INFO - Running setup.py install for mozinfo 12:56:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:56:44 INFO - Running setup.py install for mozInstall 12:56:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:56:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:56:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:56:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:56:44 INFO - Running setup.py install for mozleak 12:56:45 INFO - Running setup.py install for mozlog 12:56:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:56:45 INFO - Running setup.py install for moznetwork 12:56:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:56:45 INFO - Running setup.py install for mozprocess 12:56:45 INFO - Running setup.py install for mozprofile 12:56:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:56:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:56:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:56:45 INFO - Running setup.py install for mozrunner 12:56:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:56:45 INFO - Running setup.py install for mozscreenshot 12:56:45 INFO - Running setup.py install for moztest 12:56:45 INFO - Running setup.py install for mozversion 12:56:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:56:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:56:45 INFO - Cleaning up... 12:56:45 INFO - Return code: 0 12:56:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:56:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:56:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:56:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:56:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:56:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:56:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:56:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbcc7556f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x296c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a39ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a36f60>, '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 0x2a37d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a381d0>, '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': '5cf47e208febbcd81da23b2e0000026f-1474204125.502405-684267107', '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 12:56:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:56:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:56:45 INFO - Using env: {'DISPLAY': ':0', 12:56:45 INFO - 'HOME': '/home/cltbld', 12:56:45 INFO - 'LANG': 'en_US.UTF-8', 12:56:45 INFO - 'LANGUAGE': 'en_US:en', 12:56:45 INFO - 'LOGNAME': 'cltbld', 12:56:45 INFO - 'MAIL': '/var/mail/cltbld', 12:56:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:56:45 INFO - 'MOZ_NO_REMOTE': '1', 12:56:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:56:45 INFO - 'NO_EM_RESTART': '1', 12:56:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:56:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:56:45 INFO - 'PWD': '/builds/slave/test', 12:56:45 INFO - 'SHELL': '/bin/bash', 12:56:45 INFO - 'SHLVL': '1', 12:56:45 INFO - 'TERM': 'linux', 12:56:45 INFO - 'TMOUT': '86400', 12:56:45 INFO - 'USER': 'cltbld', 12:56:45 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474204125.502405-684267107', 12:56:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:56:45 INFO - '_': '/tools/buildbot/bin/python'} 12:56:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:56:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:56:46 INFO - Running setup.py (path:/tmp/pip-RSYVJu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:56:46 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 12:56:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:56:46 INFO - Running setup.py (path:/tmp/pip-qi6zvr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:56:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:56:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:56:46 INFO - Running setup.py (path:/tmp/pip-_606SH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:56:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:56:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:56:46 INFO - Running setup.py (path:/tmp/pip-gsVFts-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:56:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:56:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:56:46 INFO - Running setup.py (path:/tmp/pip-tdCVzF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:56:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 12:56:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:56:46 INFO - Running setup.py (path:/tmp/pip-gfAKEa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:56:46 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)) 12:56:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:56:46 INFO - Running setup.py (path:/tmp/pip-N9yC1m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:56:46 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)) 12:56:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:56:46 INFO - Running setup.py (path:/tmp/pip-mU602_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:56:46 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)) 12:56:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:56:46 INFO - Running setup.py (path:/tmp/pip-cF04dD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:56:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 12:56:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:56:46 INFO - Running setup.py (path:/tmp/pip-mPeV4K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:56:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:56:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:56:46 INFO - Running setup.py (path:/tmp/pip-VptSlu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:56:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 12:56:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:56:46 INFO - Running setup.py (path:/tmp/pip-3fKBFN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:56:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 12:56:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:56:47 INFO - Running setup.py (path:/tmp/pip-WYVqlF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:56:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 12:56:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:56:47 INFO - Running setup.py (path:/tmp/pip-XTQwnK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:56:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 12:56:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:56:47 INFO - Running setup.py (path:/tmp/pip-r_xv8C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:56:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 12:56:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:56:47 INFO - Running setup.py (path:/tmp/pip-stnvgV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:56:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 12:56:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:56:47 INFO - Running setup.py (path:/tmp/pip-DdkBHw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:56:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 12:56:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:56:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:56:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:56:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:56:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:56:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:56:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:56:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:56:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:56:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:56:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:56:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:56:49 INFO - Downloading blessings-1.6.tar.gz 12:56:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:56:49 INFO - Installing collected packages: blessings 12:56:49 INFO - Running setup.py install for blessings 12:56:49 INFO - Successfully installed blessings 12:56:49 INFO - Cleaning up... 12:56:49 INFO - Return code: 0 12:56:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:56:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:56:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:56:49 INFO - Reading from file tmpfile_stdout 12:56:49 INFO - Current package versions: 12:56:49 INFO - argparse == 1.2.1 12:56:49 INFO - blessings == 1.6 12:56:49 INFO - blobuploader == 1.2.4 12:56:49 INFO - docopt == 0.6.1 12:56:49 INFO - functools32 == 3.2.3-2 12:56:49 INFO - jsonschema == 2.5.1 12:56:49 INFO - manifestparser == 1.1 12:56:49 INFO - mozInstall == 1.12 12:56:49 INFO - mozcrash == 0.17 12:56:49 INFO - mozdebug == 0.1 12:56:49 INFO - mozdevice == 0.48 12:56:49 INFO - mozfile == 1.2 12:56:49 INFO - mozhttpd == 0.7 12:56:49 INFO - mozinfo == 0.9 12:56:49 INFO - mozleak == 0.1 12:56:49 INFO - mozlog == 3.4 12:56:49 INFO - moznetwork == 0.27 12:56:49 INFO - mozprocess == 0.23 12:56:49 INFO - mozprofile == 0.28 12:56:49 INFO - mozrunner == 6.12 12:56:49 INFO - mozscreenshot == 0.1 12:56:49 INFO - mozsystemmonitor == 0.3 12:56:49 INFO - moztest == 0.7 12:56:49 INFO - mozversion == 1.4 12:56:49 INFO - psutil == 3.1.1 12:56:49 INFO - requests == 1.2.3 12:56:49 INFO - wsgiref == 0.1.2 12:56:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:56:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:56:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:56:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:56:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:56:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:56:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:56:49 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 0x7fbcc7556f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x296c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a39ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a36f60>, '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 0x2a37d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a381d0>, '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': '5cf47e208febbcd81da23b2e0000026f-1474204125.502405-684267107', '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 12:56:49 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 12:56:49 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 12:56:49 INFO - Using env: {'DISPLAY': ':0', 12:56:49 INFO - 'HOME': '/home/cltbld', 12:56:49 INFO - 'LANG': 'en_US.UTF-8', 12:56:49 INFO - 'LANGUAGE': 'en_US:en', 12:56:49 INFO - 'LOGNAME': 'cltbld', 12:56:49 INFO - 'MAIL': '/var/mail/cltbld', 12:56:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:56:49 INFO - 'MOZ_NO_REMOTE': '1', 12:56:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:56:49 INFO - 'NO_EM_RESTART': '1', 12:56:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:56:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:56:49 INFO - 'PWD': '/builds/slave/test', 12:56:49 INFO - 'SHELL': '/bin/bash', 12:56:49 INFO - 'SHLVL': '1', 12:56:49 INFO - 'TERM': 'linux', 12:56:49 INFO - 'TMOUT': '86400', 12:56:49 INFO - 'USER': 'cltbld', 12:56:49 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474204125.502405-684267107', 12:56:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:56:49 INFO - '_': '/tools/buildbot/bin/python'} 12:56:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:56:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:56:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 12:56:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 12:56:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:56:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 12:56:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 12:56:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 12:56:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 12:56:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 12:56:49 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:56:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:56:49 INFO - Cleaning up... 12:56:49 INFO - Return code: 0 12:56:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:56:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:56:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:56:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:56:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:56:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:56:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:56:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbcc7556f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x296c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a39ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a36f60>, '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 0x2a37d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a381d0>, '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': '5cf47e208febbcd81da23b2e0000026f-1474204125.502405-684267107', '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 12:56:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:56:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:56:49 INFO - Using env: {'DISPLAY': ':0', 12:56:49 INFO - 'HOME': '/home/cltbld', 12:56:49 INFO - 'LANG': 'en_US.UTF-8', 12:56:49 INFO - 'LANGUAGE': 'en_US:en', 12:56:49 INFO - 'LOGNAME': 'cltbld', 12:56:49 INFO - 'MAIL': '/var/mail/cltbld', 12:56:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:56:49 INFO - 'MOZ_NO_REMOTE': '1', 12:56:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:56:49 INFO - 'NO_EM_RESTART': '1', 12:56:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:56:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:56:49 INFO - 'PWD': '/builds/slave/test', 12:56:49 INFO - 'SHELL': '/bin/bash', 12:56:49 INFO - 'SHLVL': '1', 12:56:49 INFO - 'TERM': 'linux', 12:56:49 INFO - 'TMOUT': '86400', 12:56:49 INFO - 'USER': 'cltbld', 12:56:49 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474204125.502405-684267107', 12:56:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:56:49 INFO - '_': '/tools/buildbot/bin/python'} 12:56:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:56:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:56:50 INFO - Cleaning up... 12:56:50 INFO - Return code: 0 12:56:50 INFO - Running post-action listener: _resource_record_post_action 12:56:50 INFO - Running post-action listener: _start_resource_monitoring 12:56:50 INFO - Starting resource monitoring. 12:56:50 INFO - [mozharness: 2016-09-18 19:56:50.210851Z] Finished create-virtualenv step (success) 12:56:50 INFO - [mozharness: 2016-09-18 19:56:50.211349Z] Running install step. 12:56:50 INFO - Running pre-action listener: _resource_record_pre_action 12:56:50 INFO - Running main action method: install 12:56:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:56:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:56:50 INFO - Reading from file tmpfile_stdout 12:56:50 INFO - Detecting whether we're running mozinstall >=1.0... 12:56:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:56:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:56:50 INFO - Reading from file tmpfile_stdout 12:56:50 INFO - Output received: 12:56:50 INFO - Usage: mozinstall [options] installer 12:56:50 INFO - Options: 12:56:50 INFO - -h, --help show this help message and exit 12:56:50 INFO - -d DEST, --destination=DEST 12:56:50 INFO - Directory to install application into. [default: 12:56:50 INFO - "/builds/slave/test"] 12:56:50 INFO - --app=APP Application being installed. [default: firefox] 12:56:50 INFO - mkdir: /builds/slave/test/build/application 12:56:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 12:56:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 12:57:06 INFO - Reading from file tmpfile_stdout 12:57:06 INFO - Output received: 12:57:06 INFO - /builds/slave/test/build/application/firefox/firefox 12:57:06 INFO - Running post-action listener: _resource_record_post_action 12:57:06 INFO - [mozharness: 2016-09-18 19:57:06.549605Z] Finished install step (success) 12:57:06 INFO - [mozharness: 2016-09-18 19:57:06.549856Z] Running run-tests step. 12:57:06 INFO - Running pre-action listener: _resource_record_pre_action 12:57:06 INFO - Running main action method: run_tests 12:57:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:57:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:57:06 INFO - Python 2.7.3 12:57:06 INFO - Return code: 0 12:57:06 INFO - grabbing minidump binary from tooltool 12:57:06 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')]} 12:57:06 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 0x2a36f60>, '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 0x2a37d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a381d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:57:06 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 12:57:06 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 12:57:06 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 12:57:06 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:57:06 INFO - Return code: 0 12:57:06 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:57:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:57:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:57:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:57:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:57:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TRmaxGIpRxW8NsN-NXJeEw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--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 12:57:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/TRmaxGIpRxW8NsN-NXJeEw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-011 --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 12:57:06 INFO - Using env: {'DISPLAY': ':0', 12:57:06 INFO - 'HOME': '/home/cltbld', 12:57:06 INFO - 'LANG': 'en_US.UTF-8', 12:57:06 INFO - 'LANGUAGE': 'en_US:en', 12:57:06 INFO - 'LOGNAME': 'cltbld', 12:57:06 INFO - 'MAIL': '/var/mail/cltbld', 12:57:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:57:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:57:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:57:06 INFO - 'MOZ_NO_REMOTE': '1', 12:57:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:57:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:57:06 INFO - 'NO_EM_RESTART': '1', 12:57:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:57:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:57:06 INFO - 'PWD': '/builds/slave/test', 12:57:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:57:06 INFO - 'SHELL': '/bin/bash', 12:57:06 INFO - 'SHLVL': '1', 12:57:06 INFO - 'TERM': 'linux', 12:57:06 INFO - 'TMOUT': '86400', 12:57:06 INFO - 'USER': 'cltbld', 12:57:06 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474204125.502405-684267107', 12:57:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:57:06 INFO - '_': '/tools/buildbot/bin/python'} 12:57:06 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TRmaxGIpRxW8NsN-NXJeEw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--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 12:57:06 INFO - mozversion application_buildid: 20160918114018 12:57:06 INFO - mozversion application_changeset: f0f15b7c6aa77a0c5750918aa0a1cb3dc82185bc 12:57:06 INFO - mozversion application_display_name: Nightly 12:57:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:57:06 INFO - mozversion application_name: Firefox 12:57:06 INFO - mozversion application_remotingname: firefox 12:57:06 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 12:57:06 INFO - mozversion application_vendor: Mozilla 12:57:06 INFO - mozversion application_version: 51.0a1 12:57:06 INFO - mozversion platform_buildid: 20160918114018 12:57:06 INFO - mozversion platform_changeset: f0f15b7c6aa77a0c5750918aa0a1cb3dc82185bc 12:57:06 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 12:57:06 INFO - mozversion platform_version: 51.0a1 12:57:06 INFO - using testdate: 1474228626 12:57:06 INFO - actual date: 1474228626 12:57:06 INFO - starting webserver on 'localhost:47475' 12:57:06 INFO - SUITE-START | Running 2 tests 12:57:06 INFO - TEST-START | dromaeo_css 12:57:06 INFO - operating with platform_type : linux_ 12:57:06 INFO - Initialising browser for dromaeo_css test... 12:57:06 INFO - TEST-INFO | started process 24041 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpztBNuz/profile http://localhost:47475/getInfo.html) 12:57:11 INFO - PROCESS | 24041 | __metrics Screen width/height:1600/1200 12:57:11 INFO - PROCESS | 24041 | colorDepth:24 12:57:11 INFO - PROCESS | 24041 | Browser inner width/height: 1024/697 12:57:11 INFO - PROCESS | 24041 | __metrics 12:57:12 INFO - PROCESS | 24041 | 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" 12:57:12 INFO - PROCESS | 24041 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:57:13 INFO - PROCESS | 24041 | console.error: PushService: 12:57:13 INFO - PROCESS | 24041 | stateChangeProcessEnqueue: Error transitioning state 12:57:13 INFO - PROCESS | 24041 | UnknownError 12:57:13 INFO - TEST-INFO | 24041: exit 0 12:57:13 INFO - Browser initialized. 12:57:13 INFO - Running cycle 1/1 for dromaeo_css test... 12:57:13 INFO - TEST-INFO | started process 24121 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpztBNuz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:57:26 INFO - PROCESS | 24121 | RSS: Main: 174608384 12:57:26 INFO - PROCESS | 24121 | 12:57:29 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.13337 seconds. 12:57:43 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 12:58:02 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.01666 seconds. 12:58:10 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 12:58:17 INFO - PROCESS | 24121 | 1474228697945 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] 12:58:23 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 12:58:29 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.01666 seconds. 12:58:34 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.01664 seconds. 12:58:41 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.01666 seconds. 12:58:43 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 12:58:48 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 12:59:03 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 12:59:05 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 12:59:09 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.09999 seconds. 12:59:16 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.06671 seconds. 12:59:22 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.05004 seconds. 12:59:25 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 12:59:27 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 12:59:44 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:59:56 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.05007 seconds. 13:00:02 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 13:00:05 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.15004 seconds. 13:00:08 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 13:00:15 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 13:00:20 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 13:00:30 INFO - PROCESS | 24121 | Cycle 1(1): loaded http://localhost:47475/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47475/tests/dromaeo/cssquery-ext.html) 13:00:31 INFO - PROCESS | 24121 | RSS: Main: 300044288 13:00:31 INFO - PROCESS | 24121 | 13:00:42 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.05003 seconds. 13:00:52 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 13:01:08 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 13:01:18 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 13:01:25 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.03339 seconds. 13:01:39 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 13:01:47 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 13:01:53 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 13:01:55 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 13:02:00 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 13:02:05 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 13:02:07 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 13:02:09 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 13:02:13 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 13:02:31 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 13:02:33 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 13:02:35 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 13:02:37 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 13:02:39 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 13:02:41 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 13:02:45 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 13:02:47 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 13:02:51 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 13:02:57 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 13:03:05 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 13:03:22 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 13:03:24 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 13:03:32 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.01666 seconds. 13:03:34 INFO - PROCESS | 24121 | Cycle 1(1): loaded http://localhost:47475/tests/dromaeo/cssquery-ext.html (next: http://localhost:47475/tests/dromaeo/cssquery-jquery.html) 13:03:35 INFO - PROCESS | 24121 | RSS: Main: 280469504 13:03:35 INFO - PROCESS | 24121 | 13:03:41 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.05003 seconds. 13:03:47 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 13:03:49 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 13:03:51 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 13:03:55 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 13:03:59 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00399 seconds. 13:04:07 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 13:04:09 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 13:04:16 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 13:04:25 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 13:04:54 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 13:04:58 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 13:05:00 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 13:05:08 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 13:05:36 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 13:05:38 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 13:05:40 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 13:05:42 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 13:05:46 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 13:05:51 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.01677 seconds. 13:06:06 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 13:06:09 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 13:06:17 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 13:06:19 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.01665 seconds. 13:06:27 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 13:06:31 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 13:06:33 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 13:06:37 INFO - PROCESS | 24121 | Cycle 1(1): loaded http://localhost:47475/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47475/tests/dromaeo/cssquery-mootools.html) 13:06:37 INFO - PROCESS | 24121 | RSS: Main: 246681600 13:06:37 INFO - PROCESS | 24121 | 13:06:41 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 13:07:09 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 13:07:12 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 13:07:13 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 2.01674 seconds. 13:07:18 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 13:07:20 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 13:07:24 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 13:07:28 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 13:07:30 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 13:07:45 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 13:07:47 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 13:07:59 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 13:08:11 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 13:08:13 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 13:08:24 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 13:08:28 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 13:08:31 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 13:08:33 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 13:08:35 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 13:08:37 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 13:08:39 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 13:08:41 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 13:08:51 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 13:08:55 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 13:09:09 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 13:09:13 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 13:09:20 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 13:09:26 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 13:09:28 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 13:09:34 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 13:09:36 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 13:09:38 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 13:09:40 INFO - PROCESS | 24121 | Cycle 1(1): loaded http://localhost:47475/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47475/tests/dromaeo/cssquery-prototype.html) 13:09:40 INFO - PROCESS | 24121 | RSS: Main: 220823552 13:09:40 INFO - PROCESS | 24121 | 13:09:50 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 13:09:51 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 13:09:58 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 13:10:17 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.06674 seconds. 13:10:19 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 13:10:36 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 13:10:40 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 13:10:53 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 13:11:06 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 13:11:11 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 13:11:19 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.03336 seconds. 13:11:29 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 13:11:31 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 13:11:34 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 13:11:40 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 13:11:42 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.99995 seconds. 13:11:43 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 13:11:48 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 13:11:52 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 13:11:54 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 13:11:57 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 13:12:01 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 13:12:06 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 13:12:07 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 13:12:11 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 13:12:13 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 13:12:27 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 13:12:29 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 13:12:31 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 13:12:41 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 13:12:44 INFO - PROCESS | 24121 | Cycle 1(1): loaded http://localhost:47475/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47475/tests/dromaeo/cssquery-yui.html) 13:12:44 INFO - PROCESS | 24121 | RSS: Main: 220721152 13:12:44 INFO - PROCESS | 24121 | 13:12:48 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 13:12:51 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.01675 seconds. 13:12:53 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 13:13:06 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 13:13:29 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 13:14:30 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 13:14:44 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 13:14:46 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 13:15:04 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 13:15:17 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 13:15:31 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.0334 seconds. 13:15:35 INFO - PROCESS | 24121 | [GFX1-]: Refresh driver waiting for the compositor for 1.05004 seconds. 13:15:47 INFO - PROCESS | 24121 | Cycle 1(1): loaded http://localhost:47475/tests/dromaeo/cssquery-yui.html (next: http://localhost:47475/tests/dromaeo/cssquery-dojo.html) 13:15:47 INFO - PROCESS | 24121 | RSS: Main: 317231104 13:15:47 INFO - PROCESS | 24121 | 13:15:47 INFO - PROCESS | 24121 | __start_tp_report 13:15:47 INFO - PROCESS | 24121 | _x_x_mozilla_page_load 13:15:47 INFO - PROCESS | 24121 | _x_x_mozilla_page_load_details 13:15:47 INFO - PROCESS | 24121 | |i|pagename|runs| 13:15:47 INFO - PROCESS | 24121 | |0;dojo.html;2540.072873401208;2567.1528395629443;2591.9092831750886;2603.752643498868;2619.0702300683256;4438.80025398857;4495.145922274765;4605.009922866583;4617.8614641560725;4623.375844261028;4509.301058335955;4550.294704320836;4574.199515084863;4599.563041511059;4619.514950930155;4167;4170.207660544502;4195.370694395834;4217.240896638604;4284.507281662602;4243.915121697564;4310.181065597542;4357.542458041908;4362.302031674933;4409.955900440987;4138.503379594438;4190.559991200941;4239.554846741109;4278.935815962763;4331.631811296043;4218.303979843322;4231.048014196812;4233.534311225762;4246.7876606169875;4256.106217694288;4168.103857670585;4205.07488352565;4211.768352740598;4215.220184265921;4221.366794980747;4550.7724613769515;4576.839810606612;4635.327877457784;4635.327877457784;4646.210144275447;4889.828855990058;4891.095147397743;4891.8287859925085;4896.657233993622;4957.082939656175;4751.501092385319;4806.255030470283;4916.655834091615;4935.777889994959;4939.703617782944;4472.396226509444;4526.366308716782;4543.886402839956;4551.977240113812;4554.24854898938;5001.149804533238;5039.193729003342;5086.287919691246;5087.033463641897;5087.440381558025;4761.2382018876815;4812.639052071108;4817.253325734517;4860.198067318924;4874.658773885794;4898.436679781799;4902.166631672624;4902.338184345139;4907.312976183337;4969.254611808257;4670.813167473331;4725.716457012591;4761.40482439695;4810.59109975649;4830.468648448667;4871.3667223260745;4887.780049897763;4907.067657145131;4911.066897289504;4936.037472692862;4153.875383738493;4154.480689913761;4161.126163505637;4204.978975105105;4215.262329092397;3645.423161314926;3657.524521812147;3694;3697.722670799647;3727.552693676776;4137.275976704064;4229.640480559187;4230.2174097792;4253.021804984871;4291.871243862689;4960.528749768766;4962.230854217602;4966.478519755446;5009.824656137018;5028.321176641107;4257.276263035292;4288.91422171561;4294.205571969195;4335.067960388468;4350.1734670412525;4291.785410729451;4333.068390296102;4382.276924307517;4423.800928958204;4436.112777444524;4438.755868427203;4657.790399431966;4660.766961651836;4714.787834547385;4719.787609557509;4900.044491324157;4930.728809915489;4931.161702510653;4934.062528119646;4993.101241776515;4554.089182163514;4590.563896429834;4655.18534256497;4672.32251323563;4728.290756386569;4750.762461876823;4754.976225118852;4792.568668819823;4803.519648035169;4854.708717476963;4847.975760121318;4852.369192005017;4859.732714700725;4860.052289803454;4918.2622606607865;4656.045510670372;4722.693024953367;4723.527647235249;4728.763561821964;4784.162771515041;4690.6247500199215;4748.192807222849;4770.284457331428;4782.2826576012485;4830.855074347775;4659.58063774262;4692.296155576691;4724.456687480846;4745.430548334222;4779.330893674819;4785.425748910153;4851.267718107322;4853.854384368475;4854.611631069577;4906.8773280668265;4562;4600.332951722048;4672.088942656285;4676.7661616919695;4716.33971244033 13:15:47 INFO - PROCESS | 24121 | |1;ext.html;555.3419198250076;558.4806130298825;570.0195663458851;578.3897987623056;582.7465052702073;27377.178684639443;37888.81055594722;38762.224755504874;38794.22411551774;42536.14927701452;39496.80251598745;39987.800060999725;40006.199876002465;40033.79983100088;40047.199056018864;7052.682629281669;7624.428167887403;7786.80532986677;7787.610619469032;7788.337991270749;7072.646367681596;9824.115829575338;10124.29129960903;10128.189744820433;10467.057964783167;9763.414195148278;9808;9829.606815727362;9840.852387214198;9849.261305402088;9950.800983980316;10013.949930250303;10018.699439016767;10019.448930308867;10031.398116113056;9900.603975840957;9961.302708810386;9965.601375944954;9969.202463802878;9974.40153590787;37169.44245836315;37470.06324841874;37510.24979500422;37539;37624;2570.961435578468;2613.7908967282574;2695.946081078387;3070.6008218931397;3083.753299736016;27959.440811183867;27980;28277.57583636247;28451.288717782016;29035.41929161426;3248.361834745077;3251;3251.138448311199;3305.8677652893857;3306.933861322784;248826;250101;250146;250909;251075;44897.32654010192;44944.77527612406;44957.775211123735;45009;45034.77482612566;31464.37071258562;32451.675483245337;52407.21389179165;52477.7376113117;52598.47401526012;12363.814542781865;12701.745965080647;12708.682282942815;12718.491260349483;12784.872151278554;8643.956780216058;8886.688965886164;9666.854997175047;9916.206703463706;10016.949915250523;16800.41198558069;17026.829731702524;17051.14744262782;17053.7441938371;17117.315307387813;6633.966830165915;6650.467962562983;6664.900026499607;6941.305869413019;6970.233274339818;758.9582572958541;877.899041610223;886.9334799890034;915.7527467583715;924.4360939826701;26131.216063518124;27382.589261161098;27503.31241718973;27704;27956.30109247285;926.8424367857345;930.7905721212672;953.9570719317647;1004.1715584642699;1008.6318493749728;14039.087459315111;14733.429754126933;14811.629709257355;14821;14972.176530290939;352139;410423;415589;420115;660600;48957;49541.50458495441;49553;49630.25554616684;49679.25481117786;1049.9580016799243;1086.5327908999047;1093.4040947683372;1179.2217136689744;1180.3154170581042;22013.66979495341;26565.734342656327;28276.717232828232;29945.368253826106;30271.697283026886;44825;45482.772586138184;45502.08995819999;45563.54436455726;45656.08687826291;332450;335659;335801;335836;336056;21656;22204;23991.640125397786;24407.75592244055;25055.123070693167;39146.608533914296;39335.41678052751;39376.606233937295;39603;39858.40212396874;33615;33956.66043339535;34351.4847277286;34359;34886.476702849985;33690.663093368756;33864;33976;34135.829320853234;34475.310493790486;33123;33798;34065;34334.48498272578;34414.655853441145;28660.283492912844;30794.692053079183;31030.22424439407;31222.843885780425;31278;23874.40313992164;23895.880520597286;24006.51986960286;24032.279031629078;24134.637980429958 13:15:47 INFO - PROCESS | 24121 | |2;jquery.html;345.3645292661502;368.44916849310266;369.5177792980161;375.94172903200007;388.10735308789793;12022.639320820379;15586.298616562253;15662.765058524132;16855;17876.19557119933;16872.409465668705;16886.155692215372;16938.40715574955;16986.235619397125;17110.486685399428;4974.801007959677;4979.576735977446;5084.262781896623;5089.524038028967;5126.333576635051;3601.7118630509494;3782.924341513168;4944.134776414131;5014.949850501503;5018.096742586333;3808.485854409661;3812.809359532026;3815.866444674437;5564.4991950724425;5856.619319744224;5140.1518749406305;5423.593230507728;6016.278046634388;6042.969785151068;6047.546434017467;5350.571954243651;5354.41101478837;5360.061989151922;5361.571074314045;5361.812336568201;12305.938470307681;12330.876691233063;12470.314132722697;12559.811602825874;12653.74692506154;1734.167599552222;1865.822746839048;1869.1308541528256;1888.0654076232354;2090.8850013249266;9157.496337701425;9578.61685532578;9878.703638890845;11474.254173478697;13386.263755493444;1635.8118816336155;1655.337864854056;1657.3287818433553;1704.9062301573408;1710.1791140252624;64431.67784161049;66619.3338066618;66863;70360.29639703588;71262.64368678123;9783.706488805346;9832.705018849447;9890.20878329732;10107.494625268708;10214.744631384276;13735.703431176446;13747.175169489663;13755.931220344035;13758.587242382744;13769.862301376857;6480.157579514727;6585.6707164641575;6720.361565651256;6797.198742333286;6832.385085342343;6135;6147.477464415575;6329.3354197809485;6334.3032266450655;6351.682415879188;4285.978570107191;4293.44185255921;4300.548442413564;4310.612044915973;4395.7142785718825;1609.3884323957056;1813.5738101546126;1834.367143335547;1835.109971663742;1851.4723303858605;728.8578727148155;823.7487566292339;828.1759649149135;860.5697151424288;915.0392088307251;16079.839201608069;16369.363063693536;16641.91679041597;17153.142342882806;17179.226934787963;827.8923739913772;837.8994520657438;840.2437805974549;849.5327569836566;850.056437354536;8904.599293031699;9216;9569.282303827102;9594.520273986273;9773.65792197284;358956;365324;365542;372930;373087;73335.99999999894;74550.6272468645;74673.62663186649;76525;76613.63704540629;842.4566154830101;871.5293741379711;871.655696000089;871.9084496127942;871.9128087191231;13992.440302388197;14648.853511465175;14941.551753447415;14967.557461784068;15106.320215590105;35617;36085.63914360823;36217;36720.63279367279;37253.25493490169;49750.25374619311;49805.78204596201;49934;50248.74875625598;50702;6967.895481567679;7101.964490177516;7200.603966781878;7206.207317195;7323.194448610755;9034.2320902724;10223.7444063899;10327.32872363294;10412.219083568914;10812.891871081189;8749.47503149813;9022.413543119676;9063.864042039242;9259.120383563552;9444.386114902509;8939.642414303355;9064.637414503346;9093.136152065543;9100.817983640423;9397.107274808883;9179.724608261762;9226.584803683716;9264.768380790534;9356.906430935876;9450.338476306593;7020.7542736004;7121.67952442131;7314.597697126709;7339.853202935805;7522.623868806647;7254.347108760237;8832.980993648003;8989.640414383613;9172.587233574372;9354.438733676001 13:15:47 INFO - PROCESS | 24121 | |3;mootools.html;1224.589762429586;1353.1678018018922;1411.5906387147727;1468.9706205875882;1701.8808683392167;6599.604023758572;6921.896171557424;7541.924580754191;7811.023622047244;7811.453198276123;7864.96067519663;7931.801704957379;7937.761867143995;7946.086200086992;7953.125156232824;2157.870527768335;2178.5425060737266;2182.6289530779804;2183.4868805830615;2184.3446965910243;2126.0964090261577;2167.8916054197307;2184.6176919039185;2196.769339219383;2196.8352373571965;2183.170395249803;2192.572448372568;2207.624703800358;2207.7350717913873;2217.5475063833214;2145.5065334972965;2149.3659370485743;2152.0961196297435;2152.838537109715;2153.935381938544;2080.583883223346;2142.592907347599;2143.95712085759;2145.249162793026;2148.7421509418814;7719.9614001930195;7785.338246249075;7819.8436031279625;7834.8433031339055;7867.881981770278;4351.151525452538;4516.683832131752;4524.88687782808;4570.794314255833;4575.679702420832;2733.7949653776045;2837.361593641414;2878.6977367376335;2885.6104425902445;2892.652881654194;999.2505620784411;1000.8899021107673;1025.4052649463292;1027.8869324374314;1030.7835354575548;10621.628243011535;10702.143828493701;10707.357558546511;10718.142548596172;10733.034026937536;4014.4580481634607;4166.270902592034;4299.656027517791;4301.16127355169;4310.43964284641;8354.540500272422;8355.415120941598;8384.161583841567;8421.663133474714;8430.494170349799;4428.645708343389;4438.889027774267;4446.4886538047895;4457.687961842705;4459.063596644741;3325.833708314575;3504.7196224302506;3676.44853272006;4125.092479654471;4128.256913755493;3441;3502.2470168913806;3528.8588456461453;3593.4250519916686;3617.4573813927595;1066.440118937564;1079.3685693869174;1081.626838740649;1087.429099722636;1094.6059418609295;717.5802155738951;726.9091363579553;743.985120297591;753.6194221917897;778.0624347661168;4781.378420805273;5029.924551131736;5041.899162016739;5265.736713164403;5282.3624676350455;868.3443999780126;869.7129947117436;875.5403413207989;920.894097178833;938.3712912348743;3151.16494129056;3223.903282901517;3237.6924192201704;3289.4243507386113;3427.537282466885;9578.856317155249;9720.611175552898;9821.656242031637;9846.753831154278;9912;9754.122129008423;9760.511974401252;9763.560639771227;9787.46168960714;9796.118349348593;7284.453665975073;7376.926230737731;7403.14863790671;7404.111506619241;7404.37062849653;4936.605071594336;5021.874453138701;5032.924506132485;5089.618278629044;5178.792848286026;8665.306775458075;8784.165504277084;8943.463392196489;8943.776405589651;8946.37375383717;7841.764747057369;8868.467891926504;8957.939673875175;9007.819843603222;9073.637054517745;5535.529479994246;5926.259217597801;6070.301915279622;6098.908516372169;6202.224721909762;7179.282071792779;7232.927670723226;7293.270672932665;7295.452841037049;7302.233265507258;6766.15423072116;6814.1482314710665;6907.067545881242;6971.895421568782;7011.3339232772805;6458.451031662361;6518.837029074311;6529.575577587441;6638.668066596748;6663.666816659244;5671.858203544779;5828.067509198508;5874.676892770939;5958.821235362946;6126.417990290915;4297.914041719086;4317.978410107929;4407.889802754828;4435.401220835146;4608.032313214262;4219.662427005894;4271.530131685574;4376.824927002885;4420.292753159479;4436.245838207448 13:15:47 INFO - PROCESS | 24121 | |4;prototype.html;292.10178700495976;292.9135904908052;294.2951630844127;297.7528651219488;299.6479137014008;4632.281996290576;4738.644601654881;4766.976165119174;4783.497732738056;4803.879903002422;4682.976585117078;4714.222153344703;4738.881527961803;4743.288506723993;4761.785719642616;3530.6469353064745;3566.7681600695873;3639.5996440391536;3669.2478042001458;3753.8123093845334;3665.047087757189;3673.687736542397;3674.7427680062406;3677;3691.427828686558;3638.6725194732467;3645.325614761278;3646.325429795483;3647.854085836563;3649.8540058397634;3461;3551.733619978512;3574.767640103411;3645.5989841117457;3677.411614141751;3202.558848518153;3205.6473787883547;3207.262329664167;3211.983940080285;3214.4374734421617;4588.931166032478;4604.424446944132;4620.330052142454;4626.930596041062;4631.536846315375;3951.644352008333;4158.91682166358;4159;4173.937390939138;4180.623743863037;4876.292937524075;4921.975390123062;4935.506449355072;4936.654434189608;4944.975275123601;3497.318022985519;3498.982505087484;3502.2120022994877;3503.754737168399;3508.5088087667746;5101.15830887903;5103.081445339837;5124.615653825978;5129.204973229118;5135.35808023997;3428.674275943781;3550.1657110578994;3565.35823551758;3584.1039740064984;3603.801790901473;3602.7117830573493;3627.4558816177255;3630.146915474861;3636.399994001013;3636.56361236648;4149.522804877417;4150.460440142762;4159.230542349675;4163.188178305201;4164.66682665386;4817.759112044454;4944.777485013183;4950.8762280943265;4958.281049247839;4965.4041515018425;1234.7592219517107;1261.744564158668;1270.4854533913779;1270.9364531773374;1271.4850485553366;793.1949071692237;799.9720009799629;810.4165001199127;813.1665043330634;813.6175997281268;4267.957320426818;4300.591443812833;4439.778011099432;4454.841741147325;4469.687121901436;5088.974555127201;5099.6685215460875;5121.385433747974;5131.5625078087605;5139.563137133311;4436.401085853433;4465.173942820588;4465.709728867613;4482.686211965131;4488.371627972087;2574.575195092826;2692.72095754515;2730.5904114382606;2734.892368590724;2736.9609640817716;4868.829590964299;4880.633952453581;4883.609311255091;4887.800489779614;4889.242167464049;4955.355803745562;5235.947640523622;5238.895222095537;5258.50044245796;5269.314989051399;4829.7058897023635;4855.660103792771;4876.902461950741;4897.95102048975;4901.583365413909;5122.718250496258;5147.356580427447;5147.58819294448;5148.1248187808915;5158.819441319536;4736.242201247784;5002.699838009731;5048.268001139888;5065.986802639413;5066.544011039025;4834.250691142878;4834.274858771072;4834.5890599299455;4843.491433399583;4854.902901942012;3631.3826649469124;3636.5272514572935;3640.308341415123;3643.417053271464;3652.160003199226;4869.561739443467;5121.462246464217;5128.512791284822;5130.179171332718;5130.640855140105;4964;4983.103041452573;4983.4518202996915;4994.6503744738975;4997.650634328784;4883.924332158747;4950.282209079735;4959;4975.701457912537;4976.8755781105765;4697.436307643105;4884.169691152441;4889.926651100308;4892.290617860462;4906.95093049065;4797.856064318076;4847.491013443679;4934.605231581537;4940.728259945594;5005.724685142207;4861.562459378673;4867.829625963074;4868.101440438303;4871.97688485422;4915 13:15:47 INFO - PROCESS | 24121 | |5;yui.html;514.4700958013248;556.1657513729406;557.5623135838367;620.7020630097552;644.3878315600181;11867.347295898722;12903.16129451585;12959.028072894524;13059.934700326497;13071.08502404832;11788.646340609796;12888.484460621587;12985.415656295465;12999.415026323815;13067.150635208705;743.7768669399172;760.7451503746224;760.9543427394347;761.3984951888002;762.6834863531627;566.4703502225411;581.9185314056035;729.2088084428401;754.8226166850785;758.9924100758977;790.2768966395741;791.0151860933146;792.1247022040649;792.6868886789715;792.6948124971899;789.3290702902544;789.3921680306161;789.6051974012994;789.834134831686;789.9526028438312;429.91616634756235;436.78815774349414;558.4694540186839;571.6855729348883;582.4117641182394;12123.575674851427;12222.388880556025;12262.693432664166;12281.201721888048;12307.876921230763;10840.295380800222;11239.550417983271;11387.658370248906;11658.766824663544;11660;10604.204684648605;10668.839967400418;10671.893281067245;10918.399488028155;11082.889171108267;3738.495303134069;3757.210985693008;3768.6419790119894;3772.3398405278967;3825.196708691178;66303;66350.66824665894;66366.3363366365;66780;67208.6639566799;8856.822863542693;8926.642934282556;8949.463032218087;9029.683961061462;9195.356325057182;16445.342186312653;16521.91739041297;16538.50384488443;16540.503784886474;16608.750868736977;6049.546284028627;6085.9695701521205;6216.44052035319;6229.314775374705;6238.438540531374;3322.368749937504;3358.8152651604396;3362.9327413451592;3387.983060084733;3421.5894092708536;2576.214254652349;2679.370347968226;2679.557872950941;2679.7454241846995;2680.4103097318557;454.7612503435657;459.18494671956876;465.2393336894202;466.0329815632508;475.4579779051884;336.5876800918904;347.93358356636827;360.68620300338875;368.19916681218245;371.36311226247113;6589.505787065989;6680.83297917556;6687.364700353459;6708.3291670833505;6911.170659520789;343.32879221122647;361.43796396603534;362.42012779553033;402.0649260399133;429.7077986968829;3026.4252032803734;3308.71875890552;3348.8827891024184;3371.4100032494707;3393.253484233514;98030.01969980208;99697.50151249343;104400;105248;105360.47319763352;51915.740421297654;52826.73586632042;53187.20219196715;66478.99999999904;86838.56580717055;47055.29417058747;66502;78168;78733.60633196797;78917.97853354132;6201.10084037821;6247.9062814056915;6330;6535.640539770358;6618.338166183536;45182.32226516539;45953;47023.76488117674;47780.7610961943;47819.28271075867;60535;65556.34443655502;65589;65663.67168164128;69144;7735.18780528059;7857.384587820956;7968.561729104955;8152.419551105293;8389.11914248995;8265.628046737791;8286.792830179294;8415.663373465237;8496.490210587384;8598.269147122564;7656.349210317186;7730.226977302224;7805.180456052033;7860.646270917708;8052.677892884219;6271.560990730787;8687.82714333557;9072.138146876036;9347.813043739223;9582.952085239529;9328.860067099135;9342.626294948397;9444.763880903032;9587.856182157133;10131.594736210469;8345.040320363236;8352.749417517483;8455.594242577577;8463.957680211806;8603.397762156588;8339.416240863082;8349.916500834914;8534.402591818762;8627.266682332072;8740.475571465733 13:15:47 INFO - PROCESS | 24121 | __end_tp_report 13:15:47 INFO - PROCESS | 24121 | __start_cc_report 13:15:47 INFO - PROCESS | 24121 | _x_x_mozilla_cycle_collect,1091 13:15:47 INFO - PROCESS | 24121 | __end_cc_report 13:15:47 INFO - PROCESS | 24121 | __startTimestamp1474229747619__endTimestamp 13:15:47 INFO - PROCESS | 24121 | 13:15:47 INFO - PROCESS | 24121 | ------- Summary: start ------- 13:15:47 INFO - PROCESS | 24121 | Number of tests: 6 13:15:47 INFO - PROCESS | 24121 | 13:15:47 INFO - PROCESS | 24121 | [#0] dojo.html Cycles:165 Average:4534.65 Median:4657.79 stddev:464.56 (10.0%) stddev-sans-first:438.84 13:15:47 INFO - PROCESS | 24121 | Values: 2540.1 2567.2 2591.9 2603.8 2619.1 4438.8 4495.1 4605.0 4617.9 4623.4 4509.3 4550.3 4574.2 4599.6 4619.5 4167.0 4170.2 4195.4 4217.2 4284.5 4243.9 4310.2 4357.5 4362.3 4410.0 4138.5 4190.6 4239.6 4278.9 4331.6 4218.3 4231.0 4233.5 4246.8 4256.1 4168.1 4205.1 4211.8 4215.2 4221.4 4550.8 4576.8 4635.3 4635.3 4646.2 4889.8 4891.1 4891.8 4896.7 4957.1 4751.5 4806.3 4916.7 4935.8 4939.7 4472.4 4526.4 4543.9 4552.0 4554.2 5001.1 5039.2 5086.3 5087.0 5087.4 4761.2 4812.6 4817.3 4860.2 4874.7 4898.4 4902.2 4902.3 4907.3 4969.3 4670.8 4725.7 4761.4 4810.6 4830.5 4871.4 4887.8 4907.1 4911.1 4936.0 4153.9 4154.5 4161.1 4205.0 4215.3 3645.4 3657.5 3694.0 3697.7 3727.6 4137.3 4229.6 4230.2 4253.0 4291.9 4960.5 4962.2 4966.5 5009.8 5028.3 4257.3 4288.9 4294.2 4335.1 4350.2 4291.8 4333.1 4382.3 4423.8 4436.1 4438.8 4657.8 4660.8 4714.8 4719.8 4900.0 4930.7 4931.2 4934.1 4993.1 4554.1 4590.6 4655.2 4672.3 4728.3 4750.8 4755.0 4792.6 4803.5 4854.7 4848.0 4852.4 4859.7 4860.1 4918.3 4656.0 4722.7 4723.5 4728.8 4784.2 4690.6 4748.2 4770.3 4782.3 4830.9 4659.6 4692.3 4724.5 4745.4 4779.3 4785.4 4851.3 4853.9 4854.6 4906.9 4562.0 4600.3 4672.1 4676.8 4716.3 13:15:47 INFO - PROCESS | 24121 | 13:15:47 INFO - PROCESS | 24121 | [#1] ext.html Cycles:180 Average:48766.31 Median:34100.41 stddev:95807.75 (281.0%) stddev-sans-first:96008.14 13:15:47 INFO - PROCESS | 24121 | Values: 555.3 558.5 570.0 578.4 582.7 27377.2 37888.8 38762.2 38794.2 42536.1 39496.8 39987.8 40006.2 40033.8 40047.2 7052.7 7624.4 7786.8 7787.6 7788.3 7072.6 9824.1 10124.3 10128.2 10467.1 9763.4 9808.0 9829.6 9840.9 9849.3 9950.8 10013.9 10018.7 10019.4 10031.4 9900.6 9961.3 9965.6 9969.2 9974.4 37169.4 37470.1 37510.2 37539.0 37624.0 2571.0 2613.8 2695.9 3070.6 3083.8 27959.4 27980.0 28277.6 28451.3 29035.4 3248.4 3251.0 3251.1 3305.9 3306.9 248826.0 250101.0 250146.0 250909.0 251075.0 44897.3 44944.8 44957.8 45009.0 45034.8 31464.4 32451.7 52407.2 52477.7 52598.5 12363.8 12701.7 12708.7 12718.5 12784.9 8644.0 8886.7 9666.9 9916.2 10016.9 16800.4 17026.8 17051.1 17053.7 17117.3 6634.0 6650.5 6664.9 6941.3 6970.2 759.0 877.9 886.9 915.8 924.4 26131.2 27382.6 27503.3 27704.0 27956.3 926.8 930.8 954.0 1004.2 1008.6 14039.1 14733.4 14811.6 14821.0 14972.2 352139.0 410423.0 415589.0 420115.0 660600.0 48957.0 49541.5 49553.0 49630.3 49679.3 1050.0 1086.5 1093.4 1179.2 1180.3 22013.7 26565.7 28276.7 29945.4 30271.7 44825.0 45482.8 45502.1 45563.5 45656.1 332450.0 335659.0 335801.0 335836.0 336056.0 21656.0 22204.0 23991.6 24407.8 25055.1 39146.6 39335.4 39376.6 39603.0 39858.4 33615.0 33956.7 34351.5 34359.0 34886.5 33690.7 33864.0 33976.0 34135.8 34475.3 33123.0 33798.0 34065.0 34334.5 34414.7 28660.3 30794.7 31030.2 31222.8 31278.0 23874.4 23895.9 24006.5 24032.3 24134.6 13:15:47 INFO - PROCESS | 24121 | 13:15:47 INFO - PROCESS | 24121 | [#2] jquery.html Cycles:180 Average:23013.39 Median:5133.24 stddev:60798.50 (1184.4%) stddev-sans-first:60945.23 13:15:47 INFO - PROCESS | 24121 | Values: 345.4 368.4 369.5 375.9 388.1 12022.6 15586.3 15662.8 16855.0 17876.2 16872.4 16886.2 16938.4 16986.2 17110.5 4974.8 4979.6 5084.3 5089.5 5126.3 3601.7 3782.9 4944.1 5014.9 5018.1 3808.5 3812.8 3815.9 5564.5 5856.6 5140.2 5423.6 6016.3 6043.0 6047.5 5350.6 5354.4 5360.1 5361.6 5361.8 12305.9 12330.9 12470.3 12559.8 12653.7 1734.2 1865.8 1869.1 1888.1 2090.9 9157.5 9578.6 9878.7 11474.3 13386.3 1635.8 1655.3 1657.3 1704.9 1710.2 64431.7 66619.3 66863.0 70360.3 71262.6 9783.7 9832.7 9890.2 10107.5 10214.7 13735.7 13747.2 13755.9 13758.6 13769.9 6480.2 6585.7 6720.4 6797.2 6832.4 6135.0 6147.5 6329.3 6334.3 6351.7 4286.0 4293.4 4300.5 4310.6 4395.7 1609.4 1813.6 1834.4 1835.1 1851.5 728.9 823.7 828.2 860.6 915.0 16079.8 16369.4 16641.9 17153.1 17179.2 827.9 837.9 840.2 849.5 850.1 8904.6 9216.0 9569.3 9594.5 9773.7 358956.0 365324.0 365542.0 372930.0 373087.0 73336.0 74550.6 74673.6 76525.0 76613.6 842.5 871.5 871.7 871.9 871.9 13992.4 14648.9 14941.6 14967.6 15106.3 35617.0 36085.6 36217.0 36720.6 37253.3 49750.3 49805.8 49934.0 50248.7 50702.0 6967.9 7102.0 7200.6 7206.2 7323.2 9034.2 10223.7 10327.3 10412.2 10812.9 8749.5 9022.4 9063.9 9259.1 9444.4 8939.6 9064.6 9093.1 9100.8 9397.1 9179.7 9226.6 9264.8 9356.9 9450.3 7020.8 7121.7 7314.6 7339.9 7522.6 7254.3 8833.0 8989.6 9172.6 9354.4 13:15:47 INFO - PROCESS | 24121 | 13:15:47 INFO - PROCESS | 24121 | [#3] mootools.html Cycles:180 Average:5020.25 Median:4979.24 stddev:2855.88 (57.4%) stddev-sans-first:2849.65 13:15:47 INFO - PROCESS | 24121 | Values: 1224.6 1353.2 1411.6 1469.0 1701.9 6599.6 6921.9 7541.9 7811.0 7811.5 7865.0 7931.8 7937.8 7946.1 7953.1 2157.9 2178.5 2182.6 2183.5 2184.3 2126.1 2167.9 2184.6 2196.8 2196.8 2183.2 2192.6 2207.6 2207.7 2217.5 2145.5 2149.4 2152.1 2152.8 2153.9 2080.6 2142.6 2144.0 2145.2 2148.7 7720.0 7785.3 7819.8 7834.8 7867.9 4351.2 4516.7 4524.9 4570.8 4575.7 2733.8 2837.4 2878.7 2885.6 2892.7 999.3 1000.9 1025.4 1027.9 1030.8 10621.6 10702.1 10707.4 10718.1 10733.0 4014.5 4166.3 4299.7 4301.2 4310.4 8354.5 8355.4 8384.2 8421.7 8430.5 4428.6 4438.9 4446.5 4457.7 4459.1 3325.8 3504.7 3676.4 4125.1 4128.3 3441.0 3502.2 3528.9 3593.4 3617.5 1066.4 1079.4 1081.6 1087.4 1094.6 717.6 726.9 744.0 753.6 778.1 4781.4 5029.9 5041.9 5265.7 5282.4 868.3 869.7 875.5 920.9 938.4 3151.2 3223.9 3237.7 3289.4 3427.5 9578.9 9720.6 9821.7 9846.8 9912.0 9754.1 9760.5 9763.6 9787.5 9796.1 7284.5 7376.9 7403.1 7404.1 7404.4 4936.6 5021.9 5032.9 5089.6 5178.8 8665.3 8784.2 8943.5 8943.8 8946.4 7841.8 8868.5 8957.9 9007.8 9073.6 5535.5 5926.3 6070.3 6098.9 6202.2 7179.3 7232.9 7293.3 7295.5 7302.2 6766.2 6814.1 6907.1 6971.9 7011.3 6458.5 6518.8 6529.6 6638.7 6663.7 5671.9 5828.1 5874.7 5958.8 6126.4 4297.9 4318.0 4407.9 4435.4 4608.0 4219.7 4271.5 4376.8 4420.3 4436.2 13:15:47 INFO - PROCESS | 24121 | 13:15:47 INFO - PROCESS | 24121 | [#4] prototype.html Cycles:180 Average:4110.79 Median:4738.76 stddev:1205.74 (25.4%) stddev-sans-first:1174.56 13:15:47 INFO - PROCESS | 24121 | Values: 292.1 292.9 294.3 297.8 299.6 4632.3 4738.6 4767.0 4783.5 4803.9 4683.0 4714.2 4738.9 4743.3 4761.8 3530.6 3566.8 3639.6 3669.2 3753.8 3665.0 3673.7 3674.7 3677.0 3691.4 3638.7 3645.3 3646.3 3647.9 3649.9 3461.0 3551.7 3574.8 3645.6 3677.4 3202.6 3205.6 3207.3 3212.0 3214.4 4588.9 4604.4 4620.3 4626.9 4631.5 3951.6 4158.9 4159.0 4173.9 4180.6 4876.3 4922.0 4935.5 4936.7 4945.0 3497.3 3499.0 3502.2 3503.8 3508.5 5101.2 5103.1 5124.6 5129.2 5135.4 3428.7 3550.2 3565.4 3584.1 3603.8 3602.7 3627.5 3630.1 3636.4 3636.6 4149.5 4150.5 4159.2 4163.2 4164.7 4817.8 4944.8 4950.9 4958.3 4965.4 1234.8 1261.7 1270.5 1270.9 1271.5 793.2 800.0 810.4 813.2 813.6 4268.0 4300.6 4439.8 4454.8 4469.7 5089.0 5099.7 5121.4 5131.6 5139.6 4436.4 4465.2 4465.7 4482.7 4488.4 2574.6 2692.7 2730.6 2734.9 2737.0 4868.8 4880.6 4883.6 4887.8 4889.2 4955.4 5235.9 5238.9 5258.5 5269.3 4829.7 4855.7 4876.9 4898.0 4901.6 5122.7 5147.4 5147.6 5148.1 5158.8 4736.2 5002.7 5048.3 5066.0 5066.5 4834.3 4834.3 4834.6 4843.5 4854.9 3631.4 3636.5 3640.3 3643.4 3652.2 4869.6 5121.5 5128.5 5130.2 5130.6 4964.0 4983.1 4983.5 4994.7 4997.7 4883.9 4950.3 4959.0 4975.7 4976.9 4697.4 4884.2 4889.9 4892.3 4907.0 4797.9 4847.5 4934.6 4940.7 5005.7 4861.6 4867.8 4868.1 4872.0 4915.0 13:15:47 INFO - PROCESS | 24121 | 13:15:47 INFO - PROCESS | 24121 | [#5] yui.html Cycles:180 Average:16549.91 Median:3353.34 stddev:25015.25 (746.0%) stddev-sans-first:25056.45 13:15:47 INFO - PROCESS | 24121 | Values: 514.5 556.2 557.6 620.7 644.4 11867.3 12903.2 12959.0 13059.9 13071.1 11788.6 12888.5 12985.4 12999.4 13067.2 743.8 760.7 761.0 761.4 762.7 566.5 581.9 729.2 754.8 759.0 790.3 791.0 792.1 792.7 792.7 789.3 789.4 789.6 789.8 790.0 429.9 436.8 558.5 571.7 582.4 12123.6 12222.4 12262.7 12281.2 12307.9 10840.3 11239.6 11387.7 11658.8 11660.0 10604.2 10668.8 10671.9 10918.4 11082.9 3738.5 3757.2 3768.6 3772.3 3825.2 66303.0 66350.7 66366.3 66780.0 67208.7 8856.8 8926.6 8949.5 9029.7 9195.4 16445.3 16521.9 16538.5 16540.5 16608.8 6049.5 6086.0 6216.4 6229.3 6238.4 3322.4 3358.8 3362.9 3388.0 3421.6 2576.2 2679.4 2679.6 2679.7 2680.4 454.8 459.2 465.2 466.0 475.5 336.6 347.9 360.7 368.2 371.4 6589.5 6680.8 6687.4 6708.3 6911.2 343.3 361.4 362.4 402.1 429.7 3026.4 3308.7 3348.9 3371.4 3393.3 98030.0 99697.5 104400.0 105248.0 105360.5 51915.7 52826.7 53187.2 66479.0 86838.6 47055.3 66502.0 78168.0 78733.6 78918.0 6201.1 6247.9 6330.0 6535.6 6618.3 45182.3 45953.0 47023.8 47780.8 47819.3 60535.0 65556.3 65589.0 65663.7 69144.0 7735.2 7857.4 7968.6 8152.4 8389.1 8265.6 8286.8 8415.7 8496.5 8598.3 7656.3 7730.2 7805.2 7860.6 8052.7 6271.6 8687.8 9072.1 9347.8 9583.0 9328.9 9342.6 9444.8 9587.9 10131.6 8345.0 8352.7 8455.6 8464.0 8603.4 8339.4 8349.9 8534.4 8627.3 8740.5 13:15:47 INFO - PROCESS | 24121 | -------- Summary: end -------- 13:15:47 INFO - PROCESS | 24121 | 13:15:48 INFO - TEST-INFO | 24121: exit 0 13:15:48 INFO - TEST-OK | dromaeo_css | took 1121751ms 13:15:48 INFO - TEST-START | kraken 13:15:48 INFO - operating with platform_type : linux_ 13:15:48 INFO - Initialising browser for kraken test... 13:15:48 INFO - TEST-INFO | started process 24423 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2jpa1s/profile http://localhost:47475/getInfo.html) 13:15:53 INFO - PROCESS | 24423 | __metrics Screen width/height:1600/1200 13:15:53 INFO - PROCESS | 24423 | colorDepth:24 13:15:53 INFO - PROCESS | 24423 | Browser inner width/height: 1024/697 13:15:53 INFO - PROCESS | 24423 | __metrics 13:15:54 INFO - PROCESS | 24423 | 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" 13:15:54 INFO - PROCESS | 24423 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:15:54 INFO - PROCESS | 24423 | console.error: PushService: 13:15:54 INFO - PROCESS | 24423 | stateChangeProcessEnqueue: Error transitioning state 13:15:54 INFO - PROCESS | 24423 | UnknownError 13:15:55 INFO - TEST-INFO | 24423: exit 0 13:15:55 INFO - Browser initialized. 13:15:55 INFO - Running cycle 1/1 for kraken test... 13:15:55 INFO - TEST-INFO | started process 24503 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2jpa1s/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:16:07 INFO - PROCESS | 24503 | RSS: Main: 174465024 13:16:07 INFO - PROCESS | 24503 | 13:16:10 INFO - PROCESS | 24503 | Cycle 1(1): loaded http://localhost:47475/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47475/tests/kraken/driver.html?testName=audio-beat-detection) 13:16:11 INFO - PROCESS | 24503 | RSS: Main: 237801472 13:16:11 INFO - PROCESS | 24503 | 13:16:14 INFO - PROCESS | 24503 | Cycle 1(1): loaded http://localhost:47475/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47475/tests/kraken/driver.html?testName=audio-dft) 13:16:14 INFO - PROCESS | 24503 | RSS: Main: 217432064 13:16:14 INFO - PROCESS | 24503 | 13:16:18 INFO - PROCESS | 24503 | Cycle 1(1): loaded http://localhost:47475/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47475/tests/kraken/driver.html?testName=audio-fft) 13:16:18 INFO - PROCESS | 24503 | RSS: Main: 299646976 13:16:18 INFO - PROCESS | 24503 | 13:16:21 INFO - PROCESS | 24503 | Cycle 1(1): loaded http://localhost:47475/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47475/tests/kraken/driver.html?testName=audio-oscillator) 13:16:21 INFO - PROCESS | 24503 | RSS: Main: 209461248 13:16:21 INFO - PROCESS | 24503 | 13:16:23 INFO - PROCESS | 24503 | Cycle 1(1): loaded http://localhost:47475/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47475/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:16:24 INFO - PROCESS | 24503 | RSS: Main: 212811776 13:16:24 INFO - PROCESS | 24503 | 13:16:27 INFO - PROCESS | 24503 | Cycle 1(1): loaded http://localhost:47475/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47475/tests/kraken/driver.html?testName=imaging-darkroom) 13:16:28 INFO - PROCESS | 24503 | RSS: Main: 249671680 13:16:28 INFO - PROCESS | 24503 | 13:16:31 INFO - PROCESS | 24503 | Cycle 1(1): loaded http://localhost:47475/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47475/tests/kraken/driver.html?testName=imaging-desaturate) 13:16:32 INFO - PROCESS | 24503 | RSS: Main: 244002816 13:16:32 INFO - PROCESS | 24503 | 13:16:35 INFO - PROCESS | 24503 | Cycle 1(1): loaded http://localhost:47475/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47475/tests/kraken/driver.html?testName=json-parse-financial) 13:16:35 INFO - PROCESS | 24503 | RSS: Main: 246923264 13:16:35 INFO - PROCESS | 24503 | 13:16:37 INFO - PROCESS | 24503 | Cycle 1(1): loaded http://localhost:47475/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47475/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:16:38 INFO - PROCESS | 24503 | RSS: Main: 210489344 13:16:38 INFO - PROCESS | 24503 | 13:16:40 INFO - PROCESS | 24503 | Cycle 1(1): loaded http://localhost:47475/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47475/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:16:40 INFO - PROCESS | 24503 | RSS: Main: 217899008 13:16:40 INFO - PROCESS | 24503 | 13:16:42 INFO - PROCESS | 24503 | Cycle 1(1): loaded http://localhost:47475/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47475/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:16:43 INFO - PROCESS | 24503 | RSS: Main: 222199808 13:16:43 INFO - PROCESS | 24503 | 13:16:46 INFO - PROCESS | 24503 | Cycle 1(1): loaded http://localhost:47475/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47475/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:16:46 INFO - PROCESS | 24503 | RSS: Main: 223653888 13:16:46 INFO - PROCESS | 24503 | 13:16:49 INFO - PROCESS | 24503 | Cycle 1(1): loaded http://localhost:47475/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47475/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:16:50 INFO - PROCESS | 24503 | RSS: Main: 221540352 13:16:50 INFO - PROCESS | 24503 | 13:16:52 INFO - PROCESS | 24503 | Cycle 1(1): loaded http://localhost:47475/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47475/tests/kraken/driver.html?testName=ai-astar) 13:16:52 INFO - PROCESS | 24503 | RSS: Main: 272785408 13:16:52 INFO - PROCESS | 24503 | 13:16:52 INFO - PROCESS | 24503 | __start_tp_report 13:16:52 INFO - PROCESS | 24503 | _x_x_mozilla_page_load 13:16:52 INFO - PROCESS | 24503 | _x_x_mozilla_page_load_details 13:16:52 INFO - PROCESS | 24503 | |i|pagename|runs| 13:16:52 INFO - PROCESS | 24503 | |0;ai-astar;112;115;115;116;114;114;117;115;114;115 13:16:52 INFO - PROCESS | 24503 | |1;audio-beat-detection;159;124;122;163;123;123;129;123;165;123 13:16:52 INFO - PROCESS | 24503 | |2;audio-dft;162;163;161;164;162;164;162;163;163;161 13:16:52 INFO - PROCESS | 24503 | |3;audio-fft;77;76;76;99;77;78;79;78;107;77 13:16:52 INFO - PROCESS | 24503 | |4;audio-oscillator;82;83;120;83;83;84;114;82;83;108 13:16:52 INFO - PROCESS | 24503 | |5;imaging-gaussian-blur;117;117;118;118;117;117;118;118;118;117 13:16:52 INFO - PROCESS | 24503 | |6;imaging-darkroom;145;147;146;146;146;146;146;146;145;146 13:16:52 INFO - PROCESS | 24503 | |7;imaging-desaturate;95;95;94;95;95;95;95;95;95;95 13:16:52 INFO - PROCESS | 24503 | |8;json-parse-financial;64;64;63;63;65;65;75;64;65;64 13:16:52 INFO - PROCESS | 24503 | |9;json-stringify-tinderbox;65;66;65;65;66;65;65;64;65;66 13:16:52 INFO - PROCESS | 24503 | |10;stanford-crypto-aes;82;83;81;82;81;82;82;81;81;81 13:16:52 INFO - PROCESS | 24503 | |11;stanford-crypto-ccm;137;138;132;134;130;130;133;144;173;134 13:16:52 INFO - PROCESS | 24503 | |12;stanford-crypto-pbkdf2;172;174;180;172;176;179;187;173;175;173 13:16:52 INFO - PROCESS | 24503 | |13;stanford-crypto-sha256-iterative;70;70;70;69;69;69;68;68;67;67 13:16:52 INFO - PROCESS | 24503 | __end_tp_report 13:16:52 INFO - PROCESS | 24503 | __start_cc_report 13:16:52 INFO - PROCESS | 24503 | _x_x_mozilla_cycle_collect,1955 13:16:52 INFO - PROCESS | 24503 | __end_cc_report 13:16:52 INFO - PROCESS | 24503 | __startTimestamp1474229812506__endTimestamp 13:16:52 INFO - PROCESS | 24503 | 13:16:52 INFO - PROCESS | 24503 | ------- Summary: start ------- 13:16:52 INFO - PROCESS | 24503 | Number of tests: 14 13:16:52 INFO - PROCESS | 24503 | 13:16:52 INFO - PROCESS | 24503 | [#0] ai-astar Cycles:10 Average:114.70 Median:115.00 stddev:1.34 (1.2%) stddev-sans-first:1.00 13:16:52 INFO - PROCESS | 24503 | Values: 112.0 115.0 115.0 116.0 114.0 114.0 117.0 115.0 114.0 115.0 13:16:52 INFO - PROCESS | 24503 | 13:16:52 INFO - PROCESS | 24503 | [#1] audio-beat-detection Cycles:10 Average:135.40 Median:126.50 stddev:18.74 (14.8%) stddev-sans-first:17.82 13:16:52 INFO - PROCESS | 24503 | Values: 159.0 124.0 122.0 163.0 123.0 123.0 129.0 123.0 165.0 123.0 13:16:52 INFO - PROCESS | 24503 | 13:16:52 INFO - PROCESS | 24503 | [#2] audio-dft Cycles:10 Average:162.50 Median:163.00 stddev:1.08 (0.7%) stddev-sans-first:1.13 13:16:52 INFO - PROCESS | 24503 | Values: 162.0 163.0 161.0 164.0 162.0 164.0 162.0 163.0 163.0 161.0 13:16:52 INFO - PROCESS | 24503 | 13:16:52 INFO - PROCESS | 24503 | [#3] audio-fft Cycles:10 Average:82.40 Median:77.50 stddev:11.06 (14.3%) stddev-sans-first:11.55 13:16:52 INFO - PROCESS | 24503 | Values: 77.0 76.0 76.0 99.0 77.0 78.0 79.0 78.0 107.0 77.0 13:16:52 INFO - PROCESS | 24503 | 13:16:52 INFO - PROCESS | 24503 | [#4] audio-oscillator Cycles:10 Average:92.20 Median:83.00 stddev:15.32 (18.5%) stddev-sans-first:15.80 13:16:52 INFO - PROCESS | 24503 | Values: 82.0 83.0 120.0 83.0 83.0 84.0 114.0 82.0 83.0 108.0 13:16:52 INFO - PROCESS | 24503 | 13:16:52 INFO - PROCESS | 24503 | [#5] imaging-gaussian-blur Cycles:10 Average:117.50 Median:118.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 13:16:52 INFO - PROCESS | 24503 | Values: 117.0 117.0 118.0 118.0 117.0 117.0 118.0 118.0 118.0 117.0 13:16:52 INFO - PROCESS | 24503 | 13:16:52 INFO - PROCESS | 24503 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.50 13:16:52 INFO - PROCESS | 24503 | Values: 145.0 147.0 146.0 146.0 146.0 146.0 146.0 146.0 145.0 146.0 13:16:52 INFO - PROCESS | 24503 | 13:16:52 INFO - PROCESS | 24503 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 13:16:52 INFO - PROCESS | 24503 | Values: 95.0 95.0 94.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 13:16:52 INFO - PROCESS | 24503 | 13:16:52 INFO - PROCESS | 24503 | [#8] json-parse-financial Cycles:10 Average:65.20 Median:64.50 stddev:3.52 (5.5%) stddev-sans-first:3.71 13:16:52 INFO - PROCESS | 24503 | Values: 64.0 64.0 63.0 63.0 65.0 65.0 75.0 64.0 65.0 64.0 13:16:52 INFO - PROCESS | 24503 | 13:16:52 INFO - PROCESS | 24503 | [#9] json-stringify-tinderbox Cycles:10 Average:65.20 Median:65.00 stddev:0.63 (1.0%) stddev-sans-first:0.67 13:16:52 INFO - PROCESS | 24503 | Values: 65.0 66.0 65.0 65.0 66.0 65.0 65.0 64.0 65.0 66.0 13:16:52 INFO - PROCESS | 24503 | 13:16:52 INFO - PROCESS | 24503 | [#10] stanford-crypto-aes Cycles:10 Average:81.60 Median:82.00 stddev:0.70 (0.9%) stddev-sans-first:0.73 13:16:52 INFO - PROCESS | 24503 | Values: 82.0 83.0 81.0 82.0 81.0 82.0 82.0 81.0 81.0 81.0 13:16:52 INFO - PROCESS | 24503 | 13:16:52 INFO - PROCESS | 24503 | [#11] stanford-crypto-ccm Cycles:10 Average:138.50 Median:135.50 stddev:12.83 (9.5%) stddev-sans-first:13.59 13:16:52 INFO - PROCESS | 24503 | Values: 137.0 138.0 132.0 134.0 130.0 130.0 133.0 144.0 173.0 134.0 13:16:52 INFO - PROCESS | 24503 | 13:16:52 INFO - PROCESS | 24503 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.10 Median:175.50 stddev:4.72 (2.7%) stddev-sans-first:4.77 13:16:52 INFO - PROCESS | 24503 | Values: 172.0 174.0 180.0 172.0 176.0 179.0 187.0 173.0 175.0 173.0 13:16:52 INFO - PROCESS | 24503 | 13:16:52 INFO - PROCESS | 24503 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.70 Median:69.00 stddev:1.16 (1.7%) stddev-sans-first:1.13 13:16:52 INFO - PROCESS | 24503 | Values: 70.0 70.0 70.0 69.0 69.0 69.0 68.0 68.0 67.0 67.0 13:16:52 INFO - PROCESS | 24503 | -------- Summary: end -------- 13:16:52 INFO - PROCESS | 24503 | 13:16:53 INFO - TEST-INFO | 24503: exit 0 13:16:53 INFO - TEST-OK | kraken | took 65256ms 13:16:53 INFO - SUITE-END | took 1187s 13:16:54 INFO - Completed test suite (00:19:47) 13:16:54 INFO - javascript benchmark 13:16:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2540.072873401208, 2567.1528395629443, 2591.9092831750886, 2603.752643498868, 2619.0702300683256, 4438.80025398857, 4495.145922274765, 4605.009922866583, 4617.8614641560725, 4623.375844261028, 4509.301058335955, 4550.294704320836, 4574.199515084863, 4599.563041511059, 4619.514950930155, 4167.0, 4170.207660544502, 4195.370694395834, 4217.240896638604, 4284.507281662602, 4243.915121697564, 4310.181065597542, 4357.542458041908, 4362.302031674933, 4409.955900440987, 4138.503379594438, 4190.559991200941, 4239.554846741109, 4278.935815962763, 4331.631811296043, 4218.303979843322, 4231.048014196812, 4233.534311225762, 4246.7876606169875, 4256.106217694288, 4168.103857670585, 4205.07488352565, 4211.768352740598, 4215.220184265921, 4221.366794980747, 4550.7724613769515, 4576.839810606612, 4635.327877457784, 4635.327877457784, 4646.210144275447, 4889.828855990058, 4891.095147397743, 4891.8287859925085, 4896.657233993622, 4957.082939656175, 4751.501092385319, 4806.255030470283, 4916.655834091615, 4935.777889994959, 4939.703617782944, 4472.396226509444, 4526.366308716782, 4543.886402839956, 4551.977240113812, 4554.24854898938, 5001.149804533238, 5039.193729003342, 5086.287919691246, 5087.033463641897, 5087.440381558025, 4761.2382018876815, 4812.639052071108, 4817.253325734517, 4860.198067318924, 4874.658773885794, 4898.436679781799, 4902.166631672624, 4902.338184345139, 4907.312976183337, 4969.254611808257, 4670.813167473331, 4725.716457012591, 4761.40482439695, 4810.59109975649, 4830.468648448667, 4871.3667223260745, 4887.780049897763, 4907.067657145131, 4911.066897289504, 4936.037472692862, 4153.875383738493, 4154.480689913761, 4161.126163505637, 4204.978975105105, 4215.262329092397, 3645.423161314926, 3657.524521812147, 3694.0, 3697.722670799647, 3727.552693676776, 4137.275976704064, 4229.640480559187, 4230.2174097792, 4253.021804984871, 4291.871243862689, 4960.528749768766, 4962.230854217602, 4966.478519755446, 5009.824656137018, 5028.321176641107, 4257.276263035292, 4288.91422171561, 4294.205571969195, 4335.067960388468, 4350.1734670412525, 4291.785410729451, 4333.068390296102, 4382.276924307517, 4423.800928958204, 4436.112777444524, 4438.755868427203, 4657.790399431966, 4660.766961651836, 4714.787834547385, 4719.787609557509, 4900.044491324157, 4930.728809915489, 4931.161702510653, 4934.062528119646, 4993.101241776515, 4554.089182163514, 4590.563896429834, 4655.18534256497, 4672.32251323563, 4728.290756386569, 4750.762461876823, 4754.976225118852, 4792.568668819823, 4803.519648035169, 4854.708717476963, 4847.975760121318, 4852.369192005017, 4859.732714700725, 4860.052289803454, 4918.2622606607865, 4656.045510670372, 4722.693024953367, 4723.527647235249, 4728.763561821964, 4784.162771515041, 4690.6247500199215, 4748.192807222849, 4770.284457331428, 4782.2826576012485, 4830.855074347775, 4659.58063774262, 4692.296155576691, 4724.456687480846, 4745.430548334222, 4779.330893674819, 4785.425748910153, 4851.267718107322, 4853.854384368475, 4854.611631069577, 4906.8773280668265, 4562.0, 4600.332951722048, 4672.088942656285, 4676.7661616919695, 4716.33971244033], "value": 4505.709881966227, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [555.3419198250076, 558.4806130298825, 570.0195663458851, 578.3897987623056, 582.7465052702073, 27377.178684639443, 37888.81055594722, 38762.224755504874, 38794.22411551774, 42536.14927701452, 39496.80251598745, 39987.800060999725, 40006.199876002465, 40033.79983100088, 40047.199056018864, 7052.682629281669, 7624.428167887403, 7786.80532986677, 7787.610619469032, 7788.337991270749, 7072.646367681596, 9824.115829575338, 10124.29129960903, 10128.189744820433, 10467.057964783167, 9763.414195148278, 9808.0, 9829.606815727362, 9840.852387214198, 9849.261305402088, 9950.800983980316, 10013.949930250303, 10018.699439016767, 10019.448930308867, 10031.398116113056, 9900.603975840957, 9961.302708810386, 9965.601375944954, 9969.202463802878, 9974.40153590787, 37169.44245836315, 37470.06324841874, 37510.24979500422, 37539.0, 37624.0, 2570.961435578468, 2613.7908967282574, 2695.946081078387, 3070.6008218931397, 3083.753299736016, 27959.440811183867, 27980.0, 28277.57583636247, 28451.288717782016, 29035.41929161426, 3248.361834745077, 3251.0, 3251.138448311199, 3305.8677652893857, 3306.933861322784, 248826.0, 250101.0, 250146.0, 250909.0, 251075.0, 44897.32654010192, 44944.77527612406, 44957.775211123735, 45009.0, 45034.77482612566, 31464.37071258562, 32451.675483245337, 52407.21389179165, 52477.7376113117, 52598.47401526012, 12363.814542781865, 12701.745965080647, 12708.682282942815, 12718.491260349483, 12784.872151278554, 8643.956780216058, 8886.688965886164, 9666.854997175047, 9916.206703463706, 10016.949915250523, 16800.41198558069, 17026.829731702524, 17051.14744262782, 17053.7441938371, 17117.315307387813, 6633.966830165915, 6650.467962562983, 6664.900026499607, 6941.305869413019, 6970.233274339818, 758.9582572958541, 877.899041610223, 886.9334799890034, 915.7527467583715, 924.4360939826701, 26131.216063518124, 27382.589261161098, 27503.31241718973, 27704.0, 27956.30109247285, 926.8424367857345, 930.7905721212672, 953.9570719317647, 1004.1715584642699, 1008.6318493749728, 14039.087459315111, 14733.429754126933, 14811.629709257355, 14821.0, 14972.176530290939, 352139.0, 410423.0, 415589.0, 420115.0, 660600.0, 48957.0, 49541.50458495441, 49553.0, 49630.25554616684, 49679.25481117786, 1049.9580016799243, 1086.5327908999047, 1093.4040947683372, 1179.2217136689744, 1180.3154170581042, 22013.66979495341, 26565.734342656327, 28276.717232828232, 29945.368253826106, 30271.697283026886, 44825.0, 45482.772586138184, 45502.08995819999, 45563.54436455726, 45656.08687826291, 332450.0, 335659.0, 335801.0, 335836.0, 336056.0, 21656.0, 22204.0, 23991.640125397786, 24407.75592244055, 25055.123070693167, 39146.608533914296, 39335.41678052751, 39376.606233937295, 39603.0, 39858.40212396874, 33615.0, 33956.66043339535, 34351.4847277286, 34359.0, 34886.476702849985, 33690.663093368756, 33864.0, 33976.0, 34135.829320853234, 34475.310493790486, 33123.0, 33798.0, 34065.0, 34334.48498272578, 34414.655853441145, 28660.283492912844, 30794.692053079183, 31030.22424439407, 31222.843885780425, 31278.0, 23874.40313992164, 23895.880520597286, 24006.51986960286, 24032.279031629078, 24134.637980429958], "value": 17309.822135978677, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [345.3645292661502, 368.44916849310266, 369.5177792980161, 375.94172903200007, 388.10735308789793, 12022.639320820379, 15586.298616562253, 15662.765058524132, 16855.0, 17876.19557119933, 16872.409465668705, 16886.155692215372, 16938.40715574955, 16986.235619397125, 17110.486685399428, 4974.801007959677, 4979.576735977446, 5084.262781896623, 5089.524038028967, 5126.333576635051, 3601.7118630509494, 3782.924341513168, 4944.134776414131, 5014.949850501503, 5018.096742586333, 3808.485854409661, 3812.809359532026, 3815.866444674437, 5564.4991950724425, 5856.619319744224, 5140.1518749406305, 5423.593230507728, 6016.278046634388, 6042.969785151068, 6047.546434017467, 5350.571954243651, 5354.41101478837, 5360.061989151922, 5361.571074314045, 5361.812336568201, 12305.938470307681, 12330.876691233063, 12470.314132722697, 12559.811602825874, 12653.74692506154, 1734.167599552222, 1865.822746839048, 1869.1308541528256, 1888.0654076232354, 2090.8850013249266, 9157.496337701425, 9578.61685532578, 9878.703638890845, 11474.254173478697, 13386.263755493444, 1635.8118816336155, 1655.337864854056, 1657.3287818433553, 1704.9062301573408, 1710.1791140252624, 64431.67784161049, 66619.3338066618, 66863.0, 70360.29639703588, 71262.64368678123, 9783.706488805346, 9832.705018849447, 9890.20878329732, 10107.494625268708, 10214.744631384276, 13735.703431176446, 13747.175169489663, 13755.931220344035, 13758.587242382744, 13769.862301376857, 6480.157579514727, 6585.6707164641575, 6720.361565651256, 6797.198742333286, 6832.385085342343, 6135.0, 6147.477464415575, 6329.3354197809485, 6334.3032266450655, 6351.682415879188, 4285.978570107191, 4293.44185255921, 4300.548442413564, 4310.612044915973, 4395.7142785718825, 1609.3884323957056, 1813.5738101546126, 1834.367143335547, 1835.109971663742, 1851.4723303858605, 728.8578727148155, 823.7487566292339, 828.1759649149135, 860.5697151424288, 915.0392088307251, 16079.839201608069, 16369.363063693536, 16641.91679041597, 17153.142342882806, 17179.226934787963, 827.8923739913772, 837.8994520657438, 840.2437805974549, 849.5327569836566, 850.056437354536, 8904.599293031699, 9216.0, 9569.282303827102, 9594.520273986273, 9773.65792197284, 358956.0, 365324.0, 365542.0, 372930.0, 373087.0, 73335.99999999894, 74550.6272468645, 74673.62663186649, 76525.0, 76613.63704540629, 842.4566154830101, 871.5293741379711, 871.655696000089, 871.9084496127942, 871.9128087191231, 13992.440302388197, 14648.853511465175, 14941.551753447415, 14967.557461784068, 15106.320215590105, 35617.0, 36085.63914360823, 36217.0, 36720.63279367279, 37253.25493490169, 49750.25374619311, 49805.78204596201, 49934.0, 50248.74875625598, 50702.0, 6967.895481567679, 7101.964490177516, 7200.603966781878, 7206.207317195, 7323.194448610755, 9034.2320902724, 10223.7444063899, 10327.32872363294, 10412.219083568914, 10812.891871081189, 8749.47503149813, 9022.413543119676, 9063.864042039242, 9259.120383563552, 9444.386114902509, 8939.642414303355, 9064.637414503346, 9093.136152065543, 9100.817983640423, 9397.107274808883, 9179.724608261762, 9226.584803683716, 9264.768380790534, 9356.906430935876, 9450.338476306593, 7020.7542736004, 7121.67952442131, 7314.597697126709, 7339.853202935805, 7522.623868806647, 7254.347108760237, 8832.980993648003, 8989.640414383613, 9172.587233574372, 9354.438733676001], "value": 7740.648181033264, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1224.589762429586, 1353.1678018018922, 1411.5906387147727, 1468.9706205875882, 1701.8808683392167, 6599.604023758572, 6921.896171557424, 7541.924580754191, 7811.023622047244, 7811.453198276123, 7864.96067519663, 7931.801704957379, 7937.761867143995, 7946.086200086992, 7953.125156232824, 2157.870527768335, 2178.5425060737266, 2182.6289530779804, 2183.4868805830615, 2184.3446965910243, 2126.0964090261577, 2167.8916054197307, 2184.6176919039185, 2196.769339219383, 2196.8352373571965, 2183.170395249803, 2192.572448372568, 2207.624703800358, 2207.7350717913873, 2217.5475063833214, 2145.5065334972965, 2149.3659370485743, 2152.0961196297435, 2152.838537109715, 2153.935381938544, 2080.583883223346, 2142.592907347599, 2143.95712085759, 2145.249162793026, 2148.7421509418814, 7719.9614001930195, 7785.338246249075, 7819.8436031279625, 7834.8433031339055, 7867.881981770278, 4351.151525452538, 4516.683832131752, 4524.88687782808, 4570.794314255833, 4575.679702420832, 2733.7949653776045, 2837.361593641414, 2878.6977367376335, 2885.6104425902445, 2892.652881654194, 999.2505620784411, 1000.8899021107673, 1025.4052649463292, 1027.8869324374314, 1030.7835354575548, 10621.628243011535, 10702.143828493701, 10707.357558546511, 10718.142548596172, 10733.034026937536, 4014.4580481634607, 4166.270902592034, 4299.656027517791, 4301.16127355169, 4310.43964284641, 8354.540500272422, 8355.415120941598, 8384.161583841567, 8421.663133474714, 8430.494170349799, 4428.645708343389, 4438.889027774267, 4446.4886538047895, 4457.687961842705, 4459.063596644741, 3325.833708314575, 3504.7196224302506, 3676.44853272006, 4125.092479654471, 4128.256913755493, 3441.0, 3502.2470168913806, 3528.8588456461453, 3593.4250519916686, 3617.4573813927595, 1066.440118937564, 1079.3685693869174, 1081.626838740649, 1087.429099722636, 1094.6059418609295, 717.5802155738951, 726.9091363579553, 743.985120297591, 753.6194221917897, 778.0624347661168, 4781.378420805273, 5029.924551131736, 5041.899162016739, 5265.736713164403, 5282.3624676350455, 868.3443999780126, 869.7129947117436, 875.5403413207989, 920.894097178833, 938.3712912348743, 3151.16494129056, 3223.903282901517, 3237.6924192201704, 3289.4243507386113, 3427.537282466885, 9578.856317155249, 9720.611175552898, 9821.656242031637, 9846.753831154278, 9912.0, 9754.122129008423, 9760.511974401252, 9763.560639771227, 9787.46168960714, 9796.118349348593, 7284.453665975073, 7376.926230737731, 7403.14863790671, 7404.111506619241, 7404.37062849653, 4936.605071594336, 5021.874453138701, 5032.924506132485, 5089.618278629044, 5178.792848286026, 8665.306775458075, 8784.165504277084, 8943.463392196489, 8943.776405589651, 8946.37375383717, 7841.764747057369, 8868.467891926504, 8957.939673875175, 9007.819843603222, 9073.637054517745, 5535.529479994246, 5926.259217597801, 6070.301915279622, 6098.908516372169, 6202.224721909762, 7179.282071792779, 7232.927670723226, 7293.270672932665, 7295.452841037049, 7302.233265507258, 6766.15423072116, 6814.1482314710665, 6907.067545881242, 6971.895421568782, 7011.3339232772805, 6458.451031662361, 6518.837029074311, 6529.575577587441, 6638.668066596748, 6663.666816659244, 5671.858203544779, 5828.067509198508, 5874.676892770939, 5958.821235362946, 6126.417990290915, 4297.914041719086, 4317.978410107929, 4407.889802754828, 4435.401220835146, 4608.032313214262, 4219.662427005894, 4271.530131685574, 4376.824927002885, 4420.292753159479, 4436.245838207448], "value": 4037.8259170647525, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [292.10178700495976, 292.9135904908052, 294.2951630844127, 297.7528651219488, 299.6479137014008, 4632.281996290576, 4738.644601654881, 4766.976165119174, 4783.497732738056, 4803.879903002422, 4682.976585117078, 4714.222153344703, 4738.881527961803, 4743.288506723993, 4761.785719642616, 3530.6469353064745, 3566.7681600695873, 3639.5996440391536, 3669.2478042001458, 3753.8123093845334, 3665.047087757189, 3673.687736542397, 3674.7427680062406, 3677.0, 3691.427828686558, 3638.6725194732467, 3645.325614761278, 3646.325429795483, 3647.854085836563, 3649.8540058397634, 3461.0, 3551.733619978512, 3574.767640103411, 3645.5989841117457, 3677.411614141751, 3202.558848518153, 3205.6473787883547, 3207.262329664167, 3211.983940080285, 3214.4374734421617, 4588.931166032478, 4604.424446944132, 4620.330052142454, 4626.930596041062, 4631.536846315375, 3951.644352008333, 4158.91682166358, 4159.0, 4173.937390939138, 4180.623743863037, 4876.292937524075, 4921.975390123062, 4935.506449355072, 4936.654434189608, 4944.975275123601, 3497.318022985519, 3498.982505087484, 3502.2120022994877, 3503.754737168399, 3508.5088087667746, 5101.15830887903, 5103.081445339837, 5124.615653825978, 5129.204973229118, 5135.35808023997, 3428.674275943781, 3550.1657110578994, 3565.35823551758, 3584.1039740064984, 3603.801790901473, 3602.7117830573493, 3627.4558816177255, 3630.146915474861, 3636.399994001013, 3636.56361236648, 4149.522804877417, 4150.460440142762, 4159.230542349675, 4163.188178305201, 4164.66682665386, 4817.759112044454, 4944.777485013183, 4950.8762280943265, 4958.281049247839, 4965.4041515018425, 1234.7592219517107, 1261.744564158668, 1270.4854533913779, 1270.9364531773374, 1271.4850485553366, 793.1949071692237, 799.9720009799629, 810.4165001199127, 813.1665043330634, 813.6175997281268, 4267.957320426818, 4300.591443812833, 4439.778011099432, 4454.841741147325, 4469.687121901436, 5088.974555127201, 5099.6685215460875, 5121.385433747974, 5131.5625078087605, 5139.563137133311, 4436.401085853433, 4465.173942820588, 4465.709728867613, 4482.686211965131, 4488.371627972087, 2574.575195092826, 2692.72095754515, 2730.5904114382606, 2734.892368590724, 2736.9609640817716, 4868.829590964299, 4880.633952453581, 4883.609311255091, 4887.800489779614, 4889.242167464049, 4955.355803745562, 5235.947640523622, 5238.895222095537, 5258.50044245796, 5269.314989051399, 4829.7058897023635, 4855.660103792771, 4876.902461950741, 4897.95102048975, 4901.583365413909, 5122.718250496258, 5147.356580427447, 5147.58819294448, 5148.1248187808915, 5158.819441319536, 4736.242201247784, 5002.699838009731, 5048.268001139888, 5065.986802639413, 5066.544011039025, 4834.250691142878, 4834.274858771072, 4834.5890599299455, 4843.491433399583, 4854.902901942012, 3631.3826649469124, 3636.5272514572935, 3640.308341415123, 3643.417053271464, 3652.160003199226, 4869.561739443467, 5121.462246464217, 5128.512791284822, 5130.179171332718, 5130.640855140105, 4964.0, 4983.103041452573, 4983.4518202996915, 4994.6503744738975, 4997.650634328784, 4883.924332158747, 4950.282209079735, 4959.0, 4975.701457912537, 4976.8755781105765, 4697.436307643105, 4884.169691152441, 4889.926651100308, 4892.290617860462, 4906.95093049065, 4797.856064318076, 4847.491013443679, 4934.605231581537, 4940.728259945594, 5005.724685142207, 4861.562459378673, 4867.829625963074, 4868.101440438303, 4871.97688485422, 4915.0], "value": 3725.9944352091693, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [514.4700958013248, 556.1657513729406, 557.5623135838367, 620.7020630097552, 644.3878315600181, 11867.347295898722, 12903.16129451585, 12959.028072894524, 13059.934700326497, 13071.08502404832, 11788.646340609796, 12888.484460621587, 12985.415656295465, 12999.415026323815, 13067.150635208705, 743.7768669399172, 760.7451503746224, 760.9543427394347, 761.3984951888002, 762.6834863531627, 566.4703502225411, 581.9185314056035, 729.2088084428401, 754.8226166850785, 758.9924100758977, 790.2768966395741, 791.0151860933146, 792.1247022040649, 792.6868886789715, 792.6948124971899, 789.3290702902544, 789.3921680306161, 789.6051974012994, 789.834134831686, 789.9526028438312, 429.91616634756235, 436.78815774349414, 558.4694540186839, 571.6855729348883, 582.4117641182394, 12123.575674851427, 12222.388880556025, 12262.693432664166, 12281.201721888048, 12307.876921230763, 10840.295380800222, 11239.550417983271, 11387.658370248906, 11658.766824663544, 11660.0, 10604.204684648605, 10668.839967400418, 10671.893281067245, 10918.399488028155, 11082.889171108267, 3738.495303134069, 3757.210985693008, 3768.6419790119894, 3772.3398405278967, 3825.196708691178, 66303.0, 66350.66824665894, 66366.3363366365, 66780.0, 67208.6639566799, 8856.822863542693, 8926.642934282556, 8949.463032218087, 9029.683961061462, 9195.356325057182, 16445.342186312653, 16521.91739041297, 16538.50384488443, 16540.503784886474, 16608.750868736977, 6049.546284028627, 6085.9695701521205, 6216.44052035319, 6229.314775374705, 6238.438540531374, 3322.368749937504, 3358.8152651604396, 3362.9327413451592, 3387.983060084733, 3421.5894092708536, 2576.214254652349, 2679.370347968226, 2679.557872950941, 2679.7454241846995, 2680.4103097318557, 454.7612503435657, 459.18494671956876, 465.2393336894202, 466.0329815632508, 475.4579779051884, 336.5876800918904, 347.93358356636827, 360.68620300338875, 368.19916681218245, 371.36311226247113, 6589.505787065989, 6680.83297917556, 6687.364700353459, 6708.3291670833505, 6911.170659520789, 343.32879221122647, 361.43796396603534, 362.42012779553033, 402.0649260399133, 429.7077986968829, 3026.4252032803734, 3308.71875890552, 3348.8827891024184, 3371.4100032494707, 3393.253484233514, 98030.01969980208, 99697.50151249343, 104400.0, 105248.0, 105360.47319763352, 51915.740421297654, 52826.73586632042, 53187.20219196715, 66478.99999999904, 86838.56580717055, 47055.29417058747, 66502.0, 78168.0, 78733.60633196797, 78917.97853354132, 6201.10084037821, 6247.9062814056915, 6330.0, 6535.640539770358, 6618.338166183536, 45182.32226516539, 45953.0, 47023.76488117674, 47780.7610961943, 47819.28271075867, 60535.0, 65556.34443655502, 65589.0, 65663.67168164128, 69144.0, 7735.18780528059, 7857.384587820956, 7968.561729104955, 8152.419551105293, 8389.11914248995, 8265.628046737791, 8286.792830179294, 8415.663373465237, 8496.490210587384, 8598.269147122564, 7656.349210317186, 7730.226977302224, 7805.180456052033, 7860.646270917708, 8052.677892884219, 6271.560990730787, 8687.82714333557, 9072.138146876036, 9347.813043739223, 9582.952085239529, 9328.860067099135, 9342.626294948397, 9444.763880903032, 9587.856182157133, 10131.594736210469, 8345.040320363236, 8352.749417517483, 8455.594242577577, 8463.957680211806, 8603.397762156588, 8339.416240863082, 8349.916500834914, 8534.402591818762, 8627.266682332072, 8740.475571465733], "value": 5720.1430590634145, "unit": "score"}], "name": "dromaeo_css", "value": 6108.672091820064, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 115.0, 115.0, 116.0, 114.0, 114.0, 117.0, 115.0, 114.0, 115.0], "value": 114.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 124.0, 122.0, 163.0, 123.0, 123.0, 129.0, 123.0, 165.0, 123.0], "value": 135.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 163.0, 161.0, 164.0, 162.0, 164.0, 162.0, 163.0, 163.0, 161.0], "value": 162.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 76.0, 99.0, 77.0, 78.0, 79.0, 78.0, 107.0, 77.0], "value": 82.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 120.0, 83.0, 83.0, 84.0, 114.0, 82.0, 83.0, 108.0], "value": 92.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 118.0, 118.0, 117.0, 117.0, 118.0, 118.0, 118.0, 117.0], "value": 117.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 147.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 145.0, 146.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 63.0, 63.0, 65.0, 65.0, 75.0, 64.0, 65.0, 64.0], "value": 65.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 65.0, 65.0, 66.0, 65.0, 65.0, 64.0, 65.0, 66.0], "value": 65.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 81.0, 82.0, 81.0, 82.0, 82.0, 81.0, 81.0, 81.0], "value": 81.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 138.0, 132.0, 134.0, 130.0, 130.0, 133.0, 144.0, 173.0, 134.0], "value": 138.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 174.0, 180.0, 172.0, 176.0, 179.0, 187.0, 173.0, 175.0, 173.0], "value": 176.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 70.0, 70.0, 69.0, 69.0, 69.0, 68.0, 68.0, 67.0, 67.0], "value": 68.7, "unit": "score"}], "name": "kraken", "value": 1540.8, "alertThreshold": 2.0}]} 13:16:54 INFO - Return code: 0 13:16:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:16:54 INFO - # TBPL SUCCESS # 13:16:54 INFO - Running post-action listener: _resource_record_post_action 13:16:54 INFO - [mozharness: 2016-09-18 20:16:54.292866Z] Finished run-tests step (success) 13:16:54 INFO - Running post-run listener: _resource_record_post_run 13:16:54 INFO - Total resource usage - Wall time: 1203s; CPU: 13.0%; Read bytes: 4096; Write bytes: 387796992; Read time: 12; Write time: 583452 13:16:54 INFO - TinderboxPrint: CPU usage
13.1% 13:16:54 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 13:16:54 INFO - TinderboxPrint: I/O write bytes / time
387,796,992 / 583,452 13:16:54 INFO - TinderboxPrint: CPU idle
8,341.9 (86.9%) 13:16:54 INFO - TinderboxPrint: CPU user
1,199.9 (12.5%) 13:16:54 INFO - TinderboxPrint: Swap in / out
0 / 0 13:16:54 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 99745792; Read time: 0; Write time: 225616 13:16:54 INFO - run-tests - Wall time: 1188s; CPU: 13.0%; Read bytes: 0; Write bytes: 288051200; Read time: 0; Write time: 357836 13:16:54 INFO - Running post-run listener: _upload_blobber_files 13:16:54 INFO - Blob upload gear active. 13:16:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:16:54 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 13:16:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 13:16:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 13:16:55 INFO - (blobuploader) - INFO - Open directory for files ... 13:16:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:16:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:16:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:16:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:16:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:16:56 INFO - (blobuploader) - INFO - Done attempting. 13:16:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:16:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:16:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:16:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:16:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:16:57 INFO - (blobuploader) - INFO - Done attempting. 13:16:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:16:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:16:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:17:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:17:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:17:03 INFO - (blobuploader) - INFO - Done attempting. 13:17:03 INFO - (blobuploader) - INFO - Iteration through files over. 13:17:03 INFO - Return code: 0 13:17:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:17:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:17:03 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/16fd9709c7b21ecadfec25d85e1c088a86eba3f1d1ff037d76eacfb2891ba72677d9c9fc5312258df4a0d292bcdacef93c6688f70d587eef7a8b85dfaf9cafcb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8bb47ce857c53b71638c6cfb7b4f905164579a3d784e4dec2deefb1dd38a9f9ca1cb9af1f6234f7fab918cca8dbf0a65bff853bf75aca7f0bf7462118ca13615", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8c501f04b21c2e00f26081c88069df8f2ac229c3578c86cd91e4180abad6229e83c480ece3b30136282a091ca5f6d85374d7b82ccf01c4afcb707d310b5a923d"} 13:17:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:17:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:17:03 INFO - Contents: 13:17:03 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/16fd9709c7b21ecadfec25d85e1c088a86eba3f1d1ff037d76eacfb2891ba72677d9c9fc5312258df4a0d292bcdacef93c6688f70d587eef7a8b85dfaf9cafcb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8bb47ce857c53b71638c6cfb7b4f905164579a3d784e4dec2deefb1dd38a9f9ca1cb9af1f6234f7fab918cca8dbf0a65bff853bf75aca7f0bf7462118ca13615", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8c501f04b21c2e00f26081c88069df8f2ac229c3578c86cd91e4180abad6229e83c480ece3b30136282a091ca5f6d85374d7b82ccf01c4afcb707d310b5a923d"} 13:17:03 INFO - Running post-run listener: copy_logs_to_upload_dir 13:17:03 INFO - Copying logs to upload dir... 13:17:03 INFO - mkdir: /builds/slave/test/build/upload/logs 13:17:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1251.931568 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2016-09-18 13:17:02.790331) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-18 13:17:02.798116) ========= 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=5cf47e208febbcd81da23b2e0000026f-1474204125.502405-684267107 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/16fd9709c7b21ecadfec25d85e1c088a86eba3f1d1ff037d76eacfb2891ba72677d9c9fc5312258df4a0d292bcdacef93c6688f70d587eef7a8b85dfaf9cafcb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8bb47ce857c53b71638c6cfb7b4f905164579a3d784e4dec2deefb1dd38a9f9ca1cb9af1f6234f7fab918cca8dbf0a65bff853bf75aca7f0bf7462118ca13615", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8c501f04b21c2e00f26081c88069df8f2ac229c3578c86cd91e4180abad6229e83c480ece3b30136282a091ca5f6d85374d7b82ccf01c4afcb707d310b5a923d"} build_url:https://queue.taskcluster.net/v1/task/TRmaxGIpRxW8NsN-NXJeEw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011224 build_url: 'https://queue.taskcluster.net/v1/task/TRmaxGIpRxW8NsN-NXJeEw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/16fd9709c7b21ecadfec25d85e1c088a86eba3f1d1ff037d76eacfb2891ba72677d9c9fc5312258df4a0d292bcdacef93c6688f70d587eef7a8b85dfaf9cafcb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8bb47ce857c53b71638c6cfb7b4f905164579a3d784e4dec2deefb1dd38a9f9ca1cb9af1f6234f7fab918cca8dbf0a65bff853bf75aca7f0bf7462118ca13615", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8c501f04b21c2e00f26081c88069df8f2ac229c3578c86cd91e4180abad6229e83c480ece3b30136282a091ca5f6d85374d7b82ccf01c4afcb707d310b5a923d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-18 13:17:02.826284) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-18 13:17:02.826642) ========= 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=5cf47e208febbcd81da23b2e0000026f-1474204125.502405-684267107 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004473 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-18 13:17:02.909381) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-18 13:17:02.912257) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-18 13:17:02.912544) ========= ========= Total master_lag: 0.39 =========