builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-034 starttime: 1474372252.51 results: success (0) buildid: 20160920003006 builduid: 518bc64353e342e8874b0c38fbd3bb6a revision: 8a1efb73e7429df1dd35a4588c038b079bdf6018 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-20 04:50:52.510941) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-20 04:50:52.511400) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-20 04:50:52.511722) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-20 04:50:52.524064) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 04:50:52.524354) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1474372251.815846-967121185 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-20 04:50:52-- 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% 218M=0s 2016-09-20 04:50:52 (218 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.124882 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 04:50:52.661521) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 04:50:52.661862) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1474372251.815846-967121185 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016547 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 04:50:52.711624) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 04:50:52.711988) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 8a1efb73e7429df1dd35a4588c038b079bdf6018 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 8a1efb73e7429df1dd35a4588c038b079bdf6018 --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=d6bbd613908ccac0c6c9cb47000001f7-1474372251.815846-967121185 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-20 04:50:52,798 truncating revision to first 12 chars 2016-09-20 04:50:52,798 Setting DEBUG logging. 2016-09-20 04:50:52,798 attempt 1/10 2016-09-20 04:50:52,798 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/8a1efb73e742?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-20 04:50:53,176 unpacking tar archive at: fx-team-8a1efb73e742/testing/mozharness/ program finished with exit code 0 elapsedTime=0.723904 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 04:50:53.450510) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-20 04:50:53.450875) ========= script_repo_revision: 8a1efb73e7429df1dd35a4588c038b079bdf6018 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-20 04:50:53.451280) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-20 04:50:53.451593) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-20 04:50:53.462042) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-09-20 04:50:53.462499) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] 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=d6bbd613908ccac0c6c9cb47000001f7-1474372251.815846-967121185 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:50:53 INFO - MultiFileLogger online at 20160920 04:50:53 in /builds/slave/test 04:50:53 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 04:50:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:50:53 INFO - {'append_to_log': False, 04:50:53 INFO - 'base_work_dir': '/builds/slave/test', 04:50:53 INFO - 'blob_upload_branch': 'Fx-Team', 04:50:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:50:53 INFO - 'branch': 'Fx-Team', 04:50:53 INFO - 'buildbot_json_path': 'buildprops.json', 04:50:53 INFO - 'config_files': ('talos/linux_config.py',), 04:50:53 INFO - 'default_actions': ('clobber', 04:50:53 INFO - 'read-buildbot-config', 04:50:53 INFO - 'download-and-extract', 04:50:53 INFO - 'populate-webroot', 04:50:53 INFO - 'create-virtualenv', 04:50:53 INFO - 'install', 04:50:53 INFO - 'run-tests'), 04:50:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:50:53 INFO - 'download_minidump_stackwalk': True, 04:50:53 INFO - 'download_symbols': 'ondemand', 04:50:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:50:53 INFO - 'tooltool.py': '/tools/tooltool.py', 04:50:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:50:53 INFO - '/tools/misc-python/virtualenv.py')}, 04:50:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:50:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:50:53 INFO - 'installer_path': 'installer.exe', 04:50:53 INFO - 'log_level': 'info', 04:50:53 INFO - 'log_name': 'talos', 04:50:53 INFO - 'log_to_console': True, 04:50:53 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:50:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:50:53 INFO - 'opt_config_files': (), 04:50:53 INFO - 'pip_index': False, 04:50:53 INFO - 'sps_profile': False, 04:50:53 INFO - 'sps_profile_interval': 0, 04:50:53 INFO - 'suite': 'dromaeojs', 04:50:53 INFO - 'system_bits': '32', 04:50:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:50:53 INFO - 'title': 'talos-linux64-ix-034', 04:50:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:50:53 INFO - 'use_talos_json': True, 04:50:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:50:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:50:53 INFO - 'work_dir': 'build'} 04:50:53 INFO - [mozharness: 2016-09-20 11:50:53.642870Z] Running clobber step. 04:50:53 INFO - Running pre-action listener: _resource_record_pre_action 04:50:53 INFO - Running main action method: clobber 04:50:53 INFO - rmtree: /builds/slave/test/build 04:50:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:50:54 INFO - Running post-action listener: _resource_record_post_action 04:50:54 INFO - [mozharness: 2016-09-20 11:50:54.108582Z] Finished clobber step (success) 04:50:54 INFO - [mozharness: 2016-09-20 11:50:54.108683Z] Running read-buildbot-config step. 04:50:54 INFO - Running pre-action listener: _resource_record_pre_action 04:50:54 INFO - Running main action method: read_buildbot_config 04:50:54 INFO - Using buildbot properties: 04:50:54 INFO - { 04:50:54 INFO - "project": "", 04:50:54 INFO - "product": "firefox", 04:50:54 INFO - "script_repo_revision": "production", 04:50:54 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 04:50:54 INFO - "repository": "", 04:50:54 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 04:50:54 INFO - "buildid": "20160920003006", 04:50:54 INFO - "pgo_build": "True", 04:50:54 INFO - "basedir": "/builds/slave/test", 04:50:54 INFO - "buildnumber": 1582, 04:50:54 INFO - "slavename": "talos-linux64-ix-034", 04:50:54 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 04:50:54 INFO - "platform": "ubuntu64_hw", 04:50:54 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 04:50:54 INFO - "branch": "fx-team", 04:50:54 INFO - "repo_path": "integration/fx-team", 04:50:54 INFO - "revision": "8a1efb73e7429df1dd35a4588c038b079bdf6018", 04:50:54 INFO - "stage_platform": "linux64-pgo", 04:50:54 INFO - "builduid": "518bc64353e342e8874b0c38fbd3bb6a", 04:50:54 INFO - "slavebuilddir": "test" 04:50:54 INFO - } 04:50:54 INFO - Found installer url https://queue.taskcluster.net/v1/task/b9ZTmjBATVyAprSp7ky4mg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2. 04:50:54 INFO - Running post-action listener: _resource_record_post_action 04:50:54 INFO - [mozharness: 2016-09-20 11:50:54.111174Z] Finished read-buildbot-config step (success) 04:50:54 INFO - [mozharness: 2016-09-20 11:50:54.111264Z] Running download-and-extract step. 04:50:54 INFO - Running pre-action listener: _resource_record_pre_action 04:50:54 INFO - Running main action method: download_and_extract 04:50:54 INFO - mkdir: /builds/slave/test/build/tests 04:50:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:50:54 INFO - trying https://queue.taskcluster.net/v1/task/b9ZTmjBATVyAprSp7ky4mg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 04:50:54 INFO - Downloading https://queue.taskcluster.net/v1/task/b9ZTmjBATVyAprSp7ky4mg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 04:50:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b9ZTmjBATVyAprSp7ky4mg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 04:50:54 INFO - Downloaded 1361 bytes. 04:50:54 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 04:50:54 INFO - Using the following test package requirements: 04:50:54 INFO - {u'common': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 04:50:54 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 04:50:54 INFO - u'firefox-52.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 04:50:54 INFO - u'gtest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 04:50:54 INFO - u'firefox-52.0a1.en-US.linux-x86_64.gtest.tests.zip'], 04:50:54 INFO - u'jittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 04:50:54 INFO - u'jsshell-linux-x86_64.zip'], 04:50:54 INFO - u'mochitest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 04:50:54 INFO - u'firefox-52.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 04:50:54 INFO - u'mozbase': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 04:50:54 INFO - u'reftest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 04:50:54 INFO - u'firefox-52.0a1.en-US.linux-x86_64.reftest.tests.zip'], 04:50:54 INFO - u'talos': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 04:50:54 INFO - u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'], 04:50:54 INFO - u'web-platform': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 04:50:54 INFO - u'firefox-52.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 04:50:54 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 04:50:54 INFO - u'firefox-52.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 04:50:54 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 04:50:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/b9ZTmjBATVyAprSp7ky4mg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 04:50:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b9ZTmjBATVyAprSp7ky4mg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:50:54 INFO - Fetch https://queue.taskcluster.net/v1/task/b9ZTmjBATVyAprSp7ky4mg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 04:50:55 INFO - Expected file size: 21936889 04:50:59 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 04:50:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/b9ZTmjBATVyAprSp7ky4mg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 04:50:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b9ZTmjBATVyAprSp7ky4mg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:50:59 INFO - Fetch https://queue.taskcluster.net/v1/task/b9ZTmjBATVyAprSp7ky4mg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 04:51:00 INFO - Expected file size: 21936889 04:51:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/b9ZTmjBATVyAprSp7ky4mg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip 04:51:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b9ZTmjBATVyAprSp7ky4mg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 04:51:05 INFO - Fetch https://queue.taskcluster.net/v1/task/b9ZTmjBATVyAprSp7ky4mg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip into memory 04:51:06 INFO - Expected file size: 14414059 04:51:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:51:07 INFO - trying https://queue.taskcluster.net/v1/task/b9ZTmjBATVyAprSp7ky4mg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 04:51:07 INFO - Downloading https://queue.taskcluster.net/v1/task/b9ZTmjBATVyAprSp7ky4mg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 04:51:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b9ZTmjBATVyAprSp7ky4mg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 04:51:08 INFO - Downloaded 59543149 bytes. 04:51:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/b9ZTmjBATVyAprSp7ky4mg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 04:51:08 INFO - mkdir: /builds/slave/test/properties 04:51:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:51:08 INFO - Writing to file /builds/slave/test/properties/build_url 04:51:08 INFO - Contents: 04:51:08 INFO - build_url:https://queue.taskcluster.net/v1/task/b9ZTmjBATVyAprSp7ky4mg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 04:51:09 INFO - Running post-action listener: _resource_record_post_action 04:51:09 INFO - Running post-action listener: set_extra_try_arguments 04:51:09 INFO - [mozharness: 2016-09-20 11:51:09.020465Z] Finished download-and-extract step (success) 04:51:09 INFO - [mozharness: 2016-09-20 11:51:09.020765Z] Running populate-webroot step. 04:51:09 INFO - Running pre-action listener: _resource_record_pre_action 04:51:09 INFO - Running main action method: populate_webroot 04:51:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:51:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:51:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:51:09 INFO - '--tppagecycles', 04:51:09 INFO - '7'], 04:51:09 INFO - 'tests': ['tsvgm']}}, 04:51:09 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:51:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:51:09 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:51:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:51:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:51:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:51:09 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:51:09 INFO - 'talos_options': ['--disable-e10s'], 04:51:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:51:09 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:51:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:51:09 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:51:09 INFO - 'talos_options': ['--disable-e10s'], 04:51:09 INFO - 'tests': ['damp', 'tps']}, 04:51:09 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:51:09 INFO - 'tests': ['damp', 'tps']}, 04:51:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:51:09 INFO - 'tests': ['dromaeo_dom']}, 04:51:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:51:09 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:51:09 INFO - 'tests': ['basic_compositor_video']}, 04:51:09 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:51:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:51:09 INFO - 'tests': ['a11yr', 04:51:09 INFO - 'ts_paint', 04:51:09 INFO - 'tpaint', 04:51:09 INFO - 'sessionrestore', 04:51:09 INFO - 'sessionrestore_no_auto_restore', 04:51:09 INFO - 'tabpaint']}, 04:51:09 INFO - 'other-e10s': {'tests': ['a11yr', 04:51:09 INFO - 'ts_paint', 04:51:09 INFO - 'tpaint', 04:51:09 INFO - 'sessionrestore', 04:51:09 INFO - 'sessionrestore_no_auto_restore', 04:51:09 INFO - 'tabpaint']}, 04:51:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:51:09 INFO - 'ts_paint', 04:51:09 INFO - 'tpaint', 04:51:09 INFO - 'sessionrestore', 04:51:09 INFO - 'sessionrestore_no_auto_restore', 04:51:09 INFO - 'tabpaint']}, 04:51:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:51:09 INFO - 'ts_paint', 04:51:09 INFO - 'tpaint', 04:51:09 INFO - 'sessionrestore', 04:51:09 INFO - 'sessionrestore_no_auto_restore', 04:51:09 INFO - 'tabpaint']}, 04:51:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:51:09 INFO - 'tests': ['a11yr', 04:51:09 INFO - 'ts_paint', 04:51:09 INFO - 'tpaint', 04:51:09 INFO - 'sessionrestore', 04:51:09 INFO - 'sessionrestore_no_auto_restore', 04:51:09 INFO - 'tabpaint']}, 04:51:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:51:09 INFO - 'tests': ['a11yr', 04:51:09 INFO - 'ts_paint', 04:51:09 INFO - 'tpaint', 04:51:09 INFO - 'sessionrestore', 04:51:09 INFO - 'sessionrestore_no_auto_restore', 04:51:09 INFO - 'tabpaint']}, 04:51:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:51:09 INFO - 'tests': ['tsvgx', 04:51:09 INFO - 'tsvgr_opacity', 04:51:09 INFO - 'tart', 04:51:09 INFO - 'tscrollx', 04:51:09 INFO - 'cart']}, 04:51:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:51:09 INFO - 'tsvgr_opacity', 04:51:09 INFO - 'tart', 04:51:09 INFO - 'tscrollx', 04:51:09 INFO - 'cart']}, 04:51:09 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:51:09 INFO - 'talos_options': ['--disable-e10s'], 04:51:09 INFO - 'tests': ['tp5o']}, 04:51:09 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:51:09 INFO - 'tests': ['tp5o']}, 04:51:09 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:51:09 INFO - 'talos_options': ['--disable-e10s', 04:51:09 INFO - '--xperf_path', 04:51:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:51:09 INFO - 'tests': ['tp5n']}, 04:51:09 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:51:09 INFO - 'talos_options': ['--xperf_path', 04:51:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:51:09 INFO - 'tests': ['tp5n']}}, 04:51:09 INFO - 'talos.zip': {'path': '', 04:51:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:51:09 INFO - Running post-action listener: _resource_record_post_action 04:51:09 INFO - [mozharness: 2016-09-20 11:51:09.038818Z] Finished populate-webroot step (success) 04:51:09 INFO - [mozharness: 2016-09-20 11:51:09.038912Z] Running create-virtualenv step. 04:51:09 INFO - Running pre-action listener: _resource_record_pre_action 04:51:09 INFO - Running main action method: create_virtualenv 04:51:09 INFO - Creating virtualenv /builds/slave/test/build/venv 04:51:09 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 04:51:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:51:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:51:09 INFO - Using real prefix '/usr' 04:51:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:51:09 INFO - Installing distribute.............................................................................................................................................................................................done. 04:51:11 INFO - Installing pip.................done. 04:51:11 INFO - Return code: 0 04:51:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:51: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')]} 04:51:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:51:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:51: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')]} 04:51:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:51:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:51:11 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 0x7fce0c836f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13448a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14107b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x149f790>, '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 0x14a0690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14a0b20>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1474372251.815846-967121185', '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 04:51:11 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 04:51:11 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 04:51:11 INFO - Using env: {'DISPLAY': ':0', 04:51:11 INFO - 'HOME': '/home/cltbld', 04:51:11 INFO - 'LANG': 'en_US.UTF-8', 04:51:11 INFO - 'LANGUAGE': 'en_US:en', 04:51:11 INFO - 'LOGNAME': 'cltbld', 04:51:11 INFO - 'MAIL': '/var/mail/cltbld', 04:51:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:51:11 INFO - 'MOZ_NO_REMOTE': '1', 04:51:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:51:11 INFO - 'NO_EM_RESTART': '1', 04:51:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:51:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:51:11 INFO - 'PWD': '/builds/slave/test', 04:51:11 INFO - 'SHELL': '/bin/bash', 04:51:11 INFO - 'SHLVL': '1', 04:51:11 INFO - 'TERM': 'linux', 04:51:11 INFO - 'TMOUT': '86400', 04:51:11 INFO - 'USER': 'cltbld', 04:51:11 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474372251.815846-967121185', 04:51:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:51:11 INFO - '_': '/tools/buildbot/bin/python'} 04:51:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:51:11 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 04:51:11 INFO - Cleaning up... 04:51:11 INFO - Return code: 0 04:51:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:51: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')]} 04:51:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:51:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:51: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')]} 04:51:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:51:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:51:11 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 0x7fce0c836f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13448a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14107b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x149f790>, '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 0x14a0690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14a0b20>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1474372251.815846-967121185', '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 04:51:11 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 04:51:11 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 04:51:11 INFO - Using env: {'DISPLAY': ':0', 04:51:11 INFO - 'HOME': '/home/cltbld', 04:51:11 INFO - 'LANG': 'en_US.UTF-8', 04:51:11 INFO - 'LANGUAGE': 'en_US:en', 04:51:11 INFO - 'LOGNAME': 'cltbld', 04:51:11 INFO - 'MAIL': '/var/mail/cltbld', 04:51:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:51:11 INFO - 'MOZ_NO_REMOTE': '1', 04:51:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:51:11 INFO - 'NO_EM_RESTART': '1', 04:51:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:51:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:51:11 INFO - 'PWD': '/builds/slave/test', 04:51:11 INFO - 'SHELL': '/bin/bash', 04:51:11 INFO - 'SHLVL': '1', 04:51:11 INFO - 'TERM': 'linux', 04:51:11 INFO - 'TMOUT': '86400', 04:51:11 INFO - 'USER': 'cltbld', 04:51:11 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474372251.815846-967121185', 04:51:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:51:11 INFO - '_': '/tools/buildbot/bin/python'} 04:51:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:51:12 INFO - Downloading/unpacking psutil>=3.1.1 04:51:12 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 04:51:12 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 04:51:12 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 04:51:12 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 04:51:12 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 04:51:12 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 04:51:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:51:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:51:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:51:14 INFO - Installing collected packages: psutil 04:51:14 INFO - Running setup.py install for psutil 04:51:14 INFO - building 'psutil._psutil_linux' extension 04:51:14 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 04:51:14 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 04:51:14 INFO - building 'psutil._psutil_posix' extension 04:51:14 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 04:51:14 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 04:51:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:51:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:51:14 INFO - Successfully installed psutil 04:51:14 INFO - Cleaning up... 04:51:14 INFO - Return code: 0 04:51:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:51:14 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')]} 04:51:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:51:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:51:14 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')]} 04:51:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:51:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:51:14 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 0x7fce0c836f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13448a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14107b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x149f790>, '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 0x14a0690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14a0b20>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1474372251.815846-967121185', '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 04:51:14 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 04:51:14 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 04:51:14 INFO - Using env: {'DISPLAY': ':0', 04:51:14 INFO - 'HOME': '/home/cltbld', 04:51:14 INFO - 'LANG': 'en_US.UTF-8', 04:51:14 INFO - 'LANGUAGE': 'en_US:en', 04:51:14 INFO - 'LOGNAME': 'cltbld', 04:51:14 INFO - 'MAIL': '/var/mail/cltbld', 04:51:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:51:14 INFO - 'MOZ_NO_REMOTE': '1', 04:51:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:51:14 INFO - 'NO_EM_RESTART': '1', 04:51:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:51:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:51:14 INFO - 'PWD': '/builds/slave/test', 04:51:14 INFO - 'SHELL': '/bin/bash', 04:51:14 INFO - 'SHLVL': '1', 04:51:14 INFO - 'TERM': 'linux', 04:51:14 INFO - 'TMOUT': '86400', 04:51:14 INFO - 'USER': 'cltbld', 04:51:14 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474372251.815846-967121185', 04:51:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:51:14 INFO - '_': '/tools/buildbot/bin/python'} 04:51:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:51:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:51:14 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 04:51:14 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 04:51:14 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 04:51:14 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 04:51:14 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 04:51:14 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 04:51:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:51:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:51:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:51:16 INFO - Installing collected packages: mozsystemmonitor 04:51:16 INFO - Running setup.py install for mozsystemmonitor 04:51:16 INFO - Successfully installed mozsystemmonitor 04:51:16 INFO - Cleaning up... 04:51:16 INFO - Return code: 0 04:51:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:51:16 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')]} 04:51:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:51:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:51:16 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')]} 04:51:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:51:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:51:16 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 0x7fce0c836f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13448a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14107b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x149f790>, '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 0x14a0690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14a0b20>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1474372251.815846-967121185', '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 04:51:16 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 04:51:16 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 04:51:16 INFO - Using env: {'DISPLAY': ':0', 04:51:16 INFO - 'HOME': '/home/cltbld', 04:51:16 INFO - 'LANG': 'en_US.UTF-8', 04:51:16 INFO - 'LANGUAGE': 'en_US:en', 04:51:16 INFO - 'LOGNAME': 'cltbld', 04:51:16 INFO - 'MAIL': '/var/mail/cltbld', 04:51:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:51:16 INFO - 'MOZ_NO_REMOTE': '1', 04:51:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:51:16 INFO - 'NO_EM_RESTART': '1', 04:51:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:51:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:51:16 INFO - 'PWD': '/builds/slave/test', 04:51:16 INFO - 'SHELL': '/bin/bash', 04:51:16 INFO - 'SHLVL': '1', 04:51:16 INFO - 'TERM': 'linux', 04:51:16 INFO - 'TMOUT': '86400', 04:51:16 INFO - 'USER': 'cltbld', 04:51:16 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474372251.815846-967121185', 04:51:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:51:16 INFO - '_': '/tools/buildbot/bin/python'} 04:51:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:51:17 INFO - Downloading/unpacking jsonschema==2.5.1 04:51:17 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 04:51:17 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 04:51:17 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 04:51:17 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 04:51:17 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 04:51:17 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 04:51:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:51:18 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:51:18 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 04:51:18 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 04:51:18 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 04:51:18 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 04:51:18 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 04:51:18 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 04:51:19 INFO - Downloading functools32-3.2.3-2.tar.gz 04:51:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:51:19 INFO - warning: no files found matching '*.txt' 04:51:19 INFO - no previously-included directories found matching 'build' 04:51:19 INFO - no previously-included directories found matching 'dist' 04:51:19 INFO - no previously-included directories found matching '.git*' 04:51:19 INFO - Installing collected packages: jsonschema, functools32 04:51:19 INFO - Running setup.py install for functools32 04:51:19 INFO - warning: no files found matching '*.txt' 04:51:19 INFO - no previously-included directories found matching 'build' 04:51:19 INFO - no previously-included directories found matching 'dist' 04:51:19 INFO - no previously-included directories found matching '.git*' 04:51:19 INFO - Successfully installed jsonschema functools32 04:51:19 INFO - Cleaning up... 04:51:19 INFO - Return code: 0 04:51:19 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:51:19 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')]} 04:51:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:51:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:51:19 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')]} 04:51:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:51:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:51:19 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 0x7fce0c836f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13448a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14107b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x149f790>, '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 0x14a0690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14a0b20>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1474372251.815846-967121185', '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 04:51:19 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 04:51:19 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 04:51:19 INFO - Using env: {'DISPLAY': ':0', 04:51:19 INFO - 'HOME': '/home/cltbld', 04:51:19 INFO - 'LANG': 'en_US.UTF-8', 04:51:19 INFO - 'LANGUAGE': 'en_US:en', 04:51:19 INFO - 'LOGNAME': 'cltbld', 04:51:19 INFO - 'MAIL': '/var/mail/cltbld', 04:51:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:51:19 INFO - 'MOZ_NO_REMOTE': '1', 04:51:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:51:19 INFO - 'NO_EM_RESTART': '1', 04:51:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:51:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:51:19 INFO - 'PWD': '/builds/slave/test', 04:51:19 INFO - 'SHELL': '/bin/bash', 04:51:19 INFO - 'SHLVL': '1', 04:51:19 INFO - 'TERM': 'linux', 04:51:19 INFO - 'TMOUT': '86400', 04:51:19 INFO - 'USER': 'cltbld', 04:51:19 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474372251.815846-967121185', 04:51:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:51:19 INFO - '_': '/tools/buildbot/bin/python'} 04:51:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:51:19 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:51:19 INFO - Cleaning up... 04:51:19 INFO - Return code: 0 04:51:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:51:19 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')]} 04:51:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:51:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:51:19 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')]} 04:51:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:51:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:51:19 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 0x7fce0c836f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13448a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14107b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x149f790>, '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 0x14a0690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14a0b20>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1474372251.815846-967121185', '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 04:51:19 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 04:51:19 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 04:51:19 INFO - Using env: {'DISPLAY': ':0', 04:51:19 INFO - 'HOME': '/home/cltbld', 04:51:19 INFO - 'LANG': 'en_US.UTF-8', 04:51:19 INFO - 'LANGUAGE': 'en_US:en', 04:51:19 INFO - 'LOGNAME': 'cltbld', 04:51:19 INFO - 'MAIL': '/var/mail/cltbld', 04:51:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:51:19 INFO - 'MOZ_NO_REMOTE': '1', 04:51:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:51:19 INFO - 'NO_EM_RESTART': '1', 04:51:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:51:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:51:19 INFO - 'PWD': '/builds/slave/test', 04:51:19 INFO - 'SHELL': '/bin/bash', 04:51:19 INFO - 'SHLVL': '1', 04:51:19 INFO - 'TERM': 'linux', 04:51:19 INFO - 'TMOUT': '86400', 04:51:19 INFO - 'USER': 'cltbld', 04:51:19 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474372251.815846-967121185', 04:51:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:51:19 INFO - '_': '/tools/buildbot/bin/python'} 04:51:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:51:19 INFO - Downloading/unpacking blobuploader==1.2.4 04:51:19 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 04:51:19 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 04:51:19 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 04:51:19 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 04:51:19 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 04:51:19 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 04:51:21 INFO - Downloading blobuploader-1.2.4.tar.gz 04:51:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:51:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:51:21 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 04:51:21 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 04:51:21 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 04:51:21 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 04:51:21 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 04:51:21 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 04:51:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:51:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:51:22 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 04:51:22 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 04:51:22 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 04:51:22 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 04:51:22 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 04:51:22 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 04:51:22 INFO - Downloading docopt-0.6.1.tar.gz 04:51:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:51:22 INFO - Installing collected packages: blobuploader, requests, docopt 04:51:22 INFO - Running setup.py install for blobuploader 04:51:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:51:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:51:22 INFO - Running setup.py install for requests 04:51:22 INFO - Running setup.py install for docopt 04:51:23 INFO - Successfully installed blobuploader requests docopt 04:51:23 INFO - Cleaning up... 04:51:23 INFO - Return code: 0 04:51:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:51:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:51:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:51:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:51:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:51:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:51:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:51:23 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 0x7fce0c836f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13448a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14107b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x149f790>, '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 0x14a0690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14a0b20>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1474372251.815846-967121185', '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 04:51:23 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 04:51:23 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 04:51:23 INFO - Using env: {'DISPLAY': ':0', 04:51:23 INFO - 'HOME': '/home/cltbld', 04:51:23 INFO - 'LANG': 'en_US.UTF-8', 04:51:23 INFO - 'LANGUAGE': 'en_US:en', 04:51:23 INFO - 'LOGNAME': 'cltbld', 04:51:23 INFO - 'MAIL': '/var/mail/cltbld', 04:51:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:51:23 INFO - 'MOZ_NO_REMOTE': '1', 04:51:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:51:23 INFO - 'NO_EM_RESTART': '1', 04:51:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:51:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:51:23 INFO - 'PWD': '/builds/slave/test', 04:51:23 INFO - 'SHELL': '/bin/bash', 04:51:23 INFO - 'SHLVL': '1', 04:51:23 INFO - 'TERM': 'linux', 04:51:23 INFO - 'TMOUT': '86400', 04:51:23 INFO - 'USER': 'cltbld', 04:51:23 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474372251.815846-967121185', 04:51:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:51:23 INFO - '_': '/tools/buildbot/bin/python'} 04:51:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:51:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:51:23 INFO - Running setup.py (path:/tmp/pip-inyBBf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:51:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:51:23 INFO - Running setup.py (path:/tmp/pip-XVwgX7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:51:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:51:23 INFO - Running setup.py (path:/tmp/pip-nt1aRM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:51:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:51:23 INFO - Running setup.py (path:/tmp/pip-hfvSY_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:51:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:51:23 INFO - Running setup.py (path:/tmp/pip-x4FSVY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:51:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:51:23 INFO - Running setup.py (path:/tmp/pip-VrjRyB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:51:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:51:23 INFO - Running setup.py (path:/tmp/pip-3M2GaG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:51:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:51:23 INFO - Running setup.py (path:/tmp/pip-pddEVi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:51:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:51:23 INFO - Running setup.py (path:/tmp/pip-x264Dk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:51:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:51:23 INFO - Running setup.py (path:/tmp/pip-6tjw4U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:51:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:51:24 INFO - Running setup.py (path:/tmp/pip-NlF56P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:51:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:51:24 INFO - Running setup.py (path:/tmp/pip-8kCnFc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:51:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:51:24 INFO - Running setup.py (path:/tmp/pip-t4nGby-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:51:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:51:24 INFO - Running setup.py (path:/tmp/pip-UTG4IA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:51:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:51:24 INFO - Running setup.py (path:/tmp/pip-7fPHL_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:51:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:51:24 INFO - Running setup.py (path:/tmp/pip-aEwgW2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:51:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:51:24 INFO - Running setup.py (path:/tmp/pip-1rFL5f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:51:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:51:24 INFO - Running setup.py install for manifestparser 04:51:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:51:24 INFO - Running setup.py install for mozcrash 04:51:24 INFO - Running setup.py install for mozdebug 04:51:24 INFO - Running setup.py install for mozdevice 04:51:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:51:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:51:24 INFO - Running setup.py install for mozfile 04:51:25 INFO - Running setup.py install for mozhttpd 04:51:25 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:51:25 INFO - Running setup.py install for mozinfo 04:51:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:51:25 INFO - Running setup.py install for mozInstall 04:51:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:51:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:51:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:51:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:51:25 INFO - Running setup.py install for mozleak 04:51:25 INFO - Running setup.py install for mozlog 04:51:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:51:25 INFO - Running setup.py install for moznetwork 04:51:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:51:25 INFO - Running setup.py install for mozprocess 04:51:25 INFO - Running setup.py install for mozprofile 04:51:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:51:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:51:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:51:25 INFO - Running setup.py install for mozrunner 04:51:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:51:26 INFO - Running setup.py install for mozscreenshot 04:51:26 INFO - Running setup.py install for moztest 04:51:26 INFO - Running setup.py install for mozversion 04:51:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:51:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:51:26 INFO - Cleaning up... 04:51:26 INFO - Return code: 0 04:51:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:51:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:51:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:51:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:51:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:51:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:51:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:51:26 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 0x7fce0c836f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13448a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14107b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x149f790>, '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 0x14a0690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14a0b20>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1474372251.815846-967121185', '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 04:51:26 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 04:51:26 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 04:51:26 INFO - Using env: {'DISPLAY': ':0', 04:51:26 INFO - 'HOME': '/home/cltbld', 04:51:26 INFO - 'LANG': 'en_US.UTF-8', 04:51:26 INFO - 'LANGUAGE': 'en_US:en', 04:51:26 INFO - 'LOGNAME': 'cltbld', 04:51:26 INFO - 'MAIL': '/var/mail/cltbld', 04:51:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:51:26 INFO - 'MOZ_NO_REMOTE': '1', 04:51:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:51:26 INFO - 'NO_EM_RESTART': '1', 04:51:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:51:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:51:26 INFO - 'PWD': '/builds/slave/test', 04:51:26 INFO - 'SHELL': '/bin/bash', 04:51:26 INFO - 'SHLVL': '1', 04:51:26 INFO - 'TERM': 'linux', 04:51:26 INFO - 'TMOUT': '86400', 04:51:26 INFO - 'USER': 'cltbld', 04:51:26 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474372251.815846-967121185', 04:51:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:51:26 INFO - '_': '/tools/buildbot/bin/python'} 04:51:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:51:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:51:26 INFO - Running setup.py (path:/tmp/pip-b_sEJk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:51:26 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)) 04:51:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:51:26 INFO - Running setup.py (path:/tmp/pip-aSvOy3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:51:26 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)) 04:51:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:51:26 INFO - Running setup.py (path:/tmp/pip-3u4Cnj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:51:26 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)) 04:51:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:51:26 INFO - Running setup.py (path:/tmp/pip-TyNn4Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:51:26 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)) 04:51:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:51:26 INFO - Running setup.py (path:/tmp/pip-QNRvht-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:51:26 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)) 04:51:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:51:26 INFO - Running setup.py (path:/tmp/pip-tnID2S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:51:27 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)) 04:51:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:51:27 INFO - Running setup.py (path:/tmp/pip-FbX9nN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:51:27 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)) 04:51:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:51:27 INFO - Running setup.py (path:/tmp/pip-Vfi6E1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:51:27 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)) 04:51:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:51:27 INFO - Running setup.py (path:/tmp/pip-s9QnDF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:51:27 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)) 04:51:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:51:27 INFO - Running setup.py (path:/tmp/pip-02aMUJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:51:27 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)) 04:51:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:51:27 INFO - Running setup.py (path:/tmp/pip-QuLDNF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:51:27 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)) 04:51:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:51:27 INFO - Running setup.py (path:/tmp/pip-QGk12M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:51:27 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)) 04:51:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:51:27 INFO - Running setup.py (path:/tmp/pip-X1MmpP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:51:27 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)) 04:51:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:51:27 INFO - Running setup.py (path:/tmp/pip-JU2N9N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:51:27 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)) 04:51:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:51:27 INFO - Running setup.py (path:/tmp/pip-pOy_w7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:51:27 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)) 04:51:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:51:27 INFO - Running setup.py (path:/tmp/pip-KDgxlq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:51:27 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)) 04:51:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:51:27 INFO - Running setup.py (path:/tmp/pip-76Rz45-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:51:27 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)) 04:51:27 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)) 04:51:27 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)) 04:51:27 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)) 04:51:27 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)) 04:51:27 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)) 04:51:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:51:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:51:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:51:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:51:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:51:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:51:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:51:29 INFO - Downloading blessings-1.6.tar.gz 04:51:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:51:29 INFO - Installing collected packages: blessings 04:51:29 INFO - Running setup.py install for blessings 04:51:29 INFO - Successfully installed blessings 04:51:29 INFO - Cleaning up... 04:51:29 INFO - Return code: 0 04:51:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:51:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:51:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:51:30 INFO - Reading from file tmpfile_stdout 04:51:30 INFO - Current package versions: 04:51:30 INFO - argparse == 1.2.1 04:51:30 INFO - blessings == 1.6 04:51:30 INFO - blobuploader == 1.2.4 04:51:30 INFO - docopt == 0.6.1 04:51:30 INFO - functools32 == 3.2.3-2 04:51:30 INFO - jsonschema == 2.5.1 04:51:30 INFO - manifestparser == 1.1 04:51:30 INFO - mozInstall == 1.12 04:51:30 INFO - mozcrash == 0.17 04:51:30 INFO - mozdebug == 0.1 04:51:30 INFO - mozdevice == 0.48 04:51:30 INFO - mozfile == 1.2 04:51:30 INFO - mozhttpd == 0.7 04:51:30 INFO - mozinfo == 0.9 04:51:30 INFO - mozleak == 0.1 04:51:30 INFO - mozlog == 3.4 04:51:30 INFO - moznetwork == 0.27 04:51:30 INFO - mozprocess == 0.23 04:51:30 INFO - mozprofile == 0.28 04:51:30 INFO - mozrunner == 6.12 04:51:30 INFO - mozscreenshot == 0.1 04:51:30 INFO - mozsystemmonitor == 0.3 04:51:30 INFO - moztest == 0.7 04:51:30 INFO - mozversion == 1.4 04:51:30 INFO - psutil == 3.1.1 04:51:30 INFO - requests == 1.2.3 04:51:30 INFO - wsgiref == 0.1.2 04:51:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:51:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:51:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:51:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:51:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:51:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:51:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:51:30 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 0x7fce0c836f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13448a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14107b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x149f790>, '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 0x14a0690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14a0b20>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1474372251.815846-967121185', '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 04:51:30 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 04:51:30 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 04:51:30 INFO - Using env: {'DISPLAY': ':0', 04:51:30 INFO - 'HOME': '/home/cltbld', 04:51:30 INFO - 'LANG': 'en_US.UTF-8', 04:51:30 INFO - 'LANGUAGE': 'en_US:en', 04:51:30 INFO - 'LOGNAME': 'cltbld', 04:51:30 INFO - 'MAIL': '/var/mail/cltbld', 04:51:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:51:30 INFO - 'MOZ_NO_REMOTE': '1', 04:51:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:51:30 INFO - 'NO_EM_RESTART': '1', 04:51:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:51:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:51:30 INFO - 'PWD': '/builds/slave/test', 04:51:30 INFO - 'SHELL': '/bin/bash', 04:51:30 INFO - 'SHLVL': '1', 04:51:30 INFO - 'TERM': 'linux', 04:51:30 INFO - 'TMOUT': '86400', 04:51:30 INFO - 'USER': 'cltbld', 04:51:30 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474372251.815846-967121185', 04:51:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:51:30 INFO - '_': '/tools/buildbot/bin/python'} 04:51:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:51:30 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)) 04:51:30 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)) 04:51:30 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)) 04:51:30 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)) 04:51:30 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)) 04:51:30 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)) 04:51:30 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)) 04:51:30 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)) 04:51:30 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)) 04:51:30 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)) 04:51:30 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)) 04:51:30 INFO - Cleaning up... 04:51:30 INFO - Return code: 0 04:51:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:51:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:51:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:51:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:51:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:51:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:51:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:51:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce0c836f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13448a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14107b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x149f790>, '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 0x14a0690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14a0b20>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1474372251.815846-967121185', '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 04:51:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:51:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:51:30 INFO - Using env: {'DISPLAY': ':0', 04:51:30 INFO - 'HOME': '/home/cltbld', 04:51:30 INFO - 'LANG': 'en_US.UTF-8', 04:51:30 INFO - 'LANGUAGE': 'en_US:en', 04:51:30 INFO - 'LOGNAME': 'cltbld', 04:51:30 INFO - 'MAIL': '/var/mail/cltbld', 04:51:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:51:30 INFO - 'MOZ_NO_REMOTE': '1', 04:51:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:51:30 INFO - 'NO_EM_RESTART': '1', 04:51:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:51:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:51:30 INFO - 'PWD': '/builds/slave/test', 04:51:30 INFO - 'SHELL': '/bin/bash', 04:51:30 INFO - 'SHLVL': '1', 04:51:30 INFO - 'TERM': 'linux', 04:51:30 INFO - 'TMOUT': '86400', 04:51:30 INFO - 'USER': 'cltbld', 04:51:30 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474372251.815846-967121185', 04:51:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:51:30 INFO - '_': '/tools/buildbot/bin/python'} 04:51:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:51:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:51:30 INFO - Cleaning up... 04:51:30 INFO - Return code: 0 04:51:30 INFO - Running post-action listener: _resource_record_post_action 04:51:30 INFO - Running post-action listener: _start_resource_monitoring 04:51:30 INFO - Starting resource monitoring. 04:51:30 INFO - [mozharness: 2016-09-20 11:51:30.654023Z] Finished create-virtualenv step (success) 04:51:30 INFO - [mozharness: 2016-09-20 11:51:30.654527Z] Running install step. 04:51:30 INFO - Running pre-action listener: _resource_record_pre_action 04:51:30 INFO - Running main action method: install 04:51:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:51:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:51:30 INFO - Reading from file tmpfile_stdout 04:51:30 INFO - Detecting whether we're running mozinstall >=1.0... 04:51:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:51:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:51:30 INFO - Reading from file tmpfile_stdout 04:51:30 INFO - Output received: 04:51:30 INFO - Usage: mozinstall [options] installer 04:51:30 INFO - Options: 04:51:30 INFO - -h, --help show this help message and exit 04:51:30 INFO - -d DEST, --destination=DEST 04:51:30 INFO - Directory to install application into. [default: 04:51:30 INFO - "/builds/slave/test"] 04:51:30 INFO - --app=APP Application being installed. [default: firefox] 04:51:30 INFO - mkdir: /builds/slave/test/build/application 04:51:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 04:51:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 04:51:47 INFO - Reading from file tmpfile_stdout 04:51:47 INFO - Output received: 04:51:47 INFO - /builds/slave/test/build/application/firefox/firefox 04:51:47 INFO - Running post-action listener: _resource_record_post_action 04:51:47 INFO - [mozharness: 2016-09-20 11:51:47.910189Z] Finished install step (success) 04:51:47 INFO - [mozharness: 2016-09-20 11:51:47.910434Z] Running run-tests step. 04:51:47 INFO - Running pre-action listener: _resource_record_pre_action 04:51:47 INFO - Running main action method: run_tests 04:51:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:51:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:51:47 INFO - Python 2.7.3 04:51:47 INFO - Return code: 0 04:51:47 INFO - grabbing minidump binary from tooltool 04:51:47 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')]} 04:51:47 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 0x149f790>, '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 0x14a0690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14a0b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:51:47 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 04:51:47 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 04:51:47 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 04:51:47 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:51:47 INFO - Return code: 0 04:51:47 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:51:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:51:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:51:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:51:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:51:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/b9ZTmjBATVyAprSp7ky4mg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--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 04:51:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/b9ZTmjBATVyAprSp7ky4mg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-034 --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 04:51:47 INFO - Using env: {'DISPLAY': ':0', 04:51:47 INFO - 'HOME': '/home/cltbld', 04:51:47 INFO - 'LANG': 'en_US.UTF-8', 04:51:47 INFO - 'LANGUAGE': 'en_US:en', 04:51:47 INFO - 'LOGNAME': 'cltbld', 04:51:47 INFO - 'MAIL': '/var/mail/cltbld', 04:51:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:51:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:51:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:51:47 INFO - 'MOZ_NO_REMOTE': '1', 04:51:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:51:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:51:47 INFO - 'NO_EM_RESTART': '1', 04:51:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:51:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:51:47 INFO - 'PWD': '/builds/slave/test', 04:51:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:51:47 INFO - 'SHELL': '/bin/bash', 04:51:47 INFO - 'SHLVL': '1', 04:51:47 INFO - 'TERM': 'linux', 04:51:47 INFO - 'TMOUT': '86400', 04:51:47 INFO - 'USER': 'cltbld', 04:51:47 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474372251.815846-967121185', 04:51:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:51:47 INFO - '_': '/tools/buildbot/bin/python'} 04:51:47 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/b9ZTmjBATVyAprSp7ky4mg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--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 04:51:48 INFO - mozversion application_buildid: 20160920003006 04:51:48 INFO - mozversion application_changeset: 8a1efb73e7429df1dd35a4588c038b079bdf6018 04:51:48 INFO - mozversion application_display_name: Nightly 04:51:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:51:48 INFO - mozversion application_name: Firefox 04:51:48 INFO - mozversion application_remotingname: firefox 04:51:48 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 04:51:48 INFO - mozversion application_vendor: Mozilla 04:51:48 INFO - mozversion application_version: 52.0a1 04:51:48 INFO - mozversion platform_buildid: 20160920003006 04:51:48 INFO - mozversion platform_changeset: 8a1efb73e7429df1dd35a4588c038b079bdf6018 04:51:48 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 04:51:48 INFO - mozversion platform_version: 52.0a1 04:51:48 INFO - using testdate: 1474372308 04:51:48 INFO - actual date: 1474372308 04:51:48 INFO - starting webserver on 'localhost:46596' 04:51:48 INFO - SUITE-START | Running 2 tests 04:51:48 INFO - TEST-START | dromaeo_css 04:51:48 INFO - operating with platform_type : linux_ 04:51:48 INFO - Initialising browser for dromaeo_css test... 04:51:48 INFO - TEST-INFO | started process 12985 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp90QOD5/profile http://localhost:46596/getInfo.html) 04:51:52 INFO - PROCESS | 12985 | __metrics Screen width/height:1600/1200 04:51:52 INFO - PROCESS | 12985 | colorDepth:24 04:51:52 INFO - PROCESS | 12985 | Browser inner width/height: 1024/697 04:51:52 INFO - PROCESS | 12985 | __metrics 04:51:53 INFO - PROCESS | 12985 | 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" 04:51:53 INFO - PROCESS | 12985 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:51:53 INFO - PROCESS | 12985 | console.error: PushService: 04:51:53 INFO - PROCESS | 12985 | stateChangeProcessEnqueue: Error transitioning state 04:51:53 INFO - PROCESS | 12985 | UnknownError 04:51:54 INFO - TEST-INFO | 12985: exit 0 04:51:54 INFO - Browser initialized. 04:51:54 INFO - Running cycle 1/1 for dromaeo_css test... 04:51:54 INFO - TEST-INFO | started process 13069 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp90QOD5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:52:06 INFO - PROCESS | 13069 | RSS: Main: 173252608 04:52:06 INFO - PROCESS | 13069 | 04:52:13 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 04:52:18 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 04:52:34 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.08338 seconds. 04:52:36 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.16667 seconds. 04:52:43 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 6.03359 seconds. 04:52:49 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 04:52:52 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 04:52:54 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 04:52:57 INFO - PROCESS | 13069 | 1474372377680 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] 04:53:02 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.05001 seconds. 04:53:07 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 04:53:09 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:53:11 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 04:53:34 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 04:53:37 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.05004 seconds. 04:53:41 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 04:53:43 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.05 seconds. 04:53:48 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 04:53:54 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 04:53:58 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 04:54:03 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:54:18 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 04:54:30 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 04:54:36 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:54:38 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:54:41 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 04:54:46 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 04:54:48 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 04:55:06 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 04:55:06 INFO - PROCESS | 13069 | Cycle 1(1): loaded http://localhost:46596/tests/dromaeo/cssquery-dojo.html (next: http://localhost:46596/tests/dromaeo/cssquery-ext.html) 04:55:08 INFO - PROCESS | 13069 | RSS: Main: 380137472 04:55:08 INFO - PROCESS | 13069 | 04:55:12 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.15013 seconds. 04:55:18 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 04:55:27 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:55:29 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 04:55:31 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:55:39 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:55:41 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:55:45 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:55:55 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:56:00 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 04:56:14 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:56:20 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:56:28 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:56:46 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:56:48 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:56:58 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:57:00 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00008 seconds. 04:57:02 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:57:10 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 04:57:12 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:57:13 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 2.00006 seconds. 04:57:20 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:57:29 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 04:57:31 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:57:32 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:57:51 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:57:53 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:58:05 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:58:11 INFO - PROCESS | 13069 | Cycle 1(1): loaded http://localhost:46596/tests/dromaeo/cssquery-ext.html (next: http://localhost:46596/tests/dromaeo/cssquery-jquery.html) 04:58:11 INFO - PROCESS | 13069 | RSS: Main: 278999040 04:58:11 INFO - PROCESS | 13069 | 04:58:15 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.03354 seconds. 04:58:19 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 04:58:29 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:58:40 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:58:48 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:59:18 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 04:59:28 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:59:34 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:59:47 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 04:59:49 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 04:59:51 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:00:01 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 05:00:07 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 05:00:21 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 05:00:27 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 05:00:31 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:00:35 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 05:00:45 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:00:55 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:00:57 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 05:01:13 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 05:01:13 INFO - PROCESS | 13069 | Cycle 1(1): loaded http://localhost:46596/tests/dromaeo/cssquery-jquery.html (next: http://localhost:46596/tests/dromaeo/cssquery-mootools.html) 05:01:14 INFO - PROCESS | 13069 | RSS: Main: 268636160 05:01:14 INFO - PROCESS | 13069 | 05:01:20 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 05:01:21 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:01:23 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:01:39 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 05:01:49 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:01:51 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:01:53 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 05:02:21 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:03:01 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 05:03:12 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:03:18 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:03:26 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:03:34 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:03:36 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.0168 seconds. 05:03:44 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 05:03:52 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:03:54 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 05:04:04 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:04:16 INFO - PROCESS | 13069 | Cycle 1(1): loaded http://localhost:46596/tests/dromaeo/cssquery-mootools.html (next: http://localhost:46596/tests/dromaeo/cssquery-prototype.html) 05:04:17 INFO - PROCESS | 13069 | RSS: Main: 214732800 05:04:17 INFO - PROCESS | 13069 | 05:04:20 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 05:04:23 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.03326 seconds. 05:04:33 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 05:04:56 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 05:04:58 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:05:08 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:05:12 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:05:28 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:05:37 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 05:05:39 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:05:44 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:06:02 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 05:06:09 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 05:06:13 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:06:17 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:06:23 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 05:06:27 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:06:39 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 05:06:41 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 05:06:46 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.01666 seconds. 05:06:50 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 05:06:59 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 05:07:01 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:07:12 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:07:13 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:07:17 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 05:07:19 INFO - PROCESS | 13069 | Cycle 1(1): loaded http://localhost:46596/tests/dromaeo/cssquery-prototype.html (next: http://localhost:46596/tests/dromaeo/cssquery-yui.html) 05:07:20 INFO - PROCESS | 13069 | RSS: Main: 217804800 05:07:20 INFO - PROCESS | 13069 | 05:07:23 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 05:07:33 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:07:37 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 05:07:40 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:07:43 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 05:07:45 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 05:07:55 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 05:07:59 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:08:09 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:08:19 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.01662 seconds. 05:08:23 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:08:35 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 05:08:43 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 05:08:45 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:08:48 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 05:09:19 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 05:09:22 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 05:09:40 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 05:09:59 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:10:21 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 05:10:23 INFO - PROCESS | 13069 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 05:10:23 INFO - PROCESS | 13069 | Cycle 1(1): loaded http://localhost:46596/tests/dromaeo/cssquery-yui.html (next: http://localhost:46596/tests/dromaeo/cssquery-dojo.html) 05:10:23 INFO - PROCESS | 13069 | RSS: Main: 310231040 05:10:23 INFO - PROCESS | 13069 | 05:10:23 INFO - PROCESS | 13069 | __start_tp_report 05:10:23 INFO - PROCESS | 13069 | _x_x_mozilla_page_load 05:10:23 INFO - PROCESS | 13069 | _x_x_mozilla_page_load_details 05:10:23 INFO - PROCESS | 13069 | |i|pagename|runs| 05:10:23 INFO - PROCESS | 13069 | |0;dojo.html;3070.3705740323235;3095.148834070632;3100.922476938076;3133.5299705044254;3155.6528781833995;5279.340082489689;5309.17707755298;5363.758630861611;5376.865578360543;5417.918731219035;5346.224797404374;5397.433269506704;5415.566754659628;5417.810376636819;5418.024755544;4884.218525035995;4958.206686930092;4964.205727083668;4970.155073637472;4972.825951091712;4911.582515486188;4970.701757894538;5034.370703662043;5038.093143234217;5092.719900405476;4965.826196083156;5009.0482808266315;5012.52381023804;5026.371703537058;5030.195168772997;4774.904501909977;4819.49395313494;4836.709797412132;4892.730899800509;4984.37695288089;4816.4220293564995;4825.227963525819;4837.782299796482;4863.562279394836;4900.534449227318;5249.501297376743;5260.053190425722;5292.655977361459;5312.2828418163435;5338.97330513345;5577.247071645316;5594.804181853616;5676.035074037382;5680.687562184078;5684.204211410406;5730.312362516483;5752.194692742977;5794.362620111784;5847.8012007538355;5896.3219229788265;5416.83749487516;5421.783128674888;5422.267993820823;5427.1316589345515;5434.75543600535;5678.772849086073;5735.627184233012;5922.289325280908;5923;5924.851878703067;5669.34802497718;5703.344115426696;5703.572232082527;5737.3688894221605;5809.767609295581;5776.884462310731;5819.010768169422;5833.416658334133;5833.854153646193;5835.35811060783;5555.194496797942;5754.625949313281;5825.242718446575;5826.621269617462;5831.62094463867;5617.466340697627;5618.971905140448;5640.605157639008;5641.40765219646;5699.373068962494;4381.036172042146;4382.583654552813;4392.077663690597;4430.091831174602;4431.911361772746;3894.6105389461395;3917.0794863207125;3924.941125883114;3927.842886284574;3969.325214713506;5315.73421328932;5349.5720342372515;5358.758855851496;5371.248025276464;5397.217403476552;5762.2221000164445;5765.423457654201;5814.156947242626;5827.3298570664065;5830.212921255661;5408.161734931092;5434.239206511092;5461.590380721462;5463;5463.071277882769;4798.424189097331;4838.903221935612;4849.321095046696;4882.292067650171;4954.727489988085;5832;5862.296524417097;5878.177055212188;5899;5917.260342457107;5676.318841738853;5696.401877802937;5703.885922281448;5704.172894930129;5711.200431939615;5845.970770146292;5879.235699359056;5880.735366908414;5887.381824908323;5892.734826932712;5637.041702910433;5645.209670646195;5678.346990096192;5681.460261275172;5780;5762.7694892203845;5802.593818432669;5814.244148260868;5818.214541036906;5823.7670493181495;5601.523870470893;5634.549236061187;5685;5697.316322041382;5697.800576979786;5627.380988091225;5687.20379146928;5708.972384970745;5710.600257981901;5715.685637289823;5671;5679.659220446786;5686.71566421669;5686.9715651421475;5689;5724.284464441945;5728.684922329312;5738.68437235956;5739.9139012914;5743.798967036134;5433.212184233343;5488.506034456918;5498.945010549844;5607.495325420732;5626.1842032905815 05:10:23 INFO - PROCESS | 13069 | |1;ext.html;527.4769172106368;580.0487200990376;581.8574449259931;590.8552404660859;604.4106995679214;31254;32000.519992200105;34159.48760768591;34165.82917085411;43178.35232471507;44858.32712509315;45356.546434535725;45493.77253113738;45562.772186139104;45584.772076139656;8895.822083558325;9058.23005044572;9059.818803623924;9062.546872656374;9066;7940.166282540336;8112.60678099959;8879.866801997976;8979.326550508706;10565.260431769779;10581.206409519278;10939.945300273528;10957.780844383107;10958.56165753369;10962.671119866416;10838.512266948006;10875.45622718861;10885.945570272177;10890.782184356347;10906.727331816728;8007.1992800720045;8219.506829590244;10278.383297002203;10630.255882088257;10691.251612387136;41915;42260.56264092288;42273.154536909395;42283.57716422827;42311.36532952008;3591.281743651254;3660.1764602964386;4061.97969010156;4146.730462519957;4191.559886211965;29687.132909778928;30086.69913300861;31076.689233107605;32387.190320241945;33635;4296.140771845612;4327.6105150536605;4337.652987760972;4380.956190438087;4383.693141480097;342921;345375;345840;347264;349163.25418373;62334.37665623286;63107.68446157832;63141;63175.368246316946;63277.36722632807;66776.66611667008;67033.3296667027;67207.6396180189;67732;67763.3223667757;16427.58931026734;16575.83424165767;16715.749263761052;16745.665086698198;16766.161691915357;11787.174897757075;11809.173357864867;11877.76244475123;11896.702582435508;11957.282563046245;17160.39938602143;17526;19310.71033934492;19545.624579166448;20170.29403970854;7342.15565209997;7387;7561.584112873845;7697.923020769721;7752.883706744404;945.4091193004373;1029.5675816157081;1049.1344640671325;1064.3773392565436;1091.3015669971226;31508.212294692818;31803;31949.84025079906;31993;32214.83892580569;1063.691529456449;1070.6145787516489;1070.7858428314369;1101.515333253366;1139.1399493382444;16720.832791672172;16853.325866965184;16858.241379137722;16944.15279236058;16997;555083;556457;561360;561868;562654;58271.41728582745;58841.41158588445;59014;59042.40957590455;59129;1323.8279023726855;1357.6673714939704;1371.6090914089436;1371.746226948018;1373.9656508587366;29715.056988601933;30941;31026;31420.842895785376;31513.527297090102;51938;52351;52427.47572524226;52437;52491.737541312046;436830;438797;439571;439842;440057;35850.028248446586;36288.45567316545;43096;43159.09454487267;43234;47517.52482475269;47804;48076;48686.5131348682;48940.510594893596;39496;42812.57187428086;43573.56426435822;44768.55231447644;46163.76918115388;42115;43149.352759709254;43405;43458.13083738244;45651.771741141085;41908.37137443002;42285.57714422816;43439.56560434355;43535.56464435442;43640.34539481847;42532;43230.56769432266;43372.56627433812;43385.78307108444;43965.560344396145;28156.296092597848;28266.717332826407;28272.376209575115;29288.56067159037;29536.852315738284 05:10:23 INFO - PROCESS | 13069 | |2;jquery.html;387.6937219596517;388.66963081380845;399.4726960412256;402.1133400851124;416.11762258131614;14069.447914064453;16435.4247601334;17964.281428742852;19661;19683;14819;16512.58718532037;17590.208440620172;18141.546461338472;18300.816991830077;5033.798648054082;5171.3535808024;5213.895722085556;5234.502722241381;5255.264263003182;5098.770555325001;5257.158854583267;5289.841304760864;5293.285406470135;5335.546478549309;5223.112070947929;5272.1300985337375;5275.894482110355;5276.736163191844;5281.947180528184;5264.263003179558;5265.394479634814;5265.4997775211295;5267.130923397634;5312.548433383167;5147.073526765181;5152.458991805881;5156.123459011977;5175.171972484384;5208.8437346879655;13962.441502339894;14122.152670839783;19498.31755888537;22902.19842305528;23142.190023349103;2323.9883800580888;2403.5072810074066;2492.9252122436355;2509.560826855293;2855.300451389402;10046.799064018753;10237.539310731036;10401;10919.181061420426;10932;1861.0261061991025;1900.5723712164652;1918.1368384227153;1938.4959910423393;1951.8145776151243;82548.17451825525;86114.56942715247;86215.56892215561;87676;88472.55763721204;11224.94387528057;11284.774304513863;11360.886391136148;11487.597934072268;11595.652130436101;15901.52295431139;15903.602409939844;15934.9203253983;15947.36210551565;15953.202339883192;7151.499395042401;7494.175640679521;7808.68765249395;7995.36095100503;8366.53984030872;7732.497387669783;7816.726414575576;7816.960915195501;7948.046234451903;8065.475744076616;4679.415073115861;4802.831900883452;4823.2282834746275;4932.21084626458;4962.106820772224;1772.2018308903912;1859.7954225035235;1913.2977688173428;1939.7575303087115;1986.3345779163853;883.9248663863643;987.2398253344884;988.2884323287221;1025.6820385680462;1032.5611615063567;18884.43346699601;19492.512687182934;19513.219471220993;19708.31020914261;20204.595908081756;974.4007435427161;1000.6497725795913;1009.2380252909154;1015.6546774096695;1049.6378749331468;10351.585936562604;10454.68635940923;10854.782904342026;10949.560222581236;11043.613473528549;453109;454995;459111;472475;479210;98025;100322.4983875076;101018;101341;101549;988.1748739802202;1008.688704683908;1020.3520764314713;1046.8953104689622;1053.536443964653;16302.429414970418;17218;17409.21658525344;17728.91135544314;17925.820741792417;39733.20533589254;42005.579944200166;42657.867106645164;42765;43069.138617228105;53065.46934530605;54431.18353224626;55686;56335.43664563302;56542;8534.573271336532;8722.345824063093;8785.692500762443;8795.076516965626;8920.910790892267;12814.423350949417;13344.666383340105;13369.53206637763;13519.256440895846;13538.390772415069;11479.4260286987;11889.227200231957;12073.75852482963;12143.635690929286;12370.690732731753;11519.88480115188;11799.174057816213;11918.901085717602;11985.400729963292;12268.815967760669;11740.119491038377;11877.821832672344;12090.818637720617;12156.696082598006;12239.571614993432;8590.398672092893;8792.384533082623;8799.076097009722;8840.778980525281;9105;10856.565737370416;10945.288556243819;11698.70753231176;11913.463894124612;12334.321612311409 05:10:23 INFO - PROCESS | 13069 | |3;mootools.html;1468.6475245940971;1523.7638166084257;1618.9514314570567;1676.3378465506119;1736.1926704082598;8067.556284404355;8256.793580160498;8622.439541429803;9342.626294948202;9357.67248146315;8706.434081784679;8916.331275154373;8954;9208.171264586186;9216.769580760485;2376.9881150594224;2394.8922298496523;2409.0604664180983;2411.0958390603523;2413.2760171948344;2046.8669536480081;2081.771005189428;2357.57563638545;2370.466645004869;2432.1730611592056;2098.3075585056895;2115.6826476028564;2321.3268152235833;2358.221786810357;2370.8221883358733;2331.895064722083;2338.0764597983703;2340.368100612842;2342.23877239896;2343.1564636730764;2262.4117729390314;2320.7447180810095;2322.674825524428;2324.302709187254;2330.6154484510034;9179.954100229455;9185.540722963891;9186;9186.08139186076;9203;5081.4918508148885;5158.664686795346;5199.454057324002;5214.947850521522;5242.685438873642;3345.2138747394342;3378.864845406181;3522.7357948153735;3530.717542596586;3533.2050288685095;1254.8305978692852;1282.1409655530817;2153.558520503293;2169.294979131773;2172.6958225848393;12933.935330323382;12942.935285323607;12970.481180752759;13045.347732613427;13082.738345233138;4451.087527056946;4575.614045606228;4704.317873908332;4708.552687494683;4784.880377990578;9986.900130998742;10004.049615286536;10004.149647280063;10005.349652272578;10015.69952901414;5258.395284542326;5269.762860671279;5278.577713782888;5281.128613778684;5283.682979021271;4783.306420569068;4799.832005879777;4957.182064959314;4963.478834722375;4965.503449655005;3795.9240815183543;3821.5987321331418;3879.321118804198;3890.06638406786;3936.940945885814;1154.2728081308721;1176.1179115663253;1185.324365111878;1185.8458400407892;1198.5505435461703;717.8815495443298;797.6530209358947;855.4995327733345;888.7022847346083;938.9577568898517;5724.713764311768;5774.566907481955;5823.213866128019;5831.329397119301;6095.6952152392205;488.0799692579513;497.99004019919397;501.88205771643635;506.91889297712925;662.2053535757111;3825.885223443301;4091.672666186698;4158.6673066154635;4164.75011499311;4224.936625950613;11195.720106997227;11603.07175425964;11608.071354291636;11609.88390116088;11612.6516204514;11248.156388270912;11405.828912566318;11489.655310340702;11522.596709115141;11582.826257606142;8459.788505287417;8536.274416674529;8541.572921353907;8546.829063418822;8555.315574754004;5638.661680299195;5674.631148975303;5771.451712087345;5898.085796701518;5906.7341969612335;10371.94814025925;10485.213608979204;10522.894771052192;10560.84158737635;10568.577256909637;10236.129928955825;10581.682549523526;10599.523021463898;10799;10937.890621093688;7721.9227807723455;7896.210378962058;7897.368210543028;7897.763067107996;7900.091489478784;8315.875261870955;8487.236148746642;8551.05938346769;8647.913520864962;8854.867176992222;7836.882446763189;7863.803404914694;7970.800729981797;8016.278534931884;8117.918820811717;7792.376609871083;7864.6854125834325;7893.57915575202;7958.403119766156;8004.95997520032;7329.230430804902;7446.888296675663;7466.618675545042;7468.887966680612;7469.14104877946;5076.517730815566;5097.745112744422;5123.38519377677;5340.839774806762;5436.45635436469;5156.5616922562;5170.656648415349;5179.533841954242;5245.580353571629;5296.496832801032 05:10:23 INFO - PROCESS | 13069 | |4;prototype.html;333.83975691667996;343.97936123832574;347.92867462170256;349.4199628616497;352.2655263775029;5456.1176394144;5476.4249753775775;5487.725613719308;5490.094134467813;5493.368262649797;5316.255724198615;5367.946320536803;5392.460753924605;5408.540274076709;5411.242426060355;3866.709996750241;3994.8402063917483;4142.461480007595;4150.962259435141;4151.149014452045;4160.958390416103;4161.459010328653;4163.355474587536;4172.791360431981;4220.177065472219;4169.249535083699;4190.757944499694;4202.6637868970565;4216.451861258032;4227.323628219486;3914.432407300954;4015.8393664253394;4167.936871248736;4197;4199.6220340169375;3673.3938900081453;3684.7707137181887;3711.721620878445;3717.423799311111;3722.664960153599;5144.845654630367;5151.45909679482;5169.457206993249;5191.506806853343;5213.556847667953;4716.127516409475;4767.690100143514;4772.522747725235;4816.8554943351755;4852.102361063179;5529.474699903502;5543.113101903676;5553.888922221533;5574.191742197399;5610.69141197234;4083.203775263825;4090.5091389033505;4090.897727556805;4104.138130992465;4124.319487284594;5330.600204984642;5515.310586176728;5908.497777688902;5910.468057874812;5911.408859114097;4144.70987030905;4170;4173.24881521323;4180.728252663567;4199.538050814408;4247.915041699211;4261.829526818955;4263.722858014219;4271.765052922057;4271.807768650418;4498.730076195438;4829.2997515360075;4844.346013288161;4867.513248675175;4875.219964805614;5534.861628459321;5596.544898326464;5618.4662457066515;5621.297337832771;5637.069883469182;1435.7128574285184;1446.7612843880643;1450.9637259068397;1459.6642772162463;1463.9634009149856;879.5953861223909;929.8791157149527;935.569637966543;952.1525842000625;956.516958935733;4784.086365453846;4812.5668689818085;4818.349522814446;4819;4833.4441539222735;4990.825321113816;5009.298698182258;5043.3443652325695;5057.418396884332;5085.863937146408;4561.44679381217;4794.472608013116;4805.951940480551;4834.299026641117;4855.757212139379;2670.9332266693486;2689.287338855205;2692.986535067312;2695.811293209496;2701.8784154713085;5196.752779332933;5221.921671174935;5234.7906083756225;5258.50044245796;5290.867728306746;4922.852314430573;5086.567641750493;5127.1540195867265;5153.479723481507;5155.200943853633;5202.635815492879;5208.817691380707;5213;5213.56626927598;5226.817061402832;4958.826441074545;5058.418281897481;5088.440271570198;5109.514596113363;5111.386633603843;4975.875603109806;5014.623903207347;5252.212168174804;5696.658200507983;5706.885862282814;5745.425457454388;5815.9709201453725;5841.561882858888;5971.790987315423;5976.581639285208;4157.459530261047;4184.911922900129;4217.578242175758;4218.957810421856;4219.156168766198;5747.224124743273;5861.128047256728;5881.676737688504;5882.264716910387;5888.4700376966275;5611.775528978795;5625.887482250414;5644.576656750678;5648.4633959773755;5670.894175635712;5493.093639549589;5589.496945274945;5600.879824035128;5608.663480191039;5657.264555607745;5481.314835645544;5601.831945041656;5636.9718151410625;5659.575531835047;5667.943320566742;5637.943620563742;5670.291213598301;5675.801346952837;5679.659220446786;5709.286339207599;5508.91736623943;5511.173324001272;5513.3108361454815;5526.784107496344;5538.393865778879 05:10:23 INFO - PROCESS | 13069 | |5;yui.html;734.8567029429263;756.0926887734717;784.1805313447447;796.9163237860024;847.9152084791519;14194.21931793751;14722.926385368084;14730.263486825643;14756.852431475696;14774.113553186802;14901.627459313495;14978.400863965428;14986.925065374659;15017.399304027853;15285.159316237603;643.2570381209698;661.0117873778707;778.2839787395582;788.2550989315099;797.872340425532;783.6356094416095;788.5465857132143;788.9644965976516;789.0334340432952;789.234442590689;852.7100785732849;857.1300130367682;858.9699360522383;859.5831021954347;859.669027424443;683.3474032299142;699.9580025198479;831.4387788242972;845.3152946113606;849.668629234599;616.6608365399057;617.213053356969;618.4001518527022;623.6133597169738;624.6283461340495;14291.714165716732;14378.496752613608;14400.207988560625;14416.85583144176;14417.71164576713;14012.22932738699;14294.928525357307;14396.208208548525;14640.853591464162;14696.338664760111;12835.101542892002;12845.165064270792;12988;13024.609261722164;13191.538296159684;4291.35629655551;4373.934390984138;4394.2969124939855;4439.800208990603;4487.551244875486;82657;83382.58308708479;83671;83786.58106709427;86265.56867215624;10492.842607360895;10608.20438467118;10644.361338319726;10747.032767050847;10861.07680847121;19952.50118747043;20019.29932452386;20051.799482004993;20532.486687832923;20577.485562860747;8553.914460855312;8589.680786187695;8668.089850565726;8758.86861697075;8777.210051095431;4450.755208463501;4514.6388288936805;4676.55572720591;4680.882977925511;4778.685861388077;3474.2356681530023;3642.5446819147605;3672.632736726306;3675.632436756303;3677.2093999790172;638.3138126514016;645.8191706322237;668.3850857211377;715.377621469325;715.9606221657859;603.1766638538363;755.3994574313324;756.255088737593;766.3332900376708;770.260549872129;9758.219342452587;9863.556139973718;10458.163346932228;10544.156467482737;10576.471176441148;621.471749013335;657.4247533408268;736.628002858553;749.1684230504136;755.4183278875236;4613.377194078824;4746.5490778376;4772.0694464579765;4784.976075119602;4806.8077276909235;111577;112642;113926;114699.42650286695;116519.41740291414;94907;95776.52111739396;95974;97403.23370896511;97616;83854;85364;86353;86832.99999999873;89293.55353223192;10213.897861021294;10459.477026148814;10716.196285278791;10753.838692419777;10830.891691083303;47479.287810683556;48232;50021.249681254085;50309.24536132034;50666.74666626643;72856;76763.2323676756;77523;77730.00429961331;78154;12141.575044873387;12402.069844761498;12663.746725065263;12714.427850746553;13232.801507977581;14506.419743210154;14542.927285363505;14699.853001469848;14761.188134652697;15456.768148478006;13694.93152534231;13894.374753136335;14090.78863817023;14382.928085359506;14743.557693269218;13381.19712817234;13536.526221582197;13571.86428135706;13970.79043814364;14140.363683634056;13571.79642305386;13995.720085598434;14368.209748463934;15161.967532048333;15310.846891531388;11406.942965285121;11584.594539191425;12002.579909703118;12200.755984880076;12730.809037864625;10952.56189752401;12149.635510934686;12194.57318993831;12365.814512782135;12471.875281247072 05:10:23 INFO - PROCESS | 13069 | __end_tp_report 05:10:23 INFO - PROCESS | 13069 | __start_cc_report 05:10:23 INFO - PROCESS | 13069 | _x_x_mozilla_cycle_collect,1715 05:10:23 INFO - PROCESS | 13069 | __end_cc_report 05:10:23 INFO - PROCESS | 13069 | __startTimestamp1474373423610__endTimestamp 05:10:23 INFO - PROCESS | 13069 | 05:10:23 INFO - PROCESS | 13069 | ------- Summary: start ------- 05:10:23 INFO - PROCESS | 13069 | Number of tests: 6 05:10:23 INFO - PROCESS | 13069 | 05:10:23 INFO - PROCESS | 13069 | [#0] dojo.html Cycles:165 Average:5349.30 Median:5607.50 stddev:604.53 (10.8%) stddev-sans-first:579.35 05:10:23 INFO - PROCESS | 13069 | Values: 3070.4 3095.1 3100.9 3133.5 3155.7 5279.3 5309.2 5363.8 5376.9 5417.9 5346.2 5397.4 5415.6 5417.8 5418.0 4884.2 4958.2 4964.2 4970.2 4972.8 4911.6 4970.7 5034.4 5038.1 5092.7 4965.8 5009.0 5012.5 5026.4 5030.2 4774.9 4819.5 4836.7 4892.7 4984.4 4816.4 4825.2 4837.8 4863.6 4900.5 5249.5 5260.1 5292.7 5312.3 5339.0 5577.2 5594.8 5676.0 5680.7 5684.2 5730.3 5752.2 5794.4 5847.8 5896.3 5416.8 5421.8 5422.3 5427.1 5434.8 5678.8 5735.6 5922.3 5923.0 5924.9 5669.3 5703.3 5703.6 5737.4 5809.8 5776.9 5819.0 5833.4 5833.9 5835.4 5555.2 5754.6 5825.2 5826.6 5831.6 5617.5 5619.0 5640.6 5641.4 5699.4 4381.0 4382.6 4392.1 4430.1 4431.9 3894.6 3917.1 3924.9 3927.8 3969.3 5315.7 5349.6 5358.8 5371.2 5397.2 5762.2 5765.4 5814.2 5827.3 5830.2 5408.2 5434.2 5461.6 5463.0 5463.1 4798.4 4838.9 4849.3 4882.3 4954.7 5832.0 5862.3 5878.2 5899.0 5917.3 5676.3 5696.4 5703.9 5704.2 5711.2 5846.0 5879.2 5880.7 5887.4 5892.7 5637.0 5645.2 5678.3 5681.5 5780.0 5762.8 5802.6 5814.2 5818.2 5823.8 5601.5 5634.5 5685.0 5697.3 5697.8 5627.4 5687.2 5709.0 5710.6 5715.7 5671.0 5679.7 5686.7 5687.0 5689.0 5724.3 5728.7 5738.7 5739.9 5743.8 5433.2 5488.5 5498.9 5607.5 5626.2 05:10:23 INFO - PROCESS | 13069 | 05:10:23 INFO - PROCESS | 13069 | [#1] ext.html Cycles:180 Average:61847.62 Median:42284.58 stddev:121045.55 (286.3%) stddev-sans-first:121297.56 05:10:23 INFO - PROCESS | 13069 | Values: 527.5 580.0 581.9 590.9 604.4 31254.0 32000.5 34159.5 34165.8 43178.4 44858.3 45356.5 45493.8 45562.8 45584.8 8895.8 9058.2 9059.8 9062.5 9066.0 7940.2 8112.6 8879.9 8979.3 10565.3 10581.2 10939.9 10957.8 10958.6 10962.7 10838.5 10875.5 10885.9 10890.8 10906.7 8007.2 8219.5 10278.4 10630.3 10691.3 41915.0 42260.6 42273.2 42283.6 42311.4 3591.3 3660.2 4062.0 4146.7 4191.6 29687.1 30086.7 31076.7 32387.2 33635.0 4296.1 4327.6 4337.7 4381.0 4383.7 342921.0 345375.0 345840.0 347264.0 349163.3 62334.4 63107.7 63141.0 63175.4 63277.4 66776.7 67033.3 67207.6 67732.0 67763.3 16427.6 16575.8 16715.7 16745.7 16766.2 11787.2 11809.2 11877.8 11896.7 11957.3 17160.4 17526.0 19310.7 19545.6 20170.3 7342.2 7387.0 7561.6 7697.9 7752.9 945.4 1029.6 1049.1 1064.4 1091.3 31508.2 31803.0 31949.8 31993.0 32214.8 1063.7 1070.6 1070.8 1101.5 1139.1 16720.8 16853.3 16858.2 16944.2 16997.0 555083.0 556457.0 561360.0 561868.0 562654.0 58271.4 58841.4 59014.0 59042.4 59129.0 1323.8 1357.7 1371.6 1371.7 1374.0 29715.1 30941.0 31026.0 31420.8 31513.5 51938.0 52351.0 52427.5 52437.0 52491.7 436830.0 438797.0 439571.0 439842.0 440057.0 35850.0 36288.5 43096.0 43159.1 43234.0 47517.5 47804.0 48076.0 48686.5 48940.5 39496.0 42812.6 43573.6 44768.6 46163.8 42115.0 43149.4 43405.0 43458.1 45651.8 41908.4 42285.6 43439.6 43535.6 43640.3 42532.0 43230.6 43372.6 43385.8 43965.6 28156.3 28266.7 28272.4 29288.6 29536.9 05:10:23 INFO - PROCESS | 13069 | 05:10:23 INFO - PROCESS | 13069 | [#2] jquery.html Cycles:180 Average:28366.97 Median:11095.47 stddev:76959.68 (693.6%) stddev-sans-first:77146.90 05:10:23 INFO - PROCESS | 13069 | Values: 387.7 388.7 399.5 402.1 416.1 14069.4 16435.4 17964.3 19661.0 19683.0 14819.0 16512.6 17590.2 18141.5 18300.8 5033.8 5171.4 5213.9 5234.5 5255.3 5098.8 5257.2 5289.8 5293.3 5335.5 5223.1 5272.1 5275.9 5276.7 5281.9 5264.3 5265.4 5265.5 5267.1 5312.5 5147.1 5152.5 5156.1 5175.2 5208.8 13962.4 14122.2 19498.3 22902.2 23142.2 2324.0 2403.5 2492.9 2509.6 2855.3 10046.8 10237.5 10401.0 10919.2 10932.0 1861.0 1900.6 1918.1 1938.5 1951.8 82548.2 86114.6 86215.6 87676.0 88472.6 11224.9 11284.8 11360.9 11487.6 11595.7 15901.5 15903.6 15934.9 15947.4 15953.2 7151.5 7494.2 7808.7 7995.4 8366.5 7732.5 7816.7 7817.0 7948.0 8065.5 4679.4 4802.8 4823.2 4932.2 4962.1 1772.2 1859.8 1913.3 1939.8 1986.3 883.9 987.2 988.3 1025.7 1032.6 18884.4 19492.5 19513.2 19708.3 20204.6 974.4 1000.6 1009.2 1015.7 1049.6 10351.6 10454.7 10854.8 10949.6 11043.6 453109.0 454995.0 459111.0 472475.0 479210.0 98025.0 100322.5 101018.0 101341.0 101549.0 988.2 1008.7 1020.4 1046.9 1053.5 16302.4 17218.0 17409.2 17728.9 17925.8 39733.2 42005.6 42657.9 42765.0 43069.1 53065.5 54431.2 55686.0 56335.4 56542.0 8534.6 8722.3 8785.7 8795.1 8920.9 12814.4 13344.7 13369.5 13519.3 13538.4 11479.4 11889.2 12073.8 12143.6 12370.7 11519.9 11799.2 11918.9 11985.4 12268.8 11740.1 11877.8 12090.8 12156.7 12239.6 8590.4 8792.4 8799.1 8840.8 9105.0 10856.6 10945.3 11698.7 11913.5 12334.3 05:10:23 INFO - PROCESS | 13069 | 05:10:23 INFO - PROCESS | 13069 | [#3] mootools.html Cycles:180 Average:5924.37 Median:2643.96 stddev:3445.67 (130.3%) stddev-sans-first:3439.07 05:10:23 INFO - PROCESS | 13069 | Values: 1468.6 1523.8 1619.0 1676.3 1736.2 8067.6 8256.8 8622.4 9342.6 9357.7 8706.4 8916.3 8954.0 9208.2 9216.8 2377.0 2394.9 2409.1 2411.1 2413.3 2046.9 2081.8 2357.6 2370.5 2432.2 2098.3 2115.7 2321.3 2358.2 2370.8 2331.9 2338.1 2340.4 2342.2 2343.2 2262.4 2320.7 2322.7 2324.3 2330.6 9180.0 9185.5 9186.0 9186.1 9203.0 5081.5 5158.7 5199.5 5214.9 5242.7 3345.2 3378.9 3522.7 3530.7 3533.2 1254.8 1282.1 2153.6 2169.3 2172.7 12933.9 12942.9 12970.5 13045.3 13082.7 4451.1 4575.6 4704.3 4708.6 4784.9 9986.9 10004.0 10004.1 10005.3 10015.7 5258.4 5269.8 5278.6 5281.1 5283.7 4783.3 4799.8 4957.2 4963.5 4965.5 3795.9 3821.6 3879.3 3890.1 3936.9 1154.3 1176.1 1185.3 1185.8 1198.6 717.9 797.7 855.5 888.7 939.0 5724.7 5774.6 5823.2 5831.3 6095.7 488.1 498.0 501.9 506.9 662.2 3825.9 4091.7 4158.7 4164.8 4224.9 11195.7 11603.1 11608.1 11609.9 11612.7 11248.2 11405.8 11489.7 11522.6 11582.8 8459.8 8536.3 8541.6 8546.8 8555.3 5638.7 5674.6 5771.5 5898.1 5906.7 10371.9 10485.2 10522.9 10560.8 10568.6 10236.1 10581.7 10599.5 10799.0 10937.9 7721.9 7896.2 7897.4 7897.8 7900.1 8315.9 8487.2 8551.1 8647.9 8854.9 7836.9 7863.8 7970.8 8016.3 8117.9 7792.4 7864.7 7893.6 7958.4 8005.0 7329.2 7446.9 7466.6 7468.9 7469.1 5076.5 5097.7 5123.4 5340.8 5436.5 5156.6 5170.7 5179.5 5245.6 5296.5 05:10:23 INFO - PROCESS | 13069 | 05:10:23 INFO - PROCESS | 13069 | [#4] prototype.html Cycles:180 Average:4588.57 Median:5087.50 stddev:1326.91 (26.1%) stddev-sans-first:1291.63 05:10:23 INFO - PROCESS | 13069 | Values: 333.8 344.0 347.9 349.4 352.3 5456.1 5476.4 5487.7 5490.1 5493.4 5316.3 5367.9 5392.5 5408.5 5411.2 3866.7 3994.8 4142.5 4151.0 4151.1 4161.0 4161.5 4163.4 4172.8 4220.2 4169.2 4190.8 4202.7 4216.5 4227.3 3914.4 4015.8 4167.9 4197.0 4199.6 3673.4 3684.8 3711.7 3717.4 3722.7 5144.8 5151.5 5169.5 5191.5 5213.6 4716.1 4767.7 4772.5 4816.9 4852.1 5529.5 5543.1 5553.9 5574.2 5610.7 4083.2 4090.5 4090.9 4104.1 4124.3 5330.6 5515.3 5908.5 5910.5 5911.4 4144.7 4170.0 4173.2 4180.7 4199.5 4247.9 4261.8 4263.7 4271.8 4271.8 4498.7 4829.3 4844.3 4867.5 4875.2 5534.9 5596.5 5618.5 5621.3 5637.1 1435.7 1446.8 1451.0 1459.7 1464.0 879.6 929.9 935.6 952.2 956.5 4784.1 4812.6 4818.3 4819.0 4833.4 4990.8 5009.3 5043.3 5057.4 5085.9 4561.4 4794.5 4806.0 4834.3 4855.8 2670.9 2689.3 2693.0 2695.8 2701.9 5196.8 5221.9 5234.8 5258.5 5290.9 4922.9 5086.6 5127.2 5153.5 5155.2 5202.6 5208.8 5213.0 5213.6 5226.8 4958.8 5058.4 5088.4 5109.5 5111.4 4975.9 5014.6 5252.2 5696.7 5706.9 5745.4 5816.0 5841.6 5971.8 5976.6 4157.5 4184.9 4217.6 4219.0 4219.2 5747.2 5861.1 5881.7 5882.3 5888.5 5611.8 5625.9 5644.6 5648.5 5670.9 5493.1 5589.5 5600.9 5608.7 5657.3 5481.3 5601.8 5637.0 5659.6 5667.9 5637.9 5670.3 5675.8 5679.7 5709.3 5508.9 5511.2 5513.3 5526.8 5538.4 05:10:23 INFO - PROCESS | 13069 | 05:10:23 INFO - PROCESS | 13069 | [#5] yui.html Cycles:180 Average:21004.21 Median:4417.05 stddev:30070.37 (680.8%) stddev-sans-first:30116.21 05:10:23 INFO - PROCESS | 13069 | Values: 734.9 756.1 784.2 796.9 847.9 14194.2 14722.9 14730.3 14756.9 14774.1 14901.6 14978.4 14986.9 15017.4 15285.2 643.3 661.0 778.3 788.3 797.9 783.6 788.5 789.0 789.0 789.2 852.7 857.1 859.0 859.6 859.7 683.3 700.0 831.4 845.3 849.7 616.7 617.2 618.4 623.6 624.6 14291.7 14378.5 14400.2 14416.9 14417.7 14012.2 14294.9 14396.2 14640.9 14696.3 12835.1 12845.2 12988.0 13024.6 13191.5 4291.4 4373.9 4394.3 4439.8 4487.6 82657.0 83382.6 83671.0 83786.6 86265.6 10492.8 10608.2 10644.4 10747.0 10861.1 19952.5 20019.3 20051.8 20532.5 20577.5 8553.9 8589.7 8668.1 8758.9 8777.2 4450.8 4514.6 4676.6 4680.9 4778.7 3474.2 3642.5 3672.6 3675.6 3677.2 638.3 645.8 668.4 715.4 716.0 603.2 755.4 756.3 766.3 770.3 9758.2 9863.6 10458.2 10544.2 10576.5 621.5 657.4 736.6 749.2 755.4 4613.4 4746.5 4772.1 4785.0 4806.8 111577.0 112642.0 113926.0 114699.4 116519.4 94907.0 95776.5 95974.0 97403.2 97616.0 83854.0 85364.0 86353.0 86833.0 89293.6 10213.9 10459.5 10716.2 10753.8 10830.9 47479.3 48232.0 50021.2 50309.2 50666.7 72856.0 76763.2 77523.0 77730.0 78154.0 12141.6 12402.1 12663.7 12714.4 13232.8 14506.4 14542.9 14699.9 14761.2 15456.8 13694.9 13894.4 14090.8 14382.9 14743.6 13381.2 13536.5 13571.9 13970.8 14140.4 13571.8 13995.7 14368.2 15162.0 15310.8 11406.9 11584.6 12002.6 12200.8 12730.8 10952.6 12149.6 12194.6 12365.8 12471.9 05:10:23 INFO - PROCESS | 13069 | -------- Summary: end -------- 05:10:23 INFO - PROCESS | 13069 | 05:10:24 INFO - TEST-INFO | 13069: exit 0 05:10:24 INFO - TEST-OK | dromaeo_css | took 1116370ms 05:10:24 INFO - TEST-START | kraken 05:10:24 INFO - operating with platform_type : linux_ 05:10:24 INFO - Initialising browser for kraken test... 05:10:24 INFO - TEST-INFO | started process 13372 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFqoJVu/profile http://localhost:46596/getInfo.html) 05:10:28 INFO - PROCESS | 13372 | __metrics Screen width/height:1600/1200 05:10:28 INFO - PROCESS | 13372 | colorDepth:24 05:10:28 INFO - PROCESS | 13372 | Browser inner width/height: 1024/697 05:10:28 INFO - PROCESS | 13372 | __metrics 05:10:29 INFO - PROCESS | 13372 | 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" 05:10:29 INFO - PROCESS | 13372 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:10:29 INFO - PROCESS | 13372 | console.error: PushService: 05:10:29 INFO - PROCESS | 13372 | stateChangeProcessEnqueue: Error transitioning state 05:10:29 INFO - PROCESS | 13372 | UnknownError 05:10:30 INFO - TEST-INFO | 13372: exit 0 05:10:30 INFO - Browser initialized. 05:10:30 INFO - Running cycle 1/1 for kraken test... 05:10:30 INFO - TEST-INFO | started process 13454 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFqoJVu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:10:42 INFO - PROCESS | 13454 | RSS: Main: 173244416 05:10:42 INFO - PROCESS | 13454 | 05:10:45 INFO - PROCESS | 13454 | Cycle 1(1): loaded http://localhost:46596/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:46596/tests/kraken/driver.html?testName=audio-beat-detection) 05:10:46 INFO - PROCESS | 13454 | RSS: Main: 232611840 05:10:46 INFO - PROCESS | 13454 | 05:10:48 INFO - PROCESS | 13454 | Cycle 1(1): loaded http://localhost:46596/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:46596/tests/kraken/driver.html?testName=audio-dft) 05:10:49 INFO - PROCESS | 13454 | RSS: Main: 216375296 05:10:49 INFO - PROCESS | 13454 | 05:10:52 INFO - PROCESS | 13454 | Cycle 1(1): loaded http://localhost:46596/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:46596/tests/kraken/driver.html?testName=audio-fft) 05:10:52 INFO - PROCESS | 13454 | RSS: Main: 298323968 05:10:52 INFO - PROCESS | 13454 | 05:10:55 INFO - PROCESS | 13454 | Cycle 1(1): loaded http://localhost:46596/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:46596/tests/kraken/driver.html?testName=audio-oscillator) 05:10:55 INFO - PROCESS | 13454 | RSS: Main: 208478208 05:10:55 INFO - PROCESS | 13454 | 05:10:57 INFO - PROCESS | 13454 | Cycle 1(1): loaded http://localhost:46596/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:46596/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:10:58 INFO - PROCESS | 13454 | RSS: Main: 212041728 05:10:58 INFO - PROCESS | 13454 | 05:11:01 INFO - PROCESS | 13454 | Cycle 1(1): loaded http://localhost:46596/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:46596/tests/kraken/driver.html?testName=imaging-darkroom) 05:11:01 INFO - PROCESS | 13454 | RSS: Main: 248786944 05:11:01 INFO - PROCESS | 13454 | 05:11:05 INFO - PROCESS | 13454 | Cycle 1(1): loaded http://localhost:46596/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:46596/tests/kraken/driver.html?testName=imaging-desaturate) 05:11:05 INFO - PROCESS | 13454 | RSS: Main: 247885824 05:11:05 INFO - PROCESS | 13454 | 05:11:08 INFO - PROCESS | 13454 | Cycle 1(1): loaded http://localhost:46596/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:46596/tests/kraken/driver.html?testName=json-parse-financial) 05:11:09 INFO - PROCESS | 13454 | RSS: Main: 247476224 05:11:09 INFO - PROCESS | 13454 | 05:11:10 INFO - PROCESS | 13454 | Cycle 1(1): loaded http://localhost:46596/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:46596/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:11:11 INFO - PROCESS | 13454 | RSS: Main: 209784832 05:11:11 INFO - PROCESS | 13454 | 05:11:13 INFO - PROCESS | 13454 | Cycle 1(1): loaded http://localhost:46596/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:46596/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:11:13 INFO - PROCESS | 13454 | RSS: Main: 216223744 05:11:13 INFO - PROCESS | 13454 | 05:11:15 INFO - PROCESS | 13454 | Cycle 1(1): loaded http://localhost:46596/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:46596/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:11:16 INFO - PROCESS | 13454 | RSS: Main: 222027776 05:11:16 INFO - PROCESS | 13454 | 05:11:19 INFO - PROCESS | 13454 | Cycle 1(1): loaded http://localhost:46596/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:46596/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:11:19 INFO - PROCESS | 13454 | RSS: Main: 223305728 05:11:19 INFO - PROCESS | 13454 | 05:11:22 INFO - PROCESS | 13454 | Cycle 1(1): loaded http://localhost:46596/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:46596/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:11:23 INFO - PROCESS | 13454 | RSS: Main: 221446144 05:11:23 INFO - PROCESS | 13454 | 05:11:24 INFO - PROCESS | 13454 | Cycle 1(1): loaded http://localhost:46596/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:46596/tests/kraken/driver.html?testName=ai-astar) 05:11:24 INFO - PROCESS | 13454 | RSS: Main: 272388096 05:11:24 INFO - PROCESS | 13454 | 05:11:24 INFO - PROCESS | 13454 | __start_tp_report 05:11:24 INFO - PROCESS | 13454 | _x_x_mozilla_page_load 05:11:24 INFO - PROCESS | 13454 | _x_x_mozilla_page_load_details 05:11:24 INFO - PROCESS | 13454 | |i|pagename|runs| 05:11:24 INFO - PROCESS | 13454 | |0;ai-astar;108;109;111;110;110;111;109;110;110;110 05:11:24 INFO - PROCESS | 13454 | |1;audio-beat-detection;147;120;119;165;119;122;117;116;143;117 05:11:24 INFO - PROCESS | 13454 | |2;audio-dft;161;160;162;162;160;163;162;161;160;160 05:11:24 INFO - PROCESS | 13454 | |3;audio-fft;76;76;75;108;77;76;75;77;104;76 05:11:24 INFO - PROCESS | 13454 | |4;audio-oscillator;82;82;131;81;81;81;110;80;80;111 05:11:24 INFO - PROCESS | 13454 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;116;117;116 05:11:24 INFO - PROCESS | 13454 | |6;imaging-darkroom;146;146;145;146;144;145;145;146;146;145 05:11:24 INFO - PROCESS | 13454 | |7;imaging-desaturate;95;96;95;95;96;95;95;95;95;95 05:11:24 INFO - PROCESS | 13454 | |8;json-parse-financial;52;52;53;51;52;53;53;62;53;54 05:11:24 INFO - PROCESS | 13454 | |9;json-stringify-tinderbox;60;62;61;61;61;60;61;61;61;61 05:11:24 INFO - PROCESS | 13454 | |10;stanford-crypto-aes;87;82;79;78;78;78;83;77;79;79 05:11:24 INFO - PROCESS | 13454 | |11;stanford-crypto-ccm;125;144;127;130;129;127;131;138;171;135 05:11:24 INFO - PROCESS | 13454 | |12;stanford-crypto-pbkdf2;169;162;159;159;162;163;165;160;167;162 05:11:24 INFO - PROCESS | 13454 | |13;stanford-crypto-sha256-iterative;67;65;65;65;65;64;67;64;64;65 05:11:24 INFO - PROCESS | 13454 | __end_tp_report 05:11:24 INFO - PROCESS | 13454 | __start_cc_report 05:11:24 INFO - PROCESS | 13454 | _x_x_mozilla_cycle_collect,1649 05:11:24 INFO - PROCESS | 13454 | __end_cc_report 05:11:24 INFO - PROCESS | 13454 | __startTimestamp1474373484969__endTimestamp 05:11:24 INFO - PROCESS | 13454 | 05:11:24 INFO - PROCESS | 13454 | ------- Summary: start ------- 05:11:24 INFO - PROCESS | 13454 | Number of tests: 14 05:11:24 INFO - PROCESS | 13454 | 05:11:24 INFO - PROCESS | 13454 | [#0] ai-astar Cycles:10 Average:109.80 Median:110.00 stddev:0.92 (0.8%) stddev-sans-first:0.71 05:11:24 INFO - PROCESS | 13454 | Values: 108.0 109.0 111.0 110.0 110.0 111.0 109.0 110.0 110.0 110.0 05:11:24 INFO - PROCESS | 13454 | 05:11:24 INFO - PROCESS | 13454 | [#1] audio-beat-detection Cycles:10 Average:128.50 Median:121.00 stddev:17.00 (14.0%) stddev-sans-first:16.66 05:11:24 INFO - PROCESS | 13454 | Values: 147.0 120.0 119.0 165.0 119.0 122.0 117.0 116.0 143.0 117.0 05:11:24 INFO - PROCESS | 13454 | 05:11:24 INFO - PROCESS | 13454 | [#2] audio-dft Cycles:10 Average:161.10 Median:161.50 stddev:1.10 (0.7%) stddev-sans-first:1.17 05:11:24 INFO - PROCESS | 13454 | Values: 161.0 160.0 162.0 162.0 160.0 163.0 162.0 161.0 160.0 160.0 05:11:24 INFO - PROCESS | 13454 | 05:11:24 INFO - PROCESS | 13454 | [#3] audio-fft Cycles:10 Average:82.00 Median:76.00 stddev:12.70 (16.7%) stddev-sans-first:13.29 05:11:24 INFO - PROCESS | 13454 | Values: 76.0 76.0 75.0 108.0 77.0 76.0 75.0 77.0 104.0 76.0 05:11:24 INFO - PROCESS | 13454 | 05:11:24 INFO - PROCESS | 13454 | [#4] audio-oscillator Cycles:10 Average:91.90 Median:81.00 stddev:18.43 (22.8%) stddev-sans-first:19.20 05:11:24 INFO - PROCESS | 13454 | Values: 82.0 82.0 131.0 81.0 81.0 81.0 110.0 80.0 80.0 111.0 05:11:24 INFO - PROCESS | 13454 | 05:11:24 INFO - PROCESS | 13454 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 05:11:24 INFO - PROCESS | 13454 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 116.0 117.0 116.0 05:11:24 INFO - PROCESS | 13454 | 05:11:24 INFO - PROCESS | 13454 | [#6] imaging-darkroom Cycles:10 Average:145.40 Median:146.00 stddev:0.70 (0.5%) stddev-sans-first:0.71 05:11:24 INFO - PROCESS | 13454 | Values: 146.0 146.0 145.0 146.0 144.0 145.0 145.0 146.0 146.0 145.0 05:11:24 INFO - PROCESS | 13454 | 05:11:24 INFO - PROCESS | 13454 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 05:11:24 INFO - PROCESS | 13454 | Values: 95.0 96.0 95.0 95.0 96.0 95.0 95.0 95.0 95.0 95.0 05:11:24 INFO - PROCESS | 13454 | 05:11:24 INFO - PROCESS | 13454 | [#8] json-parse-financial Cycles:10 Average:53.50 Median:53.00 stddev:3.10 (5.8%) stddev-sans-first:3.24 05:11:24 INFO - PROCESS | 13454 | Values: 52.0 52.0 53.0 51.0 52.0 53.0 53.0 62.0 53.0 54.0 05:11:24 INFO - PROCESS | 13454 | 05:11:24 INFO - PROCESS | 13454 | [#9] json-stringify-tinderbox Cycles:10 Average:60.90 Median:61.00 stddev:0.57 (0.9%) stddev-sans-first:0.50 05:11:24 INFO - PROCESS | 13454 | Values: 60.0 62.0 61.0 61.0 61.0 60.0 61.0 61.0 61.0 61.0 05:11:24 INFO - PROCESS | 13454 | 05:11:24 INFO - PROCESS | 13454 | [#10] stanford-crypto-aes Cycles:10 Average:80.00 Median:79.00 stddev:3.09 (3.9%) stddev-sans-first:1.99 05:11:24 INFO - PROCESS | 13454 | Values: 87.0 82.0 79.0 78.0 78.0 78.0 83.0 77.0 79.0 79.0 05:11:24 INFO - PROCESS | 13454 | 05:11:24 INFO - PROCESS | 13454 | [#11] stanford-crypto-ccm Cycles:10 Average:135.70 Median:133.00 stddev:13.69 (10.3%) stddev-sans-first:13.96 05:11:24 INFO - PROCESS | 13454 | Values: 125.0 144.0 127.0 130.0 129.0 127.0 131.0 138.0 171.0 135.0 05:11:24 INFO - PROCESS | 13454 | 05:11:24 INFO - PROCESS | 13454 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.80 Median:162.50 stddev:3.33 (2.0%) stddev-sans-first:2.67 05:11:24 INFO - PROCESS | 13454 | Values: 169.0 162.0 159.0 159.0 162.0 163.0 165.0 160.0 167.0 162.0 05:11:24 INFO - PROCESS | 13454 | 05:11:24 INFO - PROCESS | 13454 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.10 Median:65.00 stddev:1.10 (1.7%) stddev-sans-first:0.93 05:11:24 INFO - PROCESS | 13454 | Values: 67.0 65.0 65.0 65.0 65.0 64.0 67.0 64.0 64.0 65.0 05:11:24 INFO - PROCESS | 13454 | -------- Summary: end -------- 05:11:24 INFO - PROCESS | 13454 | 05:11:26 INFO - TEST-INFO | 13454: exit 0 05:11:26 INFO - TEST-OK | kraken | took 61597ms 05:11:26 INFO - SUITE-END | took 1177s 05:11:26 INFO - Completed test suite (00:19:38) 05:11:26 INFO - javascript benchmark 05:11:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3070.3705740323235, 3095.148834070632, 3100.922476938076, 3133.5299705044254, 3155.6528781833995, 5279.340082489689, 5309.17707755298, 5363.758630861611, 5376.865578360543, 5417.918731219035, 5346.224797404374, 5397.433269506704, 5415.566754659628, 5417.810376636819, 5418.024755544, 4884.218525035995, 4958.206686930092, 4964.205727083668, 4970.155073637472, 4972.825951091712, 4911.582515486188, 4970.701757894538, 5034.370703662043, 5038.093143234217, 5092.719900405476, 4965.826196083156, 5009.0482808266315, 5012.52381023804, 5026.371703537058, 5030.195168772997, 4774.904501909977, 4819.49395313494, 4836.709797412132, 4892.730899800509, 4984.37695288089, 4816.4220293564995, 4825.227963525819, 4837.782299796482, 4863.562279394836, 4900.534449227318, 5249.501297376743, 5260.053190425722, 5292.655977361459, 5312.2828418163435, 5338.97330513345, 5577.247071645316, 5594.804181853616, 5676.035074037382, 5680.687562184078, 5684.204211410406, 5730.312362516483, 5752.194692742977, 5794.362620111784, 5847.8012007538355, 5896.3219229788265, 5416.83749487516, 5421.783128674888, 5422.267993820823, 5427.1316589345515, 5434.75543600535, 5678.772849086073, 5735.627184233012, 5922.289325280908, 5923.0, 5924.851878703067, 5669.34802497718, 5703.344115426696, 5703.572232082527, 5737.3688894221605, 5809.767609295581, 5776.884462310731, 5819.010768169422, 5833.416658334133, 5833.854153646193, 5835.35811060783, 5555.194496797942, 5754.625949313281, 5825.242718446575, 5826.621269617462, 5831.62094463867, 5617.466340697627, 5618.971905140448, 5640.605157639008, 5641.40765219646, 5699.373068962494, 4381.036172042146, 4382.583654552813, 4392.077663690597, 4430.091831174602, 4431.911361772746, 3894.6105389461395, 3917.0794863207125, 3924.941125883114, 3927.842886284574, 3969.325214713506, 5315.73421328932, 5349.5720342372515, 5358.758855851496, 5371.248025276464, 5397.217403476552, 5762.2221000164445, 5765.423457654201, 5814.156947242626, 5827.3298570664065, 5830.212921255661, 5408.161734931092, 5434.239206511092, 5461.590380721462, 5463.0, 5463.071277882769, 4798.424189097331, 4838.903221935612, 4849.321095046696, 4882.292067650171, 4954.727489988085, 5832.0, 5862.296524417097, 5878.177055212188, 5899.0, 5917.260342457107, 5676.318841738853, 5696.401877802937, 5703.885922281448, 5704.172894930129, 5711.200431939615, 5845.970770146292, 5879.235699359056, 5880.735366908414, 5887.381824908323, 5892.734826932712, 5637.041702910433, 5645.209670646195, 5678.346990096192, 5681.460261275172, 5780.0, 5762.7694892203845, 5802.593818432669, 5814.244148260868, 5818.214541036906, 5823.7670493181495, 5601.523870470893, 5634.549236061187, 5685.0, 5697.316322041382, 5697.800576979786, 5627.380988091225, 5687.20379146928, 5708.972384970745, 5710.600257981901, 5715.685637289823, 5671.0, 5679.659220446786, 5686.71566421669, 5686.9715651421475, 5689.0, 5724.284464441945, 5728.684922329312, 5738.68437235956, 5739.9139012914, 5743.798967036134, 5433.212184233343, 5488.506034456918, 5498.945010549844, 5607.495325420732, 5626.1842032905815], "value": 5308.4638756943095, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [527.4769172106368, 580.0487200990376, 581.8574449259931, 590.8552404660859, 604.4106995679214, 31254.0, 32000.519992200105, 34159.48760768591, 34165.82917085411, 43178.35232471507, 44858.32712509315, 45356.546434535725, 45493.77253113738, 45562.772186139104, 45584.772076139656, 8895.822083558325, 9058.23005044572, 9059.818803623924, 9062.546872656374, 9066.0, 7940.166282540336, 8112.60678099959, 8879.866801997976, 8979.326550508706, 10565.260431769779, 10581.206409519278, 10939.945300273528, 10957.780844383107, 10958.56165753369, 10962.671119866416, 10838.512266948006, 10875.45622718861, 10885.945570272177, 10890.782184356347, 10906.727331816728, 8007.1992800720045, 8219.506829590244, 10278.383297002203, 10630.255882088257, 10691.251612387136, 41915.0, 42260.56264092288, 42273.154536909395, 42283.57716422827, 42311.36532952008, 3591.281743651254, 3660.1764602964386, 4061.97969010156, 4146.730462519957, 4191.559886211965, 29687.132909778928, 30086.69913300861, 31076.689233107605, 32387.190320241945, 33635.0, 4296.140771845612, 4327.6105150536605, 4337.652987760972, 4380.956190438087, 4383.693141480097, 342921.0, 345375.0, 345840.0, 347264.0, 349163.25418373, 62334.37665623286, 63107.68446157832, 63141.0, 63175.368246316946, 63277.36722632807, 66776.66611667008, 67033.3296667027, 67207.6396180189, 67732.0, 67763.3223667757, 16427.58931026734, 16575.83424165767, 16715.749263761052, 16745.665086698198, 16766.161691915357, 11787.174897757075, 11809.173357864867, 11877.76244475123, 11896.702582435508, 11957.282563046245, 17160.39938602143, 17526.0, 19310.71033934492, 19545.624579166448, 20170.29403970854, 7342.15565209997, 7387.0, 7561.584112873845, 7697.923020769721, 7752.883706744404, 945.4091193004373, 1029.5675816157081, 1049.1344640671325, 1064.3773392565436, 1091.3015669971226, 31508.212294692818, 31803.0, 31949.84025079906, 31993.0, 32214.83892580569, 1063.691529456449, 1070.6145787516489, 1070.7858428314369, 1101.515333253366, 1139.1399493382444, 16720.832791672172, 16853.325866965184, 16858.241379137722, 16944.15279236058, 16997.0, 555083.0, 556457.0, 561360.0, 561868.0, 562654.0, 58271.41728582745, 58841.41158588445, 59014.0, 59042.40957590455, 59129.0, 1323.8279023726855, 1357.6673714939704, 1371.6090914089436, 1371.746226948018, 1373.9656508587366, 29715.056988601933, 30941.0, 31026.0, 31420.842895785376, 31513.527297090102, 51938.0, 52351.0, 52427.47572524226, 52437.0, 52491.737541312046, 436830.0, 438797.0, 439571.0, 439842.0, 440057.0, 35850.028248446586, 36288.45567316545, 43096.0, 43159.09454487267, 43234.0, 47517.52482475269, 47804.0, 48076.0, 48686.5131348682, 48940.510594893596, 39496.0, 42812.57187428086, 43573.56426435822, 44768.55231447644, 46163.76918115388, 42115.0, 43149.352759709254, 43405.0, 43458.13083738244, 45651.771741141085, 41908.37137443002, 42285.57714422816, 43439.56560434355, 43535.56464435442, 43640.34539481847, 42532.0, 43230.56769432266, 43372.56627433812, 43385.78307108444, 43965.560344396145, 28156.296092597848, 28266.717332826407, 28272.376209575115, 29288.56067159037, 29536.852315738284], "value": 20823.102724586734, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [387.6937219596517, 388.66963081380845, 399.4726960412256, 402.1133400851124, 416.11762258131614, 14069.447914064453, 16435.4247601334, 17964.281428742852, 19661.0, 19683.0, 14819.0, 16512.58718532037, 17590.208440620172, 18141.546461338472, 18300.816991830077, 5033.798648054082, 5171.3535808024, 5213.895722085556, 5234.502722241381, 5255.264263003182, 5098.770555325001, 5257.158854583267, 5289.841304760864, 5293.285406470135, 5335.546478549309, 5223.112070947929, 5272.1300985337375, 5275.894482110355, 5276.736163191844, 5281.947180528184, 5264.263003179558, 5265.394479634814, 5265.4997775211295, 5267.130923397634, 5312.548433383167, 5147.073526765181, 5152.458991805881, 5156.123459011977, 5175.171972484384, 5208.8437346879655, 13962.441502339894, 14122.152670839783, 19498.31755888537, 22902.19842305528, 23142.190023349103, 2323.9883800580888, 2403.5072810074066, 2492.9252122436355, 2509.560826855293, 2855.300451389402, 10046.799064018753, 10237.539310731036, 10401.0, 10919.181061420426, 10932.0, 1861.0261061991025, 1900.5723712164652, 1918.1368384227153, 1938.4959910423393, 1951.8145776151243, 82548.17451825525, 86114.56942715247, 86215.56892215561, 87676.0, 88472.55763721204, 11224.94387528057, 11284.774304513863, 11360.886391136148, 11487.597934072268, 11595.652130436101, 15901.52295431139, 15903.602409939844, 15934.9203253983, 15947.36210551565, 15953.202339883192, 7151.499395042401, 7494.175640679521, 7808.68765249395, 7995.36095100503, 8366.53984030872, 7732.497387669783, 7816.726414575576, 7816.960915195501, 7948.046234451903, 8065.475744076616, 4679.415073115861, 4802.831900883452, 4823.2282834746275, 4932.21084626458, 4962.106820772224, 1772.2018308903912, 1859.7954225035235, 1913.2977688173428, 1939.7575303087115, 1986.3345779163853, 883.9248663863643, 987.2398253344884, 988.2884323287221, 1025.6820385680462, 1032.5611615063567, 18884.43346699601, 19492.512687182934, 19513.219471220993, 19708.31020914261, 20204.595908081756, 974.4007435427161, 1000.6497725795913, 1009.2380252909154, 1015.6546774096695, 1049.6378749331468, 10351.585936562604, 10454.68635940923, 10854.782904342026, 10949.560222581236, 11043.613473528549, 453109.0, 454995.0, 459111.0, 472475.0, 479210.0, 98025.0, 100322.4983875076, 101018.0, 101341.0, 101549.0, 988.1748739802202, 1008.688704683908, 1020.3520764314713, 1046.8953104689622, 1053.536443964653, 16302.429414970418, 17218.0, 17409.21658525344, 17728.91135544314, 17925.820741792417, 39733.20533589254, 42005.579944200166, 42657.867106645164, 42765.0, 43069.138617228105, 53065.46934530605, 54431.18353224626, 55686.0, 56335.43664563302, 56542.0, 8534.573271336532, 8722.345824063093, 8785.692500762443, 8795.076516965626, 8920.910790892267, 12814.423350949417, 13344.666383340105, 13369.53206637763, 13519.256440895846, 13538.390772415069, 11479.4260286987, 11889.227200231957, 12073.75852482963, 12143.635690929286, 12370.690732731753, 11519.88480115188, 11799.174057816213, 11918.901085717602, 11985.400729963292, 12268.815967760669, 11740.119491038377, 11877.821832672344, 12090.818637720617, 12156.696082598006, 12239.571614993432, 8590.398672092893, 8792.384533082623, 8799.076097009722, 8840.778980525281, 9105.0, 10856.565737370416, 10945.288556243819, 11698.70753231176, 11913.463894124612, 12334.321612311409], "value": 9086.71904560082, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1468.6475245940971, 1523.7638166084257, 1618.9514314570567, 1676.3378465506119, 1736.1926704082598, 8067.556284404355, 8256.793580160498, 8622.439541429803, 9342.626294948202, 9357.67248146315, 8706.434081784679, 8916.331275154373, 8954.0, 9208.171264586186, 9216.769580760485, 2376.9881150594224, 2394.8922298496523, 2409.0604664180983, 2411.0958390603523, 2413.2760171948344, 2046.8669536480081, 2081.771005189428, 2357.57563638545, 2370.466645004869, 2432.1730611592056, 2098.3075585056895, 2115.6826476028564, 2321.3268152235833, 2358.221786810357, 2370.8221883358733, 2331.895064722083, 2338.0764597983703, 2340.368100612842, 2342.23877239896, 2343.1564636730764, 2262.4117729390314, 2320.7447180810095, 2322.674825524428, 2324.302709187254, 2330.6154484510034, 9179.954100229455, 9185.540722963891, 9186.0, 9186.08139186076, 9203.0, 5081.4918508148885, 5158.664686795346, 5199.454057324002, 5214.947850521522, 5242.685438873642, 3345.2138747394342, 3378.864845406181, 3522.7357948153735, 3530.717542596586, 3533.2050288685095, 1254.8305978692852, 1282.1409655530817, 2153.558520503293, 2169.294979131773, 2172.6958225848393, 12933.935330323382, 12942.935285323607, 12970.481180752759, 13045.347732613427, 13082.738345233138, 4451.087527056946, 4575.614045606228, 4704.317873908332, 4708.552687494683, 4784.880377990578, 9986.900130998742, 10004.049615286536, 10004.149647280063, 10005.349652272578, 10015.69952901414, 5258.395284542326, 5269.762860671279, 5278.577713782888, 5281.128613778684, 5283.682979021271, 4783.306420569068, 4799.832005879777, 4957.182064959314, 4963.478834722375, 4965.503449655005, 3795.9240815183543, 3821.5987321331418, 3879.321118804198, 3890.06638406786, 3936.940945885814, 1154.2728081308721, 1176.1179115663253, 1185.324365111878, 1185.8458400407892, 1198.5505435461703, 717.8815495443298, 797.6530209358947, 855.4995327733345, 888.7022847346083, 938.9577568898517, 5724.713764311768, 5774.566907481955, 5823.213866128019, 5831.329397119301, 6095.6952152392205, 488.0799692579513, 497.99004019919397, 501.88205771643635, 506.91889297712925, 662.2053535757111, 3825.885223443301, 4091.672666186698, 4158.6673066154635, 4164.75011499311, 4224.936625950613, 11195.720106997227, 11603.07175425964, 11608.071354291636, 11609.88390116088, 11612.6516204514, 11248.156388270912, 11405.828912566318, 11489.655310340702, 11522.596709115141, 11582.826257606142, 8459.788505287417, 8536.274416674529, 8541.572921353907, 8546.829063418822, 8555.315574754004, 5638.661680299195, 5674.631148975303, 5771.451712087345, 5898.085796701518, 5906.7341969612335, 10371.94814025925, 10485.213608979204, 10522.894771052192, 10560.84158737635, 10568.577256909637, 10236.129928955825, 10581.682549523526, 10599.523021463898, 10799.0, 10937.890621093688, 7721.9227807723455, 7896.210378962058, 7897.368210543028, 7897.763067107996, 7900.091489478784, 8315.875261870955, 8487.236148746642, 8551.05938346769, 8647.913520864962, 8854.867176992222, 7836.882446763189, 7863.803404914694, 7970.800729981797, 8016.278534931884, 8117.918820811717, 7792.376609871083, 7864.6854125834325, 7893.57915575202, 7958.403119766156, 8004.95997520032, 7329.230430804902, 7446.888296675663, 7466.618675545042, 7468.887966680612, 7469.14104877946, 5076.517730815566, 5097.745112744422, 5123.38519377677, 5340.839774806762, 5436.45635436469, 5156.5616922562, 5170.656648415349, 5179.533841954242, 5245.580353571629, 5296.496832801032], "value": 4662.415185718112, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [333.83975691667996, 343.97936123832574, 347.92867462170256, 349.4199628616497, 352.2655263775029, 5456.1176394144, 5476.4249753775775, 5487.725613719308, 5490.094134467813, 5493.368262649797, 5316.255724198615, 5367.946320536803, 5392.460753924605, 5408.540274076709, 5411.242426060355, 3866.709996750241, 3994.8402063917483, 4142.461480007595, 4150.962259435141, 4151.149014452045, 4160.958390416103, 4161.459010328653, 4163.355474587536, 4172.791360431981, 4220.177065472219, 4169.249535083699, 4190.757944499694, 4202.6637868970565, 4216.451861258032, 4227.323628219486, 3914.432407300954, 4015.8393664253394, 4167.936871248736, 4197.0, 4199.6220340169375, 3673.3938900081453, 3684.7707137181887, 3711.721620878445, 3717.423799311111, 3722.664960153599, 5144.845654630367, 5151.45909679482, 5169.457206993249, 5191.506806853343, 5213.556847667953, 4716.127516409475, 4767.690100143514, 4772.522747725235, 4816.8554943351755, 4852.102361063179, 5529.474699903502, 5543.113101903676, 5553.888922221533, 5574.191742197399, 5610.69141197234, 4083.203775263825, 4090.5091389033505, 4090.897727556805, 4104.138130992465, 4124.319487284594, 5330.600204984642, 5515.310586176728, 5908.497777688902, 5910.468057874812, 5911.408859114097, 4144.70987030905, 4170.0, 4173.24881521323, 4180.728252663567, 4199.538050814408, 4247.915041699211, 4261.829526818955, 4263.722858014219, 4271.765052922057, 4271.807768650418, 4498.730076195438, 4829.2997515360075, 4844.346013288161, 4867.513248675175, 4875.219964805614, 5534.861628459321, 5596.544898326464, 5618.4662457066515, 5621.297337832771, 5637.069883469182, 1435.7128574285184, 1446.7612843880643, 1450.9637259068397, 1459.6642772162463, 1463.9634009149856, 879.5953861223909, 929.8791157149527, 935.569637966543, 952.1525842000625, 956.516958935733, 4784.086365453846, 4812.5668689818085, 4818.349522814446, 4819.0, 4833.4441539222735, 4990.825321113816, 5009.298698182258, 5043.3443652325695, 5057.418396884332, 5085.863937146408, 4561.44679381217, 4794.472608013116, 4805.951940480551, 4834.299026641117, 4855.757212139379, 2670.9332266693486, 2689.287338855205, 2692.986535067312, 2695.811293209496, 2701.8784154713085, 5196.752779332933, 5221.921671174935, 5234.7906083756225, 5258.50044245796, 5290.867728306746, 4922.852314430573, 5086.567641750493, 5127.1540195867265, 5153.479723481507, 5155.200943853633, 5202.635815492879, 5208.817691380707, 5213.0, 5213.56626927598, 5226.817061402832, 4958.826441074545, 5058.418281897481, 5088.440271570198, 5109.514596113363, 5111.386633603843, 4975.875603109806, 5014.623903207347, 5252.212168174804, 5696.658200507983, 5706.885862282814, 5745.425457454388, 5815.9709201453725, 5841.561882858888, 5971.790987315423, 5976.581639285208, 4157.459530261047, 4184.911922900129, 4217.578242175758, 4218.957810421856, 4219.156168766198, 5747.224124743273, 5861.128047256728, 5881.676737688504, 5882.264716910387, 5888.4700376966275, 5611.775528978795, 5625.887482250414, 5644.576656750678, 5648.4633959773755, 5670.894175635712, 5493.093639549589, 5589.496945274945, 5600.879824035128, 5608.663480191039, 5657.264555607745, 5481.314835645544, 5601.831945041656, 5636.9718151410625, 5659.575531835047, 5667.943320566742, 5637.943620563742, 5670.291213598301, 5675.801346952837, 5679.659220446786, 5709.286339207599, 5508.91736623943, 5511.173324001272, 5513.3108361454815, 5526.784107496344, 5538.393865778879], "value": 4171.560178734557, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [734.8567029429263, 756.0926887734717, 784.1805313447447, 796.9163237860024, 847.9152084791519, 14194.21931793751, 14722.926385368084, 14730.263486825643, 14756.852431475696, 14774.113553186802, 14901.627459313495, 14978.400863965428, 14986.925065374659, 15017.399304027853, 15285.159316237603, 643.2570381209698, 661.0117873778707, 778.2839787395582, 788.2550989315099, 797.872340425532, 783.6356094416095, 788.5465857132143, 788.9644965976516, 789.0334340432952, 789.234442590689, 852.7100785732849, 857.1300130367682, 858.9699360522383, 859.5831021954347, 859.669027424443, 683.3474032299142, 699.9580025198479, 831.4387788242972, 845.3152946113606, 849.668629234599, 616.6608365399057, 617.213053356969, 618.4001518527022, 623.6133597169738, 624.6283461340495, 14291.714165716732, 14378.496752613608, 14400.207988560625, 14416.85583144176, 14417.71164576713, 14012.22932738699, 14294.928525357307, 14396.208208548525, 14640.853591464162, 14696.338664760111, 12835.101542892002, 12845.165064270792, 12988.0, 13024.609261722164, 13191.538296159684, 4291.35629655551, 4373.934390984138, 4394.2969124939855, 4439.800208990603, 4487.551244875486, 82657.0, 83382.58308708479, 83671.0, 83786.58106709427, 86265.56867215624, 10492.842607360895, 10608.20438467118, 10644.361338319726, 10747.032767050847, 10861.07680847121, 19952.50118747043, 20019.29932452386, 20051.799482004993, 20532.486687832923, 20577.485562860747, 8553.914460855312, 8589.680786187695, 8668.089850565726, 8758.86861697075, 8777.210051095431, 4450.755208463501, 4514.6388288936805, 4676.55572720591, 4680.882977925511, 4778.685861388077, 3474.2356681530023, 3642.5446819147605, 3672.632736726306, 3675.632436756303, 3677.2093999790172, 638.3138126514016, 645.8191706322237, 668.3850857211377, 715.377621469325, 715.9606221657859, 603.1766638538363, 755.3994574313324, 756.255088737593, 766.3332900376708, 770.260549872129, 9758.219342452587, 9863.556139973718, 10458.163346932228, 10544.156467482737, 10576.471176441148, 621.471749013335, 657.4247533408268, 736.628002858553, 749.1684230504136, 755.4183278875236, 4613.377194078824, 4746.5490778376, 4772.0694464579765, 4784.976075119602, 4806.8077276909235, 111577.0, 112642.0, 113926.0, 114699.42650286695, 116519.41740291414, 94907.0, 95776.52111739396, 95974.0, 97403.23370896511, 97616.0, 83854.0, 85364.0, 86353.0, 86832.99999999873, 89293.55353223192, 10213.897861021294, 10459.477026148814, 10716.196285278791, 10753.838692419777, 10830.891691083303, 47479.287810683556, 48232.0, 50021.249681254085, 50309.24536132034, 50666.74666626643, 72856.0, 76763.2323676756, 77523.0, 77730.00429961331, 78154.0, 12141.575044873387, 12402.069844761498, 12663.746725065263, 12714.427850746553, 13232.801507977581, 14506.419743210154, 14542.927285363505, 14699.853001469848, 14761.188134652697, 15456.768148478006, 13694.93152534231, 13894.374753136335, 14090.78863817023, 14382.928085359506, 14743.557693269218, 13381.19712817234, 13536.526221582197, 13571.86428135706, 13970.79043814364, 14140.363683634056, 13571.79642305386, 13995.720085598434, 14368.209748463934, 15161.967532048333, 15310.846891531388, 11406.942965285121, 11584.594539191425, 12002.579909703118, 12200.755984880076, 12730.809037864625, 10952.56189752401, 12149.635510934686, 12194.57318993831, 12365.814512782135, 12471.875281247072], "value": 7645.158465042488, "unit": "score"}], "name": "dromaeo_css", "value": 7284.124436345924, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 109.0, 111.0, 110.0, 110.0, 111.0, 109.0, 110.0, 110.0, 110.0], "value": 109.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 120.0, 119.0, 165.0, 119.0, 122.0, 117.0, 116.0, 143.0, 117.0], "value": 128.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 162.0, 162.0, 160.0, 163.0, 162.0, 161.0, 160.0, 160.0], "value": 161.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 75.0, 108.0, 77.0, 76.0, 75.0, 77.0, 104.0, 76.0], "value": 82.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 131.0, 81.0, 81.0, 81.0, 110.0, 80.0, 80.0, 111.0], "value": 91.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 116.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 146.0, 144.0, 145.0, 145.0, 146.0, 146.0, 145.0], "value": 145.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 52.0, 53.0, 51.0, 52.0, 53.0, 53.0, 62.0, 53.0, 54.0], "value": 53.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 62.0, 61.0, 61.0, 61.0, 60.0, 61.0, 61.0, 61.0, 61.0], "value": 60.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 82.0, 79.0, 78.0, 78.0, 78.0, 83.0, 77.0, 79.0, 79.0], "value": 80.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 144.0, 127.0, 130.0, 129.0, 127.0, 131.0, 138.0, 171.0, 135.0], "value": 135.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 162.0, 159.0, 159.0, 162.0, 163.0, 165.0, 160.0, 167.0, 162.0], "value": 162.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 65.0, 65.0, 65.0, 64.0, 67.0, 64.0, 64.0, 65.0], "value": 65.1, "unit": "score"}], "name": "kraken", "value": 1488.6999999999998, "alertThreshold": 2.0}]} 05:11:26 INFO - Return code: 0 05:11:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:11:26 INFO - # TBPL SUCCESS # 05:11:26 INFO - Running post-action listener: _resource_record_post_action 05:11:26 INFO - [mozharness: 2016-09-20 12:11:26.768237Z] Finished run-tests step (success) 05:11:26 INFO - Running post-run listener: _resource_record_post_run 05:11:26 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 8192; Write bytes: 400584704; Read time: 80; Write time: 333584 05:11:26 INFO - TinderboxPrint: CPU usage
13.1% 05:11:26 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 80 05:11:26 INFO - TinderboxPrint: I/O write bytes / time
400,584,704 / 333,584 05:11:26 INFO - TinderboxPrint: CPU idle
8,291.0 (86.9%) 05:11:26 INFO - TinderboxPrint: CPU user
1,197.2 (12.5%) 05:11:26 INFO - TinderboxPrint: Swap in / out
0 / 0 05:11:26 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 103751680; Read time: 0; Write time: 112104 05:11:26 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 8192; Write bytes: 296488960; Read time: 80; Write time: 221476 05:11:27 INFO - Running post-run listener: _upload_blobber_files 05:11:27 INFO - Blob upload gear active. 05:11:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:11:27 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 05:11:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:11:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:11:27 INFO - (blobuploader) - INFO - Open directory for files ... 05:11:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:11:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:11:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:11:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:11:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:11:28 INFO - (blobuploader) - INFO - Done attempting. 05:11:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:11:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:11:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:11:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:11:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:11:29 INFO - (blobuploader) - INFO - Done attempting. 05:11:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:11:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:11:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:11:33 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:11:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:11:33 INFO - (blobuploader) - INFO - Done attempting. 05:11:33 INFO - (blobuploader) - INFO - Iteration through files over. 05:11:33 INFO - Return code: 0 05:11:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:11:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:11:33 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/da400232d48a4172554c508092a287f90a42f5eaca18a816cb5680ff501fe8f1e856fdab77f805ff867d3805dfcb52f40f51860aa9318d5adf2719a85d5cf851", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/68658ebeeab0d18eb40181e4f4400e3a2b5f061f3c4b64b4522e5c6d616494b61909f3c5b3a176369f0a196342d8796915d711b6f9fa5d59a244cd2e9f3371a3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fb63ac1e08c6cc8b54b8e2ec9fe292a816bbae20bf89f3f1220162d232dca233ba13223b5a44fe0d5b70cd6c0d9c825495210d936ade692bbf49b3a1b29a4672"} 05:11:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:11:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:11:33 INFO - Contents: 05:11:33 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/da400232d48a4172554c508092a287f90a42f5eaca18a816cb5680ff501fe8f1e856fdab77f805ff867d3805dfcb52f40f51860aa9318d5adf2719a85d5cf851", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/68658ebeeab0d18eb40181e4f4400e3a2b5f061f3c4b64b4522e5c6d616494b61909f3c5b3a176369f0a196342d8796915d711b6f9fa5d59a244cd2e9f3371a3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fb63ac1e08c6cc8b54b8e2ec9fe292a816bbae20bf89f3f1220162d232dca233ba13223b5a44fe0d5b70cd6c0d9c825495210d936ade692bbf49b3a1b29a4672"} 05:11:33 INFO - Running post-run listener: copy_logs_to_upload_dir 05:11:33 INFO - Copying logs to upload dir... 05:11:33 INFO - mkdir: /builds/slave/test/build/upload/logs 05:11:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.787151 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-09-20 05:11:33.268053) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-20 05:11:33.272144) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1474372251.815846-967121185 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/da400232d48a4172554c508092a287f90a42f5eaca18a816cb5680ff501fe8f1e856fdab77f805ff867d3805dfcb52f40f51860aa9318d5adf2719a85d5cf851", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/68658ebeeab0d18eb40181e4f4400e3a2b5f061f3c4b64b4522e5c6d616494b61909f3c5b3a176369f0a196342d8796915d711b6f9fa5d59a244cd2e9f3371a3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fb63ac1e08c6cc8b54b8e2ec9fe292a816bbae20bf89f3f1220162d232dca233ba13223b5a44fe0d5b70cd6c0d9c825495210d936ade692bbf49b3a1b29a4672"} build_url:https://queue.taskcluster.net/v1/task/b9ZTmjBATVyAprSp7ky4mg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011394 build_url: 'https://queue.taskcluster.net/v1/task/b9ZTmjBATVyAprSp7ky4mg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/da400232d48a4172554c508092a287f90a42f5eaca18a816cb5680ff501fe8f1e856fdab77f805ff867d3805dfcb52f40f51860aa9318d5adf2719a85d5cf851", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/68658ebeeab0d18eb40181e4f4400e3a2b5f061f3c4b64b4522e5c6d616494b61909f3c5b3a176369f0a196342d8796915d711b6f9fa5d59a244cd2e9f3371a3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fb63ac1e08c6cc8b54b8e2ec9fe292a816bbae20bf89f3f1220162d232dca233ba13223b5a44fe0d5b70cd6c0d9c825495210d936ade692bbf49b3a1b29a4672"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-20 05:11:33.298012) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 05:11:33.298485) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1474372251.815846-967121185 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004525 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 05:11:33.384899) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-20 05:11:33.385526) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-20 05:11:33.386330) ========= ========= Total master_lag: 0.17 =========