builder: autoland_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-012 starttime: 1474337464.36 results: success (0) buildid: 20160919153004 builduid: b4f93af85c2244bf8a78766eceb1d627 revision: 3edde45c1ee780de06b5bde61cfebf39e7a45ace ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-19 19:11:04.362631) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-19 19:11:04.363117) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-19 19:11:04.363395) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-19 19:11:04.378323) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 19:11:04.378718) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1474337463.986437-1677715087 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-19 19:11:04-- 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% 43.0M=0s 2016-09-19 19:11:04 (43.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.126415 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 19:11:04.519154) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 19:11:04.519469) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1474337463.986437-1677715087 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017162 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 19:11:04.568748) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-19 19:11:04.569109) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 3edde45c1ee780de06b5bde61cfebf39e7a45ace --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 3edde45c1ee780de06b5bde61cfebf39e7a45ace --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=2c5dd2b0a310ecf02fd87158000001b4-1474337463.986437-1677715087 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-19 19:11:04,910 truncating revision to first 12 chars 2016-09-19 19:11:04,910 Setting DEBUG logging. 2016-09-19 19:11:04,910 attempt 1/10 2016-09-19 19:11:04,910 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/3edde45c1ee7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-19 19:11:05,784 unpacking tar archive at: autoland-3edde45c1ee7/testing/mozharness/ program finished with exit code 0 elapsedTime=1.245043 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-19 19:11:05.827766) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-19 19:11:05.828100) ========= script_repo_revision: 3edde45c1ee780de06b5bde61cfebf39e7a45ace ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-19 19:11:05.828530) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-19 19:11:05.828819) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-19 19:11:05.839761) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-09-19 19:11:05.840179) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] 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=2c5dd2b0a310ecf02fd87158000001b4-1474337463.986437-1677715087 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:11:06 INFO - MultiFileLogger online at 20160919 19:11:06 in /builds/slave/test 19:11:06 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 19:11:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:11:06 INFO - {'append_to_log': False, 19:11:06 INFO - 'base_work_dir': '/builds/slave/test', 19:11:06 INFO - 'blob_upload_branch': 'Autoland', 19:11:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:11:06 INFO - 'branch': 'Autoland', 19:11:06 INFO - 'buildbot_json_path': 'buildprops.json', 19:11:06 INFO - 'config_files': ('talos/linux_config.py',), 19:11:06 INFO - 'default_actions': ('clobber', 19:11:06 INFO - 'read-buildbot-config', 19:11:06 INFO - 'download-and-extract', 19:11:06 INFO - 'populate-webroot', 19:11:06 INFO - 'create-virtualenv', 19:11:06 INFO - 'install', 19:11:06 INFO - 'run-tests'), 19:11:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:11:06 INFO - 'download_minidump_stackwalk': True, 19:11:06 INFO - 'download_symbols': 'ondemand', 19:11:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:11:06 INFO - 'tooltool.py': '/tools/tooltool.py', 19:11:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:11:06 INFO - '/tools/misc-python/virtualenv.py')}, 19:11:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:11:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:11:06 INFO - 'installer_path': 'installer.exe', 19:11:06 INFO - 'log_level': 'info', 19:11:06 INFO - 'log_name': 'talos', 19:11:06 INFO - 'log_to_console': True, 19:11:06 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:11:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:11:06 INFO - 'opt_config_files': (), 19:11:06 INFO - 'pip_index': False, 19:11:06 INFO - 'sps_profile': False, 19:11:06 INFO - 'sps_profile_interval': 0, 19:11:06 INFO - 'suite': 'dromaeojs', 19:11:06 INFO - 'system_bits': '32', 19:11:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:11:06 INFO - 'title': 'talos-linux64-ix-012', 19:11:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:11:06 INFO - 'use_talos_json': True, 19:11:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:11:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:11:06 INFO - 'work_dir': 'build'} 19:11:06 INFO - [mozharness: 2016-09-20 02:11:06.279183Z] Running clobber step. 19:11:06 INFO - Running pre-action listener: _resource_record_pre_action 19:11:06 INFO - Running main action method: clobber 19:11:06 INFO - rmtree: /builds/slave/test/build 19:11:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:11:06 INFO - Running post-action listener: _resource_record_post_action 19:11:06 INFO - [mozharness: 2016-09-20 02:11:06.743407Z] Finished clobber step (success) 19:11:06 INFO - [mozharness: 2016-09-20 02:11:06.743508Z] Running read-buildbot-config step. 19:11:06 INFO - Running pre-action listener: _resource_record_pre_action 19:11:06 INFO - Running main action method: read_buildbot_config 19:11:06 INFO - Using buildbot properties: 19:11:06 INFO - { 19:11:06 INFO - "project": "", 19:11:06 INFO - "product": "firefox", 19:11:06 INFO - "script_repo_revision": "production", 19:11:06 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 19:11:06 INFO - "repository": "", 19:11:06 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland pgo talos dromaeojs", 19:11:06 INFO - "buildid": "20160919153004", 19:11:06 INFO - "pgo_build": "True", 19:11:06 INFO - "basedir": "/builds/slave/test", 19:11:06 INFO - "buildnumber": 219, 19:11:06 INFO - "slavename": "talos-linux64-ix-012", 19:11:06 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 19:11:06 INFO - "platform": "ubuntu64_hw", 19:11:06 INFO - "scheduler": "tests-autoland-linux64-pgo-talos", 19:11:06 INFO - "branch": "autoland", 19:11:06 INFO - "repo_path": "integration/autoland", 19:11:06 INFO - "revision": "3edde45c1ee780de06b5bde61cfebf39e7a45ace", 19:11:06 INFO - "stage_platform": "linux64-pgo", 19:11:06 INFO - "builduid": "b4f93af85c2244bf8a78766eceb1d627", 19:11:06 INFO - "slavebuilddir": "test" 19:11:06 INFO - } 19:11:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/erbkBv0GSI23DVLqJEAB4g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 19:11:06 INFO - Running post-action listener: _resource_record_post_action 19:11:06 INFO - [mozharness: 2016-09-20 02:11:06.745976Z] Finished read-buildbot-config step (success) 19:11:06 INFO - [mozharness: 2016-09-20 02:11:06.746065Z] Running download-and-extract step. 19:11:06 INFO - Running pre-action listener: _resource_record_pre_action 19:11:06 INFO - Running main action method: download_and_extract 19:11:06 INFO - mkdir: /builds/slave/test/build/tests 19:11:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:11:06 INFO - trying https://queue.taskcluster.net/v1/task/erbkBv0GSI23DVLqJEAB4g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 19:11:06 INFO - Downloading https://queue.taskcluster.net/v1/task/erbkBv0GSI23DVLqJEAB4g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 19:11:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/erbkBv0GSI23DVLqJEAB4g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 19:11:07 INFO - Downloaded 1361 bytes. 19:11:07 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 19:11:07 INFO - Using the following test package requirements: 19:11:07 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 19:11:07 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:11:07 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 19:11:07 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:11:07 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 19:11:07 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:11:07 INFO - u'jsshell-linux-x86_64.zip'], 19:11:07 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:11:07 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 19:11:07 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 19:11:07 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:11:07 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 19:11:07 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:11:07 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 19:11:07 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:11:07 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 19:11:07 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:11:07 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 19:11:07 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 19:11:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/erbkBv0GSI23DVLqJEAB4g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 19:11:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/erbkBv0GSI23DVLqJEAB4g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 19:11:07 INFO - Fetch https://queue.taskcluster.net/v1/task/erbkBv0GSI23DVLqJEAB4g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 19:11:07 INFO - Expected file size: 21939932 19:11:12 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 19:11:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/erbkBv0GSI23DVLqJEAB4g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 19:11:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/erbkBv0GSI23DVLqJEAB4g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 19:11:12 INFO - Fetch https://queue.taskcluster.net/v1/task/erbkBv0GSI23DVLqJEAB4g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 19:11:12 INFO - Expected file size: 21939932 19:11:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/erbkBv0GSI23DVLqJEAB4g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 19:11:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/erbkBv0GSI23DVLqJEAB4g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 19:11:17 INFO - Fetch https://queue.taskcluster.net/v1/task/erbkBv0GSI23DVLqJEAB4g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip into memory 19:11:18 INFO - Expected file size: 14414059 19:11:18 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')]} 19:11:18 INFO - trying https://queue.taskcluster.net/v1/task/erbkBv0GSI23DVLqJEAB4g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 19:11:18 INFO - Downloading https://queue.taskcluster.net/v1/task/erbkBv0GSI23DVLqJEAB4g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 19:11:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/erbkBv0GSI23DVLqJEAB4g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 19:11:19 INFO - Downloaded 59570134 bytes. 19:11:19 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/erbkBv0GSI23DVLqJEAB4g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 19:11:19 INFO - mkdir: /builds/slave/test/properties 19:11:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:11:19 INFO - Writing to file /builds/slave/test/properties/build_url 19:11:19 INFO - Contents: 19:11:19 INFO - build_url:https://queue.taskcluster.net/v1/task/erbkBv0GSI23DVLqJEAB4g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 19:11:20 INFO - Running post-action listener: _resource_record_post_action 19:11:20 INFO - Running post-action listener: set_extra_try_arguments 19:11:20 INFO - [mozharness: 2016-09-20 02:11:20.294411Z] Finished download-and-extract step (success) 19:11:20 INFO - [mozharness: 2016-09-20 02:11:20.294642Z] Running populate-webroot step. 19:11:20 INFO - Running pre-action listener: _resource_record_pre_action 19:11:20 INFO - Running main action method: populate_webroot 19:11:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:11:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:11:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:11:20 INFO - '--tppagecycles', 19:11:20 INFO - '7'], 19:11:20 INFO - 'tests': ['tsvgm']}}, 19:11:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:11:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:11:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:11:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:11:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:11:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:11:20 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:11:20 INFO - 'talos_options': ['--disable-e10s'], 19:11:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:11:20 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:11:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:11:20 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:11:20 INFO - 'talos_options': ['--disable-e10s'], 19:11:20 INFO - 'tests': ['damp', 'tps']}, 19:11:20 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:11:20 INFO - 'tests': ['damp', 'tps']}, 19:11:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:11:20 INFO - 'tests': ['dromaeo_dom']}, 19:11:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:11:20 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:11:20 INFO - 'tests': ['basic_compositor_video']}, 19:11:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:11:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:11:20 INFO - 'tests': ['a11yr', 19:11:20 INFO - 'ts_paint', 19:11:20 INFO - 'tpaint', 19:11:20 INFO - 'sessionrestore', 19:11:20 INFO - 'sessionrestore_no_auto_restore', 19:11:20 INFO - 'tabpaint']}, 19:11:20 INFO - 'other-e10s': {'tests': ['a11yr', 19:11:20 INFO - 'ts_paint', 19:11:20 INFO - 'tpaint', 19:11:20 INFO - 'sessionrestore', 19:11:20 INFO - 'sessionrestore_no_auto_restore', 19:11:20 INFO - 'tabpaint']}, 19:11:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:11:20 INFO - 'ts_paint', 19:11:20 INFO - 'tpaint', 19:11:20 INFO - 'sessionrestore', 19:11:20 INFO - 'sessionrestore_no_auto_restore', 19:11:20 INFO - 'tabpaint']}, 19:11:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:11:20 INFO - 'ts_paint', 19:11:20 INFO - 'tpaint', 19:11:20 INFO - 'sessionrestore', 19:11:20 INFO - 'sessionrestore_no_auto_restore', 19:11:20 INFO - 'tabpaint']}, 19:11:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:11:20 INFO - 'tests': ['a11yr', 19:11:20 INFO - 'ts_paint', 19:11:20 INFO - 'tpaint', 19:11:20 INFO - 'sessionrestore', 19:11:20 INFO - 'sessionrestore_no_auto_restore', 19:11:20 INFO - 'tabpaint']}, 19:11:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:11:20 INFO - 'tests': ['a11yr', 19:11:20 INFO - 'ts_paint', 19:11:20 INFO - 'tpaint', 19:11:20 INFO - 'sessionrestore', 19:11:20 INFO - 'sessionrestore_no_auto_restore', 19:11:20 INFO - 'tabpaint']}, 19:11:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:11:20 INFO - 'tests': ['tsvgx', 19:11:20 INFO - 'tsvgr_opacity', 19:11:20 INFO - 'tart', 19:11:20 INFO - 'tscrollx', 19:11:20 INFO - 'cart']}, 19:11:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:11:20 INFO - 'tsvgr_opacity', 19:11:20 INFO - 'tart', 19:11:20 INFO - 'tscrollx', 19:11:20 INFO - 'cart']}, 19:11:20 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:11:20 INFO - 'talos_options': ['--disable-e10s'], 19:11:20 INFO - 'tests': ['tp5o']}, 19:11:20 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:11:20 INFO - 'tests': ['tp5o']}, 19:11:20 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:11:20 INFO - 'talos_options': ['--disable-e10s', 19:11:20 INFO - '--xperf_path', 19:11:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:11:20 INFO - 'tests': ['tp5n']}, 19:11:20 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:11:20 INFO - 'talos_options': ['--xperf_path', 19:11:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:11:20 INFO - 'tests': ['tp5n']}}, 19:11:20 INFO - 'talos.zip': {'path': '', 19:11:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:11:20 INFO - Running post-action listener: _resource_record_post_action 19:11:20 INFO - [mozharness: 2016-09-20 02:11:20.314992Z] Finished populate-webroot step (success) 19:11:20 INFO - [mozharness: 2016-09-20 02:11:20.315082Z] Running create-virtualenv step. 19:11:20 INFO - Running pre-action listener: _resource_record_pre_action 19:11:20 INFO - Running main action method: create_virtualenv 19:11:20 INFO - Creating virtualenv /builds/slave/test/build/venv 19:11:20 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 19:11:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:11:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:11:20 INFO - Using real prefix '/usr' 19:11:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:11:20 INFO - Installing distribute.............................................................................................................................................................................................done. 19:11:22 INFO - Installing pip.................done. 19:11:22 INFO - Return code: 0 19:11:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:11:22 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')]} 19:11:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:11:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:11:22 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')]} 19:11:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:11:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:11:22 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 0x7f192e499f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13988a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14647b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14f3790>, '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 0x14f4690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14f4b20>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1474337463.986437-1677715087', '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 19:11:22 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 19:11:22 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 19:11:22 INFO - Using env: {'DISPLAY': ':0', 19:11:22 INFO - 'HOME': '/home/cltbld', 19:11:22 INFO - 'LANG': 'en_US.UTF-8', 19:11:22 INFO - 'LANGUAGE': 'en_US:en', 19:11:22 INFO - 'LOGNAME': 'cltbld', 19:11:22 INFO - 'MAIL': '/var/mail/cltbld', 19:11:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:11:22 INFO - 'MOZ_NO_REMOTE': '1', 19:11:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:11:22 INFO - 'NO_EM_RESTART': '1', 19:11:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:11:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:11:22 INFO - 'PWD': '/builds/slave/test', 19:11:22 INFO - 'SHELL': '/bin/bash', 19:11:22 INFO - 'SHLVL': '1', 19:11:22 INFO - 'TERM': 'linux', 19:11:22 INFO - 'TMOUT': '86400', 19:11:22 INFO - 'USER': 'cltbld', 19:11:22 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1474337463.986437-1677715087', 19:11:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:11:22 INFO - '_': '/tools/buildbot/bin/python'} 19:11:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:11:23 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 19:11:23 INFO - Cleaning up... 19:11:23 INFO - Return code: 0 19:11:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:11: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')]} 19:11:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:11:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:11: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')]} 19:11:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:11:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:11:23 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 0x7f192e499f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13988a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14647b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14f3790>, '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 0x14f4690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14f4b20>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1474337463.986437-1677715087', '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 19:11:23 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 19:11:23 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 19:11:23 INFO - Using env: {'DISPLAY': ':0', 19:11:23 INFO - 'HOME': '/home/cltbld', 19:11:23 INFO - 'LANG': 'en_US.UTF-8', 19:11:23 INFO - 'LANGUAGE': 'en_US:en', 19:11:23 INFO - 'LOGNAME': 'cltbld', 19:11:23 INFO - 'MAIL': '/var/mail/cltbld', 19:11:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:11:23 INFO - 'MOZ_NO_REMOTE': '1', 19:11:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:11:23 INFO - 'NO_EM_RESTART': '1', 19:11:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:11:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:11:23 INFO - 'PWD': '/builds/slave/test', 19:11:23 INFO - 'SHELL': '/bin/bash', 19:11:23 INFO - 'SHLVL': '1', 19:11:23 INFO - 'TERM': 'linux', 19:11:23 INFO - 'TMOUT': '86400', 19:11:23 INFO - 'USER': 'cltbld', 19:11:23 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1474337463.986437-1677715087', 19:11:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:11:23 INFO - '_': '/tools/buildbot/bin/python'} 19:11:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:11:23 INFO - Downloading/unpacking psutil>=3.1.1 19:11:23 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 19:11:23 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 19:11:23 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 19:11:23 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 19:11:23 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 19:11:23 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 19:11:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:11:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:11:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:11:25 INFO - Installing collected packages: psutil 19:11:25 INFO - Running setup.py install for psutil 19:11:25 INFO - building 'psutil._psutil_linux' extension 19:11:25 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 19:11:25 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 19:11:25 INFO - building 'psutil._psutil_posix' extension 19:11:25 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 19:11:25 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 19:11:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:11:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:11:25 INFO - Successfully installed psutil 19:11:25 INFO - Cleaning up... 19:11:25 INFO - Return code: 0 19:11:25 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:11:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:11:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:11:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:11:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:11:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:11:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:11:25 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 0x7f192e499f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13988a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14647b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14f3790>, '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 0x14f4690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14f4b20>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1474337463.986437-1677715087', '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 19:11:25 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 19:11:25 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 19:11:25 INFO - Using env: {'DISPLAY': ':0', 19:11:25 INFO - 'HOME': '/home/cltbld', 19:11:25 INFO - 'LANG': 'en_US.UTF-8', 19:11:25 INFO - 'LANGUAGE': 'en_US:en', 19:11:25 INFO - 'LOGNAME': 'cltbld', 19:11:25 INFO - 'MAIL': '/var/mail/cltbld', 19:11:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:11:25 INFO - 'MOZ_NO_REMOTE': '1', 19:11:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:11:25 INFO - 'NO_EM_RESTART': '1', 19:11:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:11:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:11:25 INFO - 'PWD': '/builds/slave/test', 19:11:25 INFO - 'SHELL': '/bin/bash', 19:11:25 INFO - 'SHLVL': '1', 19:11:25 INFO - 'TERM': 'linux', 19:11:25 INFO - 'TMOUT': '86400', 19:11:25 INFO - 'USER': 'cltbld', 19:11:25 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1474337463.986437-1677715087', 19:11:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:11:25 INFO - '_': '/tools/buildbot/bin/python'} 19:11:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:11:26 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:11:26 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 19:11:26 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 19:11:26 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 19:11:26 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 19:11:26 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 19:11:26 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 19:11:27 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:11:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:11:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:11:28 INFO - Installing collected packages: mozsystemmonitor 19:11:28 INFO - Running setup.py install for mozsystemmonitor 19:11:28 INFO - Successfully installed mozsystemmonitor 19:11:28 INFO - Cleaning up... 19:11:28 INFO - Return code: 0 19:11:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:11:28 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')]} 19:11:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:11:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:11:28 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')]} 19:11:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:11:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:11:28 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 0x7f192e499f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13988a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14647b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14f3790>, '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 0x14f4690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14f4b20>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1474337463.986437-1677715087', '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 19:11:28 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 19:11:28 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 19:11:28 INFO - Using env: {'DISPLAY': ':0', 19:11:28 INFO - 'HOME': '/home/cltbld', 19:11:28 INFO - 'LANG': 'en_US.UTF-8', 19:11:28 INFO - 'LANGUAGE': 'en_US:en', 19:11:28 INFO - 'LOGNAME': 'cltbld', 19:11:28 INFO - 'MAIL': '/var/mail/cltbld', 19:11:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:11:28 INFO - 'MOZ_NO_REMOTE': '1', 19:11:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:11:28 INFO - 'NO_EM_RESTART': '1', 19:11:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:11:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:11:28 INFO - 'PWD': '/builds/slave/test', 19:11:28 INFO - 'SHELL': '/bin/bash', 19:11:28 INFO - 'SHLVL': '1', 19:11:28 INFO - 'TERM': 'linux', 19:11:28 INFO - 'TMOUT': '86400', 19:11:28 INFO - 'USER': 'cltbld', 19:11:28 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1474337463.986437-1677715087', 19:11:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:11:28 INFO - '_': '/tools/buildbot/bin/python'} 19:11:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:11:28 INFO - Downloading/unpacking jsonschema==2.5.1 19:11:28 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 19:11:28 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 19:11:28 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 19:11:28 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 19:11:28 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 19:11:28 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 19:11:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:11:30 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:11:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:11:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:11:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:11:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:11:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:11:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:11:30 INFO - Downloading functools32-3.2.3-2.tar.gz 19:11:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:11:30 INFO - warning: no files found matching '*.txt' 19:11:30 INFO - no previously-included directories found matching 'build' 19:11:30 INFO - no previously-included directories found matching 'dist' 19:11:30 INFO - no previously-included directories found matching '.git*' 19:11:30 INFO - Installing collected packages: jsonschema, functools32 19:11:30 INFO - Running setup.py install for functools32 19:11:30 INFO - warning: no files found matching '*.txt' 19:11:30 INFO - no previously-included directories found matching 'build' 19:11:30 INFO - no previously-included directories found matching 'dist' 19:11:30 INFO - no previously-included directories found matching '.git*' 19:11:30 INFO - Successfully installed jsonschema functools32 19:11:30 INFO - Cleaning up... 19:11:30 INFO - Return code: 0 19:11:30 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:11: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')]} 19:11:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:11:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:11: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')]} 19:11:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:11:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:11: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', '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 0x7f192e499f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13988a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14647b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14f3790>, '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 0x14f4690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14f4b20>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1474337463.986437-1677715087', '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 19:11: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 19:11: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 functools32==3.2.3-2 19:11:30 INFO - Using env: {'DISPLAY': ':0', 19:11:30 INFO - 'HOME': '/home/cltbld', 19:11:30 INFO - 'LANG': 'en_US.UTF-8', 19:11:30 INFO - 'LANGUAGE': 'en_US:en', 19:11:30 INFO - 'LOGNAME': 'cltbld', 19:11:30 INFO - 'MAIL': '/var/mail/cltbld', 19:11:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:11:30 INFO - 'MOZ_NO_REMOTE': '1', 19:11:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:11:30 INFO - 'NO_EM_RESTART': '1', 19:11:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:11:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:11:30 INFO - 'PWD': '/builds/slave/test', 19:11:30 INFO - 'SHELL': '/bin/bash', 19:11:30 INFO - 'SHLVL': '1', 19:11:30 INFO - 'TERM': 'linux', 19:11:30 INFO - 'TMOUT': '86400', 19:11:30 INFO - 'USER': 'cltbld', 19:11:30 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1474337463.986437-1677715087', 19:11:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:11:30 INFO - '_': '/tools/buildbot/bin/python'} 19:11:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:11:30 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:11:30 INFO - Cleaning up... 19:11:30 INFO - Return code: 0 19:11:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:11: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')]} 19:11:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:11:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:11: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')]} 19:11:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:11:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:11: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', '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 0x7f192e499f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13988a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14647b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14f3790>, '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 0x14f4690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14f4b20>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1474337463.986437-1677715087', '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 19:11: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:11: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 blobuploader==1.2.4 19:11:30 INFO - Using env: {'DISPLAY': ':0', 19:11:30 INFO - 'HOME': '/home/cltbld', 19:11:30 INFO - 'LANG': 'en_US.UTF-8', 19:11:30 INFO - 'LANGUAGE': 'en_US:en', 19:11:30 INFO - 'LOGNAME': 'cltbld', 19:11:30 INFO - 'MAIL': '/var/mail/cltbld', 19:11:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:11:31 INFO - 'MOZ_NO_REMOTE': '1', 19:11:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:11:31 INFO - 'NO_EM_RESTART': '1', 19:11:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:11:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:11:31 INFO - 'PWD': '/builds/slave/test', 19:11:31 INFO - 'SHELL': '/bin/bash', 19:11:31 INFO - 'SHLVL': '1', 19:11:31 INFO - 'TERM': 'linux', 19:11:31 INFO - 'TMOUT': '86400', 19:11:31 INFO - 'USER': 'cltbld', 19:11:31 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1474337463.986437-1677715087', 19:11:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:11:31 INFO - '_': '/tools/buildbot/bin/python'} 19:11:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:11:31 INFO - Downloading/unpacking blobuploader==1.2.4 19:11:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:11:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:11:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:11:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:11:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:11:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:11:33 INFO - Downloading blobuploader-1.2.4.tar.gz 19:11:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:11:33 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:11:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:11:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:11:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:11:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:11:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:11:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:11:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:11:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:11:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:11:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:11:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:11:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:11:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:11:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:11:33 INFO - Downloading docopt-0.6.1.tar.gz 19:11:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:11:33 INFO - Installing collected packages: blobuploader, requests, docopt 19:11:33 INFO - Running setup.py install for blobuploader 19:11:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:11:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:11:33 INFO - Running setup.py install for requests 19:11:34 INFO - Running setup.py install for docopt 19:11:34 INFO - Successfully installed blobuploader requests docopt 19:11:34 INFO - Cleaning up... 19:11:34 INFO - Return code: 0 19:11:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:11:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:11:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:11:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:11:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:11:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:11:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:11:34 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 0x7f192e499f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13988a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14647b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14f3790>, '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 0x14f4690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14f4b20>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1474337463.986437-1677715087', '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 19:11:34 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 19:11:34 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 19:11:34 INFO - Using env: {'DISPLAY': ':0', 19:11:34 INFO - 'HOME': '/home/cltbld', 19:11:34 INFO - 'LANG': 'en_US.UTF-8', 19:11:34 INFO - 'LANGUAGE': 'en_US:en', 19:11:34 INFO - 'LOGNAME': 'cltbld', 19:11:34 INFO - 'MAIL': '/var/mail/cltbld', 19:11:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:11:34 INFO - 'MOZ_NO_REMOTE': '1', 19:11:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:11:34 INFO - 'NO_EM_RESTART': '1', 19:11:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:11:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:11:34 INFO - 'PWD': '/builds/slave/test', 19:11:34 INFO - 'SHELL': '/bin/bash', 19:11:34 INFO - 'SHLVL': '1', 19:11:34 INFO - 'TERM': 'linux', 19:11:34 INFO - 'TMOUT': '86400', 19:11:34 INFO - 'USER': 'cltbld', 19:11:34 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1474337463.986437-1677715087', 19:11:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:11:34 INFO - '_': '/tools/buildbot/bin/python'} 19:11:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:11:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:11:34 INFO - Running setup.py (path:/tmp/pip-E3xsg0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:11:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:11:34 INFO - Running setup.py (path:/tmp/pip-w1pPvX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:11:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:11:34 INFO - Running setup.py (path:/tmp/pip-K2vwTP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:11:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:11:34 INFO - Running setup.py (path:/tmp/pip-KLrCeh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:11:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:11:35 INFO - Running setup.py (path:/tmp/pip-O6V6uF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:11:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:11:35 INFO - Running setup.py (path:/tmp/pip-yudNWL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:11:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:11:35 INFO - Running setup.py (path:/tmp/pip-Hrbajf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:11:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:11:35 INFO - Running setup.py (path:/tmp/pip-9WP27S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:11:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:11:35 INFO - Running setup.py (path:/tmp/pip-XWjLbP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:11:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:11:35 INFO - Running setup.py (path:/tmp/pip-kDug1d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:11:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:11:35 INFO - Running setup.py (path:/tmp/pip-v3_Agy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:11:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:11:35 INFO - Running setup.py (path:/tmp/pip-B9wG_Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:11:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:11:35 INFO - Running setup.py (path:/tmp/pip-gxDTbW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:11:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:11:35 INFO - Running setup.py (path:/tmp/pip-BQeGkf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:11:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:11:35 INFO - Running setup.py (path:/tmp/pip-Nq9q9d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:11:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:11:35 INFO - Running setup.py (path:/tmp/pip-0C08V_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:11:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:11:35 INFO - Running setup.py (path:/tmp/pip-WeXKPX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:11:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:11:35 INFO - Running setup.py install for manifestparser 19:11:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:11:36 INFO - Running setup.py install for mozcrash 19:11:36 INFO - Running setup.py install for mozdebug 19:11:36 INFO - Running setup.py install for mozdevice 19:11:36 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:11:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:11:36 INFO - Running setup.py install for mozfile 19:11:36 INFO - Running setup.py install for mozhttpd 19:11:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:11:36 INFO - Running setup.py install for mozinfo 19:11:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:11:36 INFO - Running setup.py install for mozInstall 19:11:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:11:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:11:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:11:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:11:36 INFO - Running setup.py install for mozleak 19:11:36 INFO - Running setup.py install for mozlog 19:11:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:11:36 INFO - Running setup.py install for moznetwork 19:11:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:11:37 INFO - Running setup.py install for mozprocess 19:11:37 INFO - Running setup.py install for mozprofile 19:11:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:11:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:11:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:11:37 INFO - Running setup.py install for mozrunner 19:11:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:11:37 INFO - Running setup.py install for mozscreenshot 19:11:37 INFO - Running setup.py install for moztest 19:11:37 INFO - Running setup.py install for mozversion 19:11:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:11:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:11:37 INFO - Cleaning up... 19:11:37 INFO - Return code: 0 19:11:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:11:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:11:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:11:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:11:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:11:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:11:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:11:37 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 0x7f192e499f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13988a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14647b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14f3790>, '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 0x14f4690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14f4b20>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1474337463.986437-1677715087', '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 19:11:37 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 19:11:37 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 19:11:37 INFO - Using env: {'DISPLAY': ':0', 19:11:37 INFO - 'HOME': '/home/cltbld', 19:11:37 INFO - 'LANG': 'en_US.UTF-8', 19:11:37 INFO - 'LANGUAGE': 'en_US:en', 19:11:37 INFO - 'LOGNAME': 'cltbld', 19:11:37 INFO - 'MAIL': '/var/mail/cltbld', 19:11:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:11:37 INFO - 'MOZ_NO_REMOTE': '1', 19:11:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:11:37 INFO - 'NO_EM_RESTART': '1', 19:11:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:11:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:11:37 INFO - 'PWD': '/builds/slave/test', 19:11:37 INFO - 'SHELL': '/bin/bash', 19:11:37 INFO - 'SHLVL': '1', 19:11:37 INFO - 'TERM': 'linux', 19:11:37 INFO - 'TMOUT': '86400', 19:11:37 INFO - 'USER': 'cltbld', 19:11:37 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1474337463.986437-1677715087', 19:11:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:11:37 INFO - '_': '/tools/buildbot/bin/python'} 19:11:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:11:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:11:37 INFO - Running setup.py (path:/tmp/pip-2IuLmu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:11:38 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)) 19:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:11:38 INFO - Running setup.py (path:/tmp/pip-_V1Bpd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:11:38 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)) 19:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:11:38 INFO - Running setup.py (path:/tmp/pip-u8ArGI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:11:38 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)) 19:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:11:38 INFO - Running setup.py (path:/tmp/pip-jYncdR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:11:38 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)) 19:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:11:38 INFO - Running setup.py (path:/tmp/pip-9yIv90-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:11:38 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)) 19:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:11:38 INFO - Running setup.py (path:/tmp/pip-r64R5A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:11:38 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)) 19:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:11:38 INFO - Running setup.py (path:/tmp/pip-ZhZj_b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:11:38 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)) 19:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:11:38 INFO - Running setup.py (path:/tmp/pip-g3nyEf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:11:38 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)) 19:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:11:38 INFO - Running setup.py (path:/tmp/pip-SYYreV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:11:38 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)) 19:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:11:38 INFO - Running setup.py (path:/tmp/pip-sPR856-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:11:38 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)) 19:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:11:38 INFO - Running setup.py (path:/tmp/pip-41uiAw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:11:38 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)) 19:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:11:38 INFO - Running setup.py (path:/tmp/pip-6SnXcd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:11:38 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)) 19:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:11:38 INFO - Running setup.py (path:/tmp/pip-37P8hQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:11:38 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)) 19:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:11:38 INFO - Running setup.py (path:/tmp/pip-DfN0dS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:11:39 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)) 19:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:11:39 INFO - Running setup.py (path:/tmp/pip-rj6Jlb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:11:39 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)) 19:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:11:39 INFO - Running setup.py (path:/tmp/pip-Xnl0uF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:11:39 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)) 19:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:11:39 INFO - Running setup.py (path:/tmp/pip-mT8HFg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:11:39 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)) 19:11:39 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)) 19:11:39 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)) 19:11:39 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)) 19:11:39 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)) 19:11:39 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)) 19:11:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:11:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:11:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:11:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:11:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:11:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:11:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:11:41 INFO - Downloading blessings-1.6.tar.gz 19:11:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:11:41 INFO - Installing collected packages: blessings 19:11:41 INFO - Running setup.py install for blessings 19:11:41 INFO - Successfully installed blessings 19:11:41 INFO - Cleaning up... 19:11:41 INFO - Return code: 0 19:11:41 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:11:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:11:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:11:41 INFO - Reading from file tmpfile_stdout 19:11:41 INFO - Current package versions: 19:11:41 INFO - argparse == 1.2.1 19:11:41 INFO - blessings == 1.6 19:11:41 INFO - blobuploader == 1.2.4 19:11:41 INFO - docopt == 0.6.1 19:11:41 INFO - functools32 == 3.2.3-2 19:11:41 INFO - jsonschema == 2.5.1 19:11:41 INFO - manifestparser == 1.1 19:11:41 INFO - mozInstall == 1.12 19:11:41 INFO - mozcrash == 0.17 19:11:41 INFO - mozdebug == 0.1 19:11:41 INFO - mozdevice == 0.48 19:11:41 INFO - mozfile == 1.2 19:11:41 INFO - mozhttpd == 0.7 19:11:41 INFO - mozinfo == 0.9 19:11:41 INFO - mozleak == 0.1 19:11:41 INFO - mozlog == 3.4 19:11:41 INFO - moznetwork == 0.27 19:11:41 INFO - mozprocess == 0.23 19:11:41 INFO - mozprofile == 0.28 19:11:41 INFO - mozrunner == 6.12 19:11:41 INFO - mozscreenshot == 0.1 19:11:41 INFO - mozsystemmonitor == 0.3 19:11:41 INFO - moztest == 0.7 19:11:41 INFO - mozversion == 1.4 19:11:41 INFO - psutil == 3.1.1 19:11:41 INFO - requests == 1.2.3 19:11:41 INFO - wsgiref == 0.1.2 19:11:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:11:41 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')]} 19:11:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:11:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:11:41 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')]} 19:11:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:11:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:11:41 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 0x7f192e499f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13988a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14647b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14f3790>, '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 0x14f4690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14f4b20>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1474337463.986437-1677715087', '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 19:11:41 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 19:11:41 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 19:11:41 INFO - Using env: {'DISPLAY': ':0', 19:11:41 INFO - 'HOME': '/home/cltbld', 19:11:41 INFO - 'LANG': 'en_US.UTF-8', 19:11:41 INFO - 'LANGUAGE': 'en_US:en', 19:11:41 INFO - 'LOGNAME': 'cltbld', 19:11:41 INFO - 'MAIL': '/var/mail/cltbld', 19:11:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:11:41 INFO - 'MOZ_NO_REMOTE': '1', 19:11:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:11:41 INFO - 'NO_EM_RESTART': '1', 19:11:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:11:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:11:41 INFO - 'PWD': '/builds/slave/test', 19:11:41 INFO - 'SHELL': '/bin/bash', 19:11:41 INFO - 'SHLVL': '1', 19:11:41 INFO - 'TERM': 'linux', 19:11:41 INFO - 'TMOUT': '86400', 19:11:41 INFO - 'USER': 'cltbld', 19:11:41 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1474337463.986437-1677715087', 19:11:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:11:41 INFO - '_': '/tools/buildbot/bin/python'} 19:11:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:11:41 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)) 19:11:41 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)) 19:11:41 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)) 19:11:41 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)) 19:11:41 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)) 19:11:41 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)) 19:11:41 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)) 19:11:41 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)) 19:11:41 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)) 19:11:41 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)) 19:11:41 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)) 19:11:41 INFO - Cleaning up... 19:11:41 INFO - Return code: 0 19:11:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:11:41 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')]} 19:11:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:11:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:11:41 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')]} 19:11:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:11:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:11:41 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 0x7f192e499f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13988a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14647b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14f3790>, '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 0x14f4690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14f4b20>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1474337463.986437-1677715087', '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 19:11:41 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 19:11:41 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 19:11:41 INFO - Using env: {'DISPLAY': ':0', 19:11:41 INFO - 'HOME': '/home/cltbld', 19:11:41 INFO - 'LANG': 'en_US.UTF-8', 19:11:41 INFO - 'LANGUAGE': 'en_US:en', 19:11:41 INFO - 'LOGNAME': 'cltbld', 19:11:41 INFO - 'MAIL': '/var/mail/cltbld', 19:11:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:11:41 INFO - 'MOZ_NO_REMOTE': '1', 19:11:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:11:41 INFO - 'NO_EM_RESTART': '1', 19:11:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:11:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:11:41 INFO - 'PWD': '/builds/slave/test', 19:11:41 INFO - 'SHELL': '/bin/bash', 19:11:41 INFO - 'SHLVL': '1', 19:11:41 INFO - 'TERM': 'linux', 19:11:41 INFO - 'TMOUT': '86400', 19:11:41 INFO - 'USER': 'cltbld', 19:11:41 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1474337463.986437-1677715087', 19:11:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:11:41 INFO - '_': '/tools/buildbot/bin/python'} 19:11:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:11:42 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:11:42 INFO - Cleaning up... 19:11:42 INFO - Return code: 0 19:11:42 INFO - Running post-action listener: _resource_record_post_action 19:11:42 INFO - Running post-action listener: _start_resource_monitoring 19:11:42 INFO - Starting resource monitoring. 19:11:42 INFO - [mozharness: 2016-09-20 02:11:42.103621Z] Finished create-virtualenv step (success) 19:11:42 INFO - [mozharness: 2016-09-20 02:11:42.104086Z] Running install step. 19:11:42 INFO - Running pre-action listener: _resource_record_pre_action 19:11:42 INFO - Running main action method: install 19:11:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:11:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:11:42 INFO - Reading from file tmpfile_stdout 19:11:42 INFO - Detecting whether we're running mozinstall >=1.0... 19:11:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:11:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:11:42 INFO - Reading from file tmpfile_stdout 19:11:42 INFO - Output received: 19:11:42 INFO - Usage: mozinstall [options] installer 19:11:42 INFO - Options: 19:11:42 INFO - -h, --help show this help message and exit 19:11:42 INFO - -d DEST, --destination=DEST 19:11:42 INFO - Directory to install application into. [default: 19:11:42 INFO - "/builds/slave/test"] 19:11:42 INFO - --app=APP Application being installed. [default: firefox] 19:11:42 INFO - mkdir: /builds/slave/test/build/application 19:11:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 19:11:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 19:11:59 INFO - Reading from file tmpfile_stdout 19:11:59 INFO - Output received: 19:11:59 INFO - /builds/slave/test/build/application/firefox/firefox 19:11:59 INFO - Running post-action listener: _resource_record_post_action 19:11:59 INFO - [mozharness: 2016-09-20 02:11:59.476422Z] Finished install step (success) 19:11:59 INFO - [mozharness: 2016-09-20 02:11:59.476667Z] Running run-tests step. 19:11:59 INFO - Running pre-action listener: _resource_record_pre_action 19:11:59 INFO - Running main action method: run_tests 19:11:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:11:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:11:59 INFO - Python 2.7.3 19:11:59 INFO - Return code: 0 19:11:59 INFO - grabbing minidump binary from tooltool 19:11:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:11:59 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 0x14f3790>, '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 0x14f4690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14f4b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:11:59 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 19:11:59 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 19:11:59 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 19:11:59 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:11:59 INFO - Return code: 0 19:11:59 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:11:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:11:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:11:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:11:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:11:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/erbkBv0GSI23DVLqJEAB4g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-012', '--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 19:11:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/erbkBv0GSI23DVLqJEAB4g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-012 --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 19:11:59 INFO - Using env: {'DISPLAY': ':0', 19:11:59 INFO - 'HOME': '/home/cltbld', 19:11:59 INFO - 'LANG': 'en_US.UTF-8', 19:11:59 INFO - 'LANGUAGE': 'en_US:en', 19:11:59 INFO - 'LOGNAME': 'cltbld', 19:11:59 INFO - 'MAIL': '/var/mail/cltbld', 19:11:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:11:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:11:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:11:59 INFO - 'MOZ_NO_REMOTE': '1', 19:11:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:11:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:11:59 INFO - 'NO_EM_RESTART': '1', 19:11:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:11:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:11:59 INFO - 'PWD': '/builds/slave/test', 19:11:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:11:59 INFO - 'SHELL': '/bin/bash', 19:11:59 INFO - 'SHLVL': '1', 19:11:59 INFO - 'TERM': 'linux', 19:11:59 INFO - 'TMOUT': '86400', 19:11:59 INFO - 'USER': 'cltbld', 19:11:59 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1474337463.986437-1677715087', 19:11:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:11:59 INFO - '_': '/tools/buildbot/bin/python'} 19:11:59 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/erbkBv0GSI23DVLqJEAB4g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-012', '--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 19:11:59 INFO - mozversion application_buildid: 20160919153004 19:11:59 INFO - mozversion application_changeset: 3edde45c1ee780de06b5bde61cfebf39e7a45ace 19:11:59 INFO - mozversion application_display_name: Nightly 19:11:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:11:59 INFO - mozversion application_name: Firefox 19:11:59 INFO - mozversion application_remotingname: firefox 19:11:59 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 19:11:59 INFO - mozversion application_vendor: Mozilla 19:11:59 INFO - mozversion application_version: 51.0a1 19:11:59 INFO - mozversion platform_buildid: 20160919153004 19:11:59 INFO - mozversion platform_changeset: 3edde45c1ee780de06b5bde61cfebf39e7a45ace 19:11:59 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 19:11:59 INFO - mozversion platform_version: 51.0a1 19:11:59 INFO - using testdate: 1474337519 19:11:59 INFO - actual date: 1474337519 19:11:59 INFO - starting webserver on 'localhost:45335' 19:11:59 INFO - SUITE-START | Running 2 tests 19:11:59 INFO - TEST-START | dromaeo_css 19:11:59 INFO - operating with platform_type : linux_ 19:11:59 INFO - Initialising browser for dromaeo_css test... 19:11:59 INFO - TEST-INFO | started process 17255 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmplwfk3K/profile http://localhost:45335/getInfo.html) 19:12:03 INFO - PROCESS | 17255 | __metrics Screen width/height:1600/1200 19:12:03 INFO - PROCESS | 17255 | colorDepth:24 19:12:03 INFO - PROCESS | 17255 | Browser inner width/height: 1024/697 19:12:03 INFO - PROCESS | 17255 | __metrics 19:12:04 INFO - PROCESS | 17255 | 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" 19:12:04 INFO - PROCESS | 17255 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:12:04 INFO - PROCESS | 17255 | console.error: PushService: 19:12:04 INFO - PROCESS | 17255 | stateChangeProcessEnqueue: Error transitioning state 19:12:04 INFO - PROCESS | 17255 | UnknownError 19:12:05 INFO - TEST-INFO | 17255: exit 0 19:12:05 INFO - Browser initialized. 19:12:05 INFO - Running cycle 1/1 for dromaeo_css test... 19:12:05 INFO - TEST-INFO | started process 17340 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmplwfk3K/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:12:17 INFO - PROCESS | 17340 | RSS: Main: 173576192 19:12:17 INFO - PROCESS | 17340 | 19:12:20 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.15004 seconds. 19:12:27 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 19:12:34 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 19:12:53 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.05005 seconds. 19:12:59 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 19:13:08 INFO - PROCESS | 17340 | 1474337588673 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] 19:13:09 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 19:13:10 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 19:13:16 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 19:13:21 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 19:13:33 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 19:13:44 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 19:13:57 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.01683 seconds. 19:13:59 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 2.03337 seconds. 19:13:59 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 2.03337 seconds. 19:14:16 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.04998 seconds. 19:14:18 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.0168 seconds. 19:14:24 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 19:14:33 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:14:42 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 19:14:47 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 19:14:49 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:14:55 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 19:15:00 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.05004 seconds. 19:15:10 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 19:15:12 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.08339 seconds. 19:15:18 INFO - PROCESS | 17340 | Cycle 1(1): loaded http://localhost:45335/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45335/tests/dromaeo/cssquery-ext.html) 19:15:19 INFO - PROCESS | 17340 | RSS: Main: 299347968 19:15:19 INFO - PROCESS | 17340 | 19:15:22 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 19:15:34 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 19:15:36 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 19:15:44 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 19:16:18 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 19:16:26 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 19:16:32 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:16:49 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 19:17:07 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 19:17:28 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 19:17:30 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.03334 seconds. 19:17:35 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 19:17:38 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 19:17:39 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 19:17:45 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 19:17:48 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 19:18:01 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 19:18:13 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 19:18:17 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 19:18:19 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 19:18:21 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 19:18:22 INFO - PROCESS | 17340 | Cycle 1(1): loaded http://localhost:45335/tests/dromaeo/cssquery-ext.html (next: http://localhost:45335/tests/dromaeo/cssquery-jquery.html) 19:18:22 INFO - PROCESS | 17340 | RSS: Main: 277184512 19:18:22 INFO - PROCESS | 17340 | 19:18:25 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 19:18:45 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 19:18:54 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:19:01 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 19:19:25 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 19:19:27 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 19:19:31 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 19:19:51 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:20:01 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 19:20:11 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 19:20:16 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 19:20:28 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 19:20:34 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 19:20:56 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 19:21:04 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 19:21:11 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 19:21:24 INFO - PROCESS | 17340 | Cycle 1(1): loaded http://localhost:45335/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45335/tests/dromaeo/cssquery-mootools.html) 19:21:25 INFO - PROCESS | 17340 | RSS: Main: 213655552 19:21:25 INFO - PROCESS | 17340 | 19:21:29 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 19:21:30 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 2.01674 seconds. 19:21:43 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 19:21:45 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 19:21:54 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 19:21:56 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 19:22:04 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:22:16 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:22:18 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 19:22:32 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 19:22:48 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 19:22:58 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 19:23:04 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 19:23:06 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 19:23:10 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 19:23:12 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 19:23:36 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.01676 seconds. 19:23:40 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:23:42 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 19:23:50 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 19:23:52 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 19:23:58 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:23:59 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:24:01 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 19:24:03 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 19:24:21 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 19:24:26 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 19:24:27 INFO - PROCESS | 17340 | Cycle 1(1): loaded http://localhost:45335/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45335/tests/dromaeo/cssquery-prototype.html) 19:24:27 INFO - PROCESS | 17340 | RSS: Main: 249253888 19:24:27 INFO - PROCESS | 17340 | 19:24:40 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00343 seconds. 19:24:57 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 19:25:00 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:25:08 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 19:25:14 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 19:25:19 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 19:25:23 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 19:25:32 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.05008 seconds. 19:26:11 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 19:26:13 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 19:26:15 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.0001 seconds. 19:26:29 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 19:26:46 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 19:27:07 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 19:27:20 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.03339 seconds. 19:27:22 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 19:27:30 INFO - PROCESS | 17340 | Cycle 1(1): loaded http://localhost:45335/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45335/tests/dromaeo/cssquery-yui.html) 19:27:31 INFO - PROCESS | 17340 | RSS: Main: 215793664 19:27:31 INFO - PROCESS | 17340 | 19:27:34 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.03341 seconds. 19:27:45 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 19:27:47 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 19:27:51 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 19:28:02 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 19:28:09 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 19:28:36 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:28:46 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:28:50 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 19:28:53 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 19:28:54 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 19:29:20 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 19:29:41 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 19:29:43 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 19:29:45 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 19:29:47 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 19:29:49 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 19:30:01 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 19:30:22 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 19:30:24 INFO - PROCESS | 17340 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 19:30:34 INFO - PROCESS | 17340 | Cycle 1(1): loaded http://localhost:45335/tests/dromaeo/cssquery-yui.html (next: http://localhost:45335/tests/dromaeo/cssquery-dojo.html) 19:30:34 INFO - PROCESS | 17340 | RSS: Main: 293941248 19:30:34 INFO - PROCESS | 17340 | 19:30:34 INFO - PROCESS | 17340 | __start_tp_report 19:30:34 INFO - PROCESS | 17340 | _x_x_mozilla_page_load 19:30:34 INFO - PROCESS | 17340 | _x_x_mozilla_page_load_details 19:30:34 INFO - PROCESS | 17340 | |i|pagename|runs| 19:30:34 INFO - PROCESS | 17340 | |0;dojo.html;2997.5803387525752;3051.9237019074526;3061.678523755007;3082.044566184484;3083.5528848316994;5085.923711144335;5185.274061631375;5249.86875328117;5325.041492531343;5352.571794256465;5189.8183563575285;5261.526462618363;5273.367195936484;5309.150535914255;5321.095413779657;4712.976435117819;4762.095201911643;4802.207635740098;4811.398575178103;4845.297431872394;4828.299896515004;4861.586765124968;4862.6839255448285;4868.56182943535;4900.779464924087;4731.266653668669;4737.857864264078;4748.7863046163;4769.284607308931;4847.321375007502;4815.349927759743;4841.515848415165;4841.85474435764;4847.345608342899;4850.223964165716;4753.453352864396;4760.476347601761;4761.619070474354;4761.6904901181315;4777.617790576745;5192.8961420771375;5208.322918020633;5215.348081489814;5221.843344699665;5275.340582427197;5371.248025276425;5420.83737487876;5422.376426710939;5498.3127109111365;5505.834824955257;5506.779728810843;5509.917351239735;5521.972390138024;5524.06090964537;5526.447355264481;5144.845654630367;5186.559142472895;5189.40321862983;5256.816011439658;5272.05103081449;5787.797427090031;5794.768209271582;5843.474087332161;5854.531637469077;5857.15071314657;5553.305836770403;5557.055300598908;5557.166425036196;5560.304961879765;5563.610547261653;5574.526165275997;5641.153826925994;5642.097264437671;5649.774009039675;5654.15187721845;5612.102063669793;5624.184493248456;5626.071698169838;5626.746796394172;5629.634073785272;5523.25436066134;5569.248151499496;5631.662100273915;5644.3508996465935;5644.8306550803545;4252.213340532012;4261.91476170481;4399.12017596482;4401;4408.559144085566;3798.658120769089;3812.675922546559;3824.78963656996;3866.478025466582;3872.496575445174;5069.087564238399;5228.137357336073;5257.684538927677;5261.158214685632;5319.388270348882;5364.436734142937;5600.5799565032785;5651.2935883014625;5665.66006039639;5684.6020778545935;5338.3593968724;5339.546138578265;5354.839354819362;5367.194920761917;5372.274742909735;4685.9531404686195;4859.312192943265;4879.609631229493;4884.560389564886;4938.160512712848;5596.916046259228;5784.566157538202;5832.737526811219;5848.1227815827115;5848.561357898089;5638.351589567253;5639.689817060101;5643.238162847963;5643.266375371176;5644.9435505644415;5788.797392091256;5796.362400135898;5797.739101740347;5803.187553742395;5803.7388317524965;5416.431274716098;5469.726513674221;5518.668879867221;5541.972290138524;5558.194061860927;5634.887302254014;5671.914921276267;5679.801206957736;5681.17622944679;5737.540996720336;5511.751971161388;5545.223668686306;5558.527525160245;5562.054450743464;5566.666000039849;5356.919646205388;5408.75660595282;5478.780848766164;5535.0590399632965;5618.129189975561;5431.972840135774;5518.2826232589505;5526;5581.10702287632;5586;5575.330960284792;5575.414581468887;5576.5817563683695;5582.49757521825;5587.692676902647;5190.351206099238;5236.711980841091;5469;5472.370677372153;5536.861578460571 19:30:34 INFO - PROCESS | 17340 | |1;ext.html;569.1576466829093;603.7072020070265;619.355869895309;631.1195881744966;659.7262136213471;33216.18365337027;42600.360994585106;43689.34465983013;43700.125997480034;43704.56295437045;44168.33747493782;44406.33390499137;44621.55378446214;44681;44779.55220447794;9007.954960225206;9162.725118246462;9170.770730731718;9173.862392064124;9176.265898728087;8965.731028069169;8969.820603587925;9047.276217902585;9050;9060.229880460169;9063.45619262843;9105.726828195131;9116.270698344117;9118.772030699252;9122.407043542182;9030.051844556325;9040.457572545603;9068.455892646396;9070.682526111588;9081.046490118542;8838.646454141826;8873.29013678904;8962.37263391563;8963.193312601898;8968.775780605538;41960.370594441105;42049.579504205176;42084.78957605223;42135.157296854195;42550;3474.739394545394;3575.481555174511;3595.928081438383;3622.5290712207507;3665.2486240320936;32386.838065809756;32571.674283257336;32582.83708581442;32644.6735532644;32751.6724832751;4305.160493703729;4469.195544801934;4485.551444855521;4513.6684678020065;4641.582257596832;333928;334896;336868;337492.3125384382;339889;56949.430505694414;57349.71325143431;57417.425825742044;57417.71291143518;57465.42534574601;59485;59734;59788;59789.70105149446;59816;15657;15840.366385344458;15899.682006359808;15902;15936;12391.690207744698;12448.75102497945;12455.688607784878;12461.068534377186;12466.937665311616;14588.270586470633;15791;15873.365065397486;15878.9206053969;16092;6089.878202435926;6244.501319683334;6348.492120630339;6351.157549682468;6472.255690595548;915.9038300978434;1007.4509392381024;1046.926715129949;1076.7469644633504;1080.5515710979855;31291;31530.527042094385;31557.526637100003;31662.525062124085;32347.191320216712;859.879616853641;1052.7894421115807;1134.7219931116929;1177.3701069927547;1193.9223950443193;15618.84381156174;16176.67646647084;16250.918745406434;16372.754408683879;16599.585010374594;506580;508976;509687.160949365;510300;511742;56872.146917795435;56918.43081569131;57131;57174.42825571691;57512;1195.3664557784389;1225.1117939493975;1304.804279358104;1345.8654134586463;1348.736996285715;29973.700262997092;32806;32945.835270823496;32947.835260823544;33466.83266583652;52861.20708189303;54731;54739.72630136824;59072.70463647654;59129.45223286616;467751;470434;472264;472291;473005;26744.732552675003;27664.861675692297;27701.168964931083;28694.704423645642;28785.280367990967;46992.53007470018;47717.76141119411;47863;47954;48323;40343;40602.79698601488;42278.577214228695;42502.57497424986;42926;39509.604903950596;39708;40095.799521002205;40734.38898416467;41469.43836178078;39441.60558394494;40070.79964600275;40385.798071009456;40527.59472405238;40662.18675626412;33030.834845825615;36148;36363.45454818127;37043.259134817694;37069.6293037077;27463.588046178924;27492.45015099647;27681.72318276872;27942.3014424641;28269.57595636026 19:30:34 INFO - PROCESS | 17340 | |2;jquery.html;382.76651242741906;385.59705108161984;395.29834543684944;406.33361287488526;416.3795944043374;13540;15352.846471535282;16998.150092495387;19376.128074236658;19562.11970461329;18493.630127397442;19198.23207071719;19245.23019079235;19830.206791728313;19868.50328741782;5579;5629.802956896499;5886.117082437626;5892.351841297454;5893.263342082239;5621.381648018714;5694.316681998166;5718.628289161191;5734.741936612862;5746.770129194827;5593.52455041322;5670.0927851543765;5670.121131224667;5674.687892165929;5677.205191273225;5841.912371314434;5939.70301484924;5949.077892926625;5950.851228719273;5956.910646340309;5639.915401268984;5742.196092547047;5770.653760774325;5774.758426938229;5782.45066718661;13811.240381778998;14052.227127507984;14053.08654937436;16767.736355591962;22197.445063873372;2247.2471721973156;2251.977480225193;2451.1552332263313;2463.19946017545;2753.2703833484143;9629.759256018586;10107.090361867467;10375.481225938749;10571.841422378671;10715.410652414113;1904.1431355889904;1916.0802814649046;1952.0922770911595;1961.5390383259924;1962;61929.38070619281;65749.3425065748;65848;79346.6032669833;83302;8683.522406267715;9990.400575965465;10116.747081323027;10785.83821242682;11201.43179954807;15532.301046452936;15556.766648500281;15593.376264949275;15691.215439227994;15825.446109386115;7651.464397492234;7958.363330933512;8063.314618257396;8327.750167494985;8337.666493340319;7585.924140758632;7676.270754278335;7679;7880.724174653974;7889.9211007890335;4645.513435700544;4732.716037037789;4792.616590672737;4800.73595952226;4843.636727245471;1930.3436831477366;1962.0876292524044;1992.641324561593;2017.7175195472646;2022.7029941195756;841.6212704283097;923.7921467669855;935.0836180543106;960.0399600399601;999.7150812018685;18187.909060454876;18489.260429582668;19070.61858762817;19117.33089341894;19413.8058619412;919.4621146629163;938.6010945348199;982.3418309732497;992.7418871093568;1003.4430890855646;10056.497175141214;10714.41070741101;10757.83863242052;10773.676789696321;10776.19178561611;464523;469777;472184;485740;487781.6607422289;86276;87171;89289.10710892938;91785.54107229422;92422.53788731013;878.9165029322204;955.9856602150974;1025.0467065629036;1028.095276156992;1051.9053285204368;15334.463293784664;16494.092824894746;16804.243809028867;17724;17738.91130544339;39486;39957.80021099876;40258;41010.1797964045;41509.58490415057;54197;54715.45284547104;54867.725661371434;56717.43282567122;56860;7993.280604745601;8125.146859579895;8158.224968627971;8353.24820766134;8361.790955226168;11113.499892505019;12359.196652217577;12370.13409061357;12476.937615312228;12549.686257843261;10579.523921423708;10780.29928054674;10843;10858;11113.722156946142;5220.269162317202;8103.149169337371;10793.19051071157;10881;11108.222424430212;10768.3538987661;11077.889221108007;11144.60993865211;11573.247738896946;11577.826332604849;8356.164383561838;8453.239208471266;8584.227419532272;8652.78368040804;8717.91282087171;9839.901600983898;11241.887144473296;11492.885071149181;11563.88436115628;11758.294502329887 19:30:34 INFO - PROCESS | 17340 | |3;mootools.html;1472.7569950958093;1499.902506337088;1591.8487743664348;1725.896446213228;1775.3519965212693;8502.234798868101;9190.44857308562;9192.35653504255;9194.080591940803;9194.402363846346;7542.170361260257;7817.257360550739;8466.872996905051;8603.91396086039;8607.827843443127;2402.1352313167245;2432.8661923594195;2433.221369161869;2434.415740222352;2435.659007738918;2372.0511795281855;2386.653935179398;2429.8056155507606;2430.161594249981;2432.6351047342864;2416.613341865302;2422.5397174536783;2437.3297343230574;2437.500312435955;2444.8655323957173;2338.298510446859;2341.1220792202926;2344.6248600223967;2348.847324923883;2373.715154181492;2344.906203751848;2347.213683416058;2355.0462062864567;2356.7643235676464;2368.9526209475885;8902.554872256362;8938.46369217849;8979.820403591957;8980.640774369018;8980.955095224548;5237.79048838046;5366.409694933554;5383.192521121824;5390.110631745757;5392.541633961078;3484.4599087141282;3485.9651403485896;3544.946825797615;3562.1806984393734;3562.69717074049;1116.402724542374;1123.3428444360059;1126.4030064065976;1134.1267224237374;1141.9657410277705;12395.504179832886;12448.25310481365;12460.376981150906;12498.937505312506;12502.249865008129;4502.864914052584;4567.451905771329;4658.231391820312;4703.435587729495;4705.929411058837;10387.636432724817;10394.532246048946;10446.425446600359;10460.163186945178;10460.52927618259;5336.839894803162;5346.091164501966;5351.304330437096;5402.621816472809;5405.459454054641;4211.494620645542;4224;4398.824047038082;4898.191798353232;4957.033378491232;3909.218156368681;4038.4749982502676;4137.731047481904;4146.771927543954;4176.895577610523;1295.831541899566;1300.024981264052;1331.8668133186604;1350.372076984207;1357.321339330335;855.0466230153335;881.969131080409;887;896.5965315607872;935.2144198872979;5686.658800471985;5850.385709500526;5851.824445266649;5904.940950590439;6093.908591371133;919.3013309884402;952.8523078922778;959.4818797849223;976.4190306767462;1011.5700827148432;3649.3213121964195;3926.234384295035;4008.819603117867;4081.163361510883;4088.1823635273063;11098.223124381217;11289.774204515863;11379.43102844871;11402.600908968312;11402.657920262405;11276.887231127748;11281.492332845042;11308.773824523629;11340.886591134147;11354.205205635526;8389.203025712548;8496.490210587384;8510.276626486819;8516.914830851736;8520.616572254263;5996.730147143477;6001.969990150022;6120.510359171345;6133.509319254538;6304.243490781136;9768.853467197856;10194.286399951932;10272.486375681336;10320;10324.22568307365;10213.591456341663;10409.115225205942;10916.01755841978;10948.069414099573;10952.288101273392;7226.494145409771;7282.67227974753;8351.081381047958;8625.094365091574;8835.955820220857;8435.283000944988;8903.376763626744;8906.242969347679;8926.73219803407;8928.419652722552;7500.36246919018;7725.304722574995;7730.574818385043;7844;7912.525248485109;7624.771256862303;7631.1605723371495;7656.961715191611;7729.9227007729205;7755;7121.394681452135;7253.854922901618;7259.818504537428;7268.563886166847;7268.963655181902;4826.855194344175;5058.64589478733;5098.107831129611;5125.308083408841;5263.710495922761;5031.245313203049;5040.949590504048;5143.099957507496;5178.042062218502;5231.607629427732 19:30:34 INFO - PROCESS | 17340 | |4;prototype.html;327.91095044791456;342.39054483020226;342.609425255209;346;346.9028671971848;5232.895342093156;5272.525472707455;5305.257263983045;5319.335083114611;5324.680519168843;4934.235193544997;5064.417591976924;5076.213186956019;5090.974545127269;5240.816571420001;4014.1369605534805;4064.796760161995;4111.52717437494;4141.399497072923;4144.150449157915;4140.875773726793;4141.233871733739;4144.709870309064;4155.563665815092;4166.708330416872;3883.184531248441;4077.9592204078026;4090.284200264957;4122.175564887019;4140.192662430827;4074.6943979201383;4083.203775263825;4086.4483294755423;4087.6321131098343;4093.7953102344914;3598.46023096535;3601.891943241707;3603.6756691897854;3611.205534782344;3612.9522438492804;4804.95195048052;5037.219231019198;5107.8507335849135;5113.411957624883;5119.2321151827155;4558.316252562109;4589.495155532889;4605.134920357227;4609.216433206363;4631.16639004979;5437.537809286176;5449.7275136243425;5458.563314934796;5468.671879687232;5477.424870388591;3816.313063648542;3918.921621567581;3925.7840818754958;3932.6067393260732;3938.5864484229314;5586.301712285965;5603.2715746953045;5603.327600687903;5634.211210430543;5638.4361563843695;3918.7060970427333;3923.6076392360537;3926.4895563577124;3926.784026878549;3929.1945151243926;3961.5246170459727;3964.32606456903;3970.3448930926074;3976.443297938291;3986.202759448064;4406.603405693503;4580.679352445363;4632.143053535106;4638.933045399577;4654.883627909329;5447.046766815792;5452.727363631802;5460.399356070829;5461.781528738886;5472;1327.283267035809;1396.7136736968896;1417.3551034279378;1420.034376623885;1425.536700572318;862.2584577263548;891.4918496457087;913.4154141349542;926.6293482607011;944.9858252126223;4624.45080897903;4627.472933931795;4657.930131048037;4659.297607387707;4686.226772582554;4867.488913664014;4883.755812209375;4900.436449808247;4923.310736496894;4928.186849169847;4437.711548749321;4622.491525932145;4634.745089020136;4662.090892276042;4675.836345727883;2605.3356394119755;2605.387733882536;2606.335384476984;2612.8895219531623;2613.9346516337246;4971.527704868032;5005.27423523587;5028.547430731252;5029.5221953914315;5032.47159048302;4647.582487341393;4877.707337559757;4949.975250123726;4960.578350840219;4994.550490455877;4960.652754307236;4975.189018204703;4975.601951843843;4995.9508503214;4998.775055122455;4848.781804818721;4863.294822250683;4864.270359446112;4869.709526975284;4908.239222920453;4678.195510941851;4709.905801884011;4843.094143513864;4910.508949105061;4915;4989.129076596277;4999.525045120708;5008.749562521786;5060.291559181644;5072.264521644414;3674.375356189508;3675.963240367562;3683.8710645127294;3687.539057617798;3694.07648087978;4753.502646666289;4807.879803004953;4882.072406242803;4889.217725164099;4942.851714448572;4776.23580227162;4782.210935195793;4784.425868895755;4812.422509298766;4832.8791780205775;4768.877849357003;4778.163821331323;4829.806807727651;4830.082284365959;4837.879053023703;4770.976145119391;4775.76121193932;5040.445550989315;5222.477752224899;5429.647072940246;5350.866228344323;5399.163129714901;5402.702851343214;5455.454454554671;5458.144230961492;5413.133898576209;5434.64674796137;5438.4561543846885;5439.401665816677;5447.766415769674 19:30:34 INFO - PROCESS | 17340 | |5;yui.html;715.2542878004439;737.8598066367391;747.3908764357045;751.113685850696;762.6872982077349;15877.206139693013;17238.138093095327;17250.913745431288;17252.482425527254;17270;15094.16982065986;15137.24313784312;15221.315040823161;15257.923710381432;15288.770668439955;655.416679155552;770.4491288728558;792.0455850699922;795.5187111797366;795.9283664470197;663.5719960625394;773.6093272897207;791.9128895821484;801.1787917384698;805.4442434720024;850.8255807559466;854.6581367453007;854.8418542569614;854.8974123105236;855.7732200966749;695.401954319285;842.3135144856952;843.8059246373338;844.5312851367488;844.5566077809137;512.344199424737;590.748931704024;602.105872778923;606.75123199488;608.9421504957022;14999.475018374305;15002.549923502313;15023.77464338025;15025.774613380809;15040.699186016218;12755.808662870064;13339.866601334057;13581.728365432637;13601.115927464683;13755.931220343835;12048.939755301255;12125.636230913087;12155.817662735066;12504.999600031975;12849.807252891214;4409.581089796464;4459.3756874037335;4472.530384309665;4609.779672358677;4686.133065382881;69968;71155.2884471159;72963.27036729618;80866.59566702129;80893.59553202255;11934.820977685342;11935.940320298343;11973.880261197452;12070.27578345302;12169.939150304192;21843.453913651967;21913.890430547744;21927.561448770935;21998.120075196814;22074.22740204085;8104.0680321762575;8260.669573217125;8270.518844346792;8311.16888311176;8583.313334933191;4330.220560299176;4710.552497512731;4751.263554149113;4840.128776820206;4921.803127874845;3473.704735097545;3575.6960658344333;3586.4398986440983;3606.891793246207;3611.7291203159343;576.2278546747295;579.8289504596154;591.4263164730205;591.5858898770791;595.3183604772511;726.3462883404873;729.8686236477484;757.7044952468542;770.5838847022545;786.3434032582858;9734.41593504392;10335.896641033643;10365.067143956929;10384.42885641297;10461.320014199206;623.5977794322637;640.2924768131148;674.9571911396904;677.1298880937971;745.1356426545182;4411.20598292304;4573;4641.303804429295;4708.115804461333;4789.688670236424;104042;106364;107761;110325.44837275762;110728.4463577677;58277;94768;95055.52472237733;98696.50651746696;101409;75507.24492755112;82208;83360;84753.15246847576;85687;8798.38411311202;9153.725388238363;9162.862557061517;9209.401388909699;9258.30562707813;47792.522074778804;48370.61405701567;48799.5120048795;49650.255246172055;49792.25311620256;70155.29844701488;73461;73587.26412736018;74484.62757686176;74509.6274518624;12297.44661490253;12358.382080896099;12458.252504849737;12475.251484910934;12568.622941311774;13964.4414223434;14075.648108797363;14189.716205676035;14353.712925741218;15029.924850375679;12422.37888105573;13472.59582212496;13549.86450135486;13673.384697688429;14134.575962721136;12103.878961210276;12618.621441356774;12694.809577856524;12727.80908286358;12775.425105870445;12402.75194496123;12893.290869002294;12943.223406595635;13034.47862085506;13462.361311507879;10252.64115755945;10664.200184986312;10716.946415267874;10867.510962006887;11011.119110470983;10162.39025658463;10437.425941573287;11291.830622540832;11324.433778311217;11379.146564007568 19:30:34 INFO - PROCESS | 17340 | __end_tp_report 19:30:34 INFO - PROCESS | 17340 | __start_cc_report 19:30:34 INFO - PROCESS | 17340 | _x_x_mozilla_cycle_collect,990 19:30:34 INFO - PROCESS | 17340 | __end_cc_report 19:30:34 INFO - PROCESS | 17340 | __startTimestamp1474338634762__endTimestamp 19:30:34 INFO - PROCESS | 17340 | 19:30:34 INFO - PROCESS | 17340 | ------- Summary: start ------- 19:30:34 INFO - PROCESS | 17340 | Number of tests: 6 19:30:34 INFO - PROCESS | 17340 | 19:30:34 INFO - PROCESS | 17340 | [#0] dojo.html Cycles:165 Average:5222.75 Median:5420.84 stddev:586.81 (10.8%) stddev-sans-first:562.04 19:30:34 INFO - PROCESS | 17340 | Values: 2997.6 3051.9 3061.7 3082.0 3083.6 5085.9 5185.3 5249.9 5325.0 5352.6 5189.8 5261.5 5273.4 5309.2 5321.1 4713.0 4762.1 4802.2 4811.4 4845.3 4828.3 4861.6 4862.7 4868.6 4900.8 4731.3 4737.9 4748.8 4769.3 4847.3 4815.3 4841.5 4841.9 4847.3 4850.2 4753.5 4760.5 4761.6 4761.7 4777.6 5192.9 5208.3 5215.3 5221.8 5275.3 5371.2 5420.8 5422.4 5498.3 5505.8 5506.8 5509.9 5522.0 5524.1 5526.4 5144.8 5186.6 5189.4 5256.8 5272.1 5787.8 5794.8 5843.5 5854.5 5857.2 5553.3 5557.1 5557.2 5560.3 5563.6 5574.5 5641.2 5642.1 5649.8 5654.2 5612.1 5624.2 5626.1 5626.7 5629.6 5523.3 5569.2 5631.7 5644.4 5644.8 4252.2 4261.9 4399.1 4401.0 4408.6 3798.7 3812.7 3824.8 3866.5 3872.5 5069.1 5228.1 5257.7 5261.2 5319.4 5364.4 5600.6 5651.3 5665.7 5684.6 5338.4 5339.5 5354.8 5367.2 5372.3 4686.0 4859.3 4879.6 4884.6 4938.2 5596.9 5784.6 5832.7 5848.1 5848.6 5638.4 5639.7 5643.2 5643.3 5644.9 5788.8 5796.4 5797.7 5803.2 5803.7 5416.4 5469.7 5518.7 5542.0 5558.2 5634.9 5671.9 5679.8 5681.2 5737.5 5511.8 5545.2 5558.5 5562.1 5566.7 5356.9 5408.8 5478.8 5535.1 5618.1 5432.0 5518.3 5526.0 5581.1 5586.0 5575.3 5575.4 5576.6 5582.5 5587.7 5190.4 5236.7 5469.0 5472.4 5536.9 19:30:34 INFO - PROCESS | 17340 | 19:30:34 INFO - PROCESS | 17340 | [#1] ext.html Cycles:180 Average:59934.95 Median:42390.58 stddev:118052.04 (278.5%) stddev-sans-first:118299.06 19:30:34 INFO - PROCESS | 17340 | Values: 569.2 603.7 619.4 631.1 659.7 33216.2 42600.4 43689.3 43700.1 43704.6 44168.3 44406.3 44621.6 44681.0 44779.6 9008.0 9162.7 9170.8 9173.9 9176.3 8965.7 8969.8 9047.3 9050.0 9060.2 9063.5 9105.7 9116.3 9118.8 9122.4 9030.1 9040.5 9068.5 9070.7 9081.0 8838.6 8873.3 8962.4 8963.2 8968.8 41960.4 42049.6 42084.8 42135.2 42550.0 3474.7 3575.5 3595.9 3622.5 3665.2 32386.8 32571.7 32582.8 32644.7 32751.7 4305.2 4469.2 4485.6 4513.7 4641.6 333928.0 334896.0 336868.0 337492.3 339889.0 56949.4 57349.7 57417.4 57417.7 57465.4 59485.0 59734.0 59788.0 59789.7 59816.0 15657.0 15840.4 15899.7 15902.0 15936.0 12391.7 12448.8 12455.7 12461.1 12466.9 14588.3 15791.0 15873.4 15878.9 16092.0 6089.9 6244.5 6348.5 6351.2 6472.3 915.9 1007.5 1046.9 1076.7 1080.6 31291.0 31530.5 31557.5 31662.5 32347.2 859.9 1052.8 1134.7 1177.4 1193.9 15618.8 16176.7 16250.9 16372.8 16599.6 506580.0 508976.0 509687.2 510300.0 511742.0 56872.1 56918.4 57131.0 57174.4 57512.0 1195.4 1225.1 1304.8 1345.9 1348.7 29973.7 32806.0 32945.8 32947.8 33466.8 52861.2 54731.0 54739.7 59072.7 59129.5 467751.0 470434.0 472264.0 472291.0 473005.0 26744.7 27664.9 27701.2 28694.7 28785.3 46992.5 47717.8 47863.0 47954.0 48323.0 40343.0 40602.8 42278.6 42502.6 42926.0 39509.6 39708.0 40095.8 40734.4 41469.4 39441.6 40070.8 40385.8 40527.6 40662.2 33030.8 36148.0 36363.5 37043.3 37069.6 27463.6 27492.5 27681.7 27942.3 28269.6 19:30:34 INFO - PROCESS | 17340 | 19:30:34 INFO - PROCESS | 17340 | [#2] jquery.html Cycles:180 Average:27716.29 Median:41259.88 stddev:78407.09 (190.0%) stddev-sans-first:78600.18 19:30:34 INFO - PROCESS | 17340 | Values: 382.8 385.6 395.3 406.3 416.4 13540.0 15352.8 16998.2 19376.1 19562.1 18493.6 19198.2 19245.2 19830.2 19868.5 5579.0 5629.8 5886.1 5892.4 5893.3 5621.4 5694.3 5718.6 5734.7 5746.8 5593.5 5670.1 5670.1 5674.7 5677.2 5841.9 5939.7 5949.1 5950.9 5956.9 5639.9 5742.2 5770.7 5774.8 5782.5 13811.2 14052.2 14053.1 16767.7 22197.4 2247.2 2252.0 2451.2 2463.2 2753.3 9629.8 10107.1 10375.5 10571.8 10715.4 1904.1 1916.1 1952.1 1961.5 1962.0 61929.4 65749.3 65848.0 79346.6 83302.0 8683.5 9990.4 10116.7 10785.8 11201.4 15532.3 15556.8 15593.4 15691.2 15825.4 7651.5 7958.4 8063.3 8327.8 8337.7 7585.9 7676.3 7679.0 7880.7 7889.9 4645.5 4732.7 4792.6 4800.7 4843.6 1930.3 1962.1 1992.6 2017.7 2022.7 841.6 923.8 935.1 960.0 999.7 18187.9 18489.3 19070.6 19117.3 19413.8 919.5 938.6 982.3 992.7 1003.4 10056.5 10714.4 10757.8 10773.7 10776.2 464523.0 469777.0 472184.0 485740.0 487781.7 86276.0 87171.0 89289.1 91785.5 92422.5 878.9 956.0 1025.0 1028.1 1051.9 15334.5 16494.1 16804.2 17724.0 17738.9 39486.0 39957.8 40258.0 41010.2 41509.6 54197.0 54715.5 54867.7 56717.4 56860.0 7993.3 8125.1 8158.2 8353.2 8361.8 11113.5 12359.2 12370.1 12476.9 12549.7 10579.5 10780.3 10843.0 10858.0 11113.7 5220.3 8103.1 10793.2 10881.0 11108.2 10768.4 11077.9 11144.6 11573.2 11577.8 8356.2 8453.2 8584.2 8652.8 8717.9 9839.9 11241.9 11492.9 11563.9 11758.3 19:30:34 INFO - PROCESS | 17340 | 19:30:34 INFO - PROCESS | 17340 | [#3] mootools.html Cycles:180 Average:5893.40 Median:4680.83 stddev:3377.96 (72.2%) stddev-sans-first:3371.10 19:30:34 INFO - PROCESS | 17340 | Values: 1472.8 1499.9 1591.8 1725.9 1775.4 8502.2 9190.4 9192.4 9194.1 9194.4 7542.2 7817.3 8466.9 8603.9 8607.8 2402.1 2432.9 2433.2 2434.4 2435.7 2372.1 2386.7 2429.8 2430.2 2432.6 2416.6 2422.5 2437.3 2437.5 2444.9 2338.3 2341.1 2344.6 2348.8 2373.7 2344.9 2347.2 2355.0 2356.8 2369.0 8902.6 8938.5 8979.8 8980.6 8981.0 5237.8 5366.4 5383.2 5390.1 5392.5 3484.5 3486.0 3544.9 3562.2 3562.7 1116.4 1123.3 1126.4 1134.1 1142.0 12395.5 12448.3 12460.4 12498.9 12502.2 4502.9 4567.5 4658.2 4703.4 4705.9 10387.6 10394.5 10446.4 10460.2 10460.5 5336.8 5346.1 5351.3 5402.6 5405.5 4211.5 4224.0 4398.8 4898.2 4957.0 3909.2 4038.5 4137.7 4146.8 4176.9 1295.8 1300.0 1331.9 1350.4 1357.3 855.0 882.0 887.0 896.6 935.2 5686.7 5850.4 5851.8 5904.9 6093.9 919.3 952.9 959.5 976.4 1011.6 3649.3 3926.2 4008.8 4081.2 4088.2 11098.2 11289.8 11379.4 11402.6 11402.7 11276.9 11281.5 11308.8 11340.9 11354.2 8389.2 8496.5 8510.3 8516.9 8520.6 5996.7 6002.0 6120.5 6133.5 6304.2 9768.9 10194.3 10272.5 10320.0 10324.2 10213.6 10409.1 10916.0 10948.1 10952.3 7226.5 7282.7 8351.1 8625.1 8836.0 8435.3 8903.4 8906.2 8926.7 8928.4 7500.4 7725.3 7730.6 7844.0 7912.5 7624.8 7631.2 7657.0 7729.9 7755.0 7121.4 7253.9 7259.8 7268.6 7269.0 4826.9 5058.6 5098.1 5125.3 5263.7 5031.2 5040.9 5143.1 5178.0 5231.6 19:30:34 INFO - PROCESS | 17340 | 19:30:34 INFO - PROCESS | 17340 | [#4] prototype.html Cycles:180 Average:4332.37 Median:4794.69 stddev:1224.09 (25.5%) stddev-sans-first:1190.05 19:30:34 INFO - PROCESS | 17340 | Values: 327.9 342.4 342.6 346.0 346.9 5232.9 5272.5 5305.3 5319.3 5324.7 4934.2 5064.4 5076.2 5091.0 5240.8 4014.1 4064.8 4111.5 4141.4 4144.2 4140.9 4141.2 4144.7 4155.6 4166.7 3883.2 4078.0 4090.3 4122.2 4140.2 4074.7 4083.2 4086.4 4087.6 4093.8 3598.5 3601.9 3603.7 3611.2 3613.0 4805.0 5037.2 5107.9 5113.4 5119.2 4558.3 4589.5 4605.1 4609.2 4631.2 5437.5 5449.7 5458.6 5468.7 5477.4 3816.3 3918.9 3925.8 3932.6 3938.6 5586.3 5603.3 5603.3 5634.2 5638.4 3918.7 3923.6 3926.5 3926.8 3929.2 3961.5 3964.3 3970.3 3976.4 3986.2 4406.6 4580.7 4632.1 4638.9 4654.9 5447.0 5452.7 5460.4 5461.8 5472.0 1327.3 1396.7 1417.4 1420.0 1425.5 862.3 891.5 913.4 926.6 945.0 4624.5 4627.5 4657.9 4659.3 4686.2 4867.5 4883.8 4900.4 4923.3 4928.2 4437.7 4622.5 4634.7 4662.1 4675.8 2605.3 2605.4 2606.3 2612.9 2613.9 4971.5 5005.3 5028.5 5029.5 5032.5 4647.6 4877.7 4950.0 4960.6 4994.6 4960.7 4975.2 4975.6 4996.0 4998.8 4848.8 4863.3 4864.3 4869.7 4908.2 4678.2 4709.9 4843.1 4910.5 4915.0 4989.1 4999.5 5008.7 5060.3 5072.3 3674.4 3676.0 3683.9 3687.5 3694.1 4753.5 4807.9 4882.1 4889.2 4942.9 4776.2 4782.2 4784.4 4812.4 4832.9 4768.9 4778.2 4829.8 4830.1 4837.9 4771.0 4775.8 5040.4 5222.5 5429.6 5350.9 5399.2 5402.7 5455.5 5458.1 5413.1 5434.6 5438.5 5439.4 5447.8 19:30:34 INFO - PROCESS | 17340 | 19:30:34 INFO - PROCESS | 17340 | [#5] yui.html Cycles:180 Average:20083.19 Median:4591.39 stddev:28345.68 (617.4%) stddev-sans-first:28387.89 19:30:34 INFO - PROCESS | 17340 | Values: 715.3 737.9 747.4 751.1 762.7 15877.2 17238.1 17250.9 17252.5 17270.0 15094.2 15137.2 15221.3 15257.9 15288.8 655.4 770.4 792.0 795.5 795.9 663.6 773.6 791.9 801.2 805.4 850.8 854.7 854.8 854.9 855.8 695.4 842.3 843.8 844.5 844.6 512.3 590.7 602.1 606.8 608.9 14999.5 15002.5 15023.8 15025.8 15040.7 12755.8 13339.9 13581.7 13601.1 13755.9 12048.9 12125.6 12155.8 12505.0 12849.8 4409.6 4459.4 4472.5 4609.8 4686.1 69968.0 71155.3 72963.3 80866.6 80893.6 11934.8 11935.9 11973.9 12070.3 12169.9 21843.5 21913.9 21927.6 21998.1 22074.2 8104.1 8260.7 8270.5 8311.2 8583.3 4330.2 4710.6 4751.3 4840.1 4921.8 3473.7 3575.7 3586.4 3606.9 3611.7 576.2 579.8 591.4 591.6 595.3 726.3 729.9 757.7 770.6 786.3 9734.4 10335.9 10365.1 10384.4 10461.3 623.6 640.3 675.0 677.1 745.1 4411.2 4573.0 4641.3 4708.1 4789.7 104042.0 106364.0 107761.0 110325.4 110728.4 58277.0 94768.0 95055.5 98696.5 101409.0 75507.2 82208.0 83360.0 84753.2 85687.0 8798.4 9153.7 9162.9 9209.4 9258.3 47792.5 48370.6 48799.5 49650.3 49792.3 70155.3 73461.0 73587.3 74484.6 74509.6 12297.4 12358.4 12458.3 12475.3 12568.6 13964.4 14075.6 14189.7 14353.7 15029.9 12422.4 13472.6 13549.9 13673.4 14134.6 12103.9 12618.6 12694.8 12727.8 12775.4 12402.8 12893.3 12943.2 13034.5 13462.4 10252.6 10664.2 10716.9 10867.5 11011.1 10162.4 10437.4 11291.8 11324.4 11379.1 19:30:34 INFO - PROCESS | 17340 | -------- Summary: end -------- 19:30:34 INFO - PROCESS | 17340 | 19:30:35 INFO - TEST-INFO | 17340: exit 0 19:30:35 INFO - TEST-OK | dromaeo_css | took 1115897ms 19:30:35 INFO - TEST-START | kraken 19:30:35 INFO - operating with platform_type : linux_ 19:30:35 INFO - Initialising browser for kraken test... 19:30:35 INFO - TEST-INFO | started process 17648 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQUZ16x/profile http://localhost:45335/getInfo.html) 19:30:39 INFO - PROCESS | 17648 | __metrics Screen width/height:1600/1200 19:30:39 INFO - PROCESS | 17648 | colorDepth:24 19:30:39 INFO - PROCESS | 17648 | Browser inner width/height: 1024/697 19:30:39 INFO - PROCESS | 17648 | __metrics 19:30:39 INFO - PROCESS | 17648 | 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" 19:30:39 INFO - PROCESS | 17648 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:30:40 INFO - PROCESS | 17648 | console.error: PushService: 19:30:40 INFO - PROCESS | 17648 | stateChangeProcessEnqueue: Error transitioning state 19:30:40 INFO - PROCESS | 17648 | UnknownError 19:30:40 INFO - TEST-INFO | 17648: exit 0 19:30:40 INFO - Browser initialized. 19:30:40 INFO - Running cycle 1/1 for kraken test... 19:30:40 INFO - TEST-INFO | started process 17730 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQUZ16x/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:30:53 INFO - PROCESS | 17730 | RSS: Main: 173424640 19:30:53 INFO - PROCESS | 17730 | 19:30:55 INFO - PROCESS | 17730 | Cycle 1(1): loaded http://localhost:45335/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:45335/tests/kraken/driver.html?testName=audio-beat-detection) 19:30:56 INFO - PROCESS | 17730 | RSS: Main: 232529920 19:30:56 INFO - PROCESS | 17730 | 19:30:59 INFO - PROCESS | 17730 | Cycle 1(1): loaded http://localhost:45335/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:45335/tests/kraken/driver.html?testName=audio-dft) 19:30:59 INFO - PROCESS | 17730 | RSS: Main: 216588288 19:30:59 INFO - PROCESS | 17730 | 19:31:02 INFO - PROCESS | 17730 | Cycle 1(1): loaded http://localhost:45335/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:45335/tests/kraken/driver.html?testName=audio-fft) 19:31:03 INFO - PROCESS | 17730 | RSS: Main: 299057152 19:31:03 INFO - PROCESS | 17730 | 19:31:05 INFO - PROCESS | 17730 | Cycle 1(1): loaded http://localhost:45335/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:45335/tests/kraken/driver.html?testName=audio-oscillator) 19:31:06 INFO - PROCESS | 17730 | RSS: Main: 208822272 19:31:06 INFO - PROCESS | 17730 | 19:31:08 INFO - PROCESS | 17730 | Cycle 1(1): loaded http://localhost:45335/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:45335/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:31:08 INFO - PROCESS | 17730 | RSS: Main: 213004288 19:31:08 INFO - PROCESS | 17730 | 19:31:11 INFO - PROCESS | 17730 | Cycle 1(1): loaded http://localhost:45335/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:45335/tests/kraken/driver.html?testName=imaging-darkroom) 19:31:12 INFO - PROCESS | 17730 | RSS: Main: 249622528 19:31:12 INFO - PROCESS | 17730 | 19:31:15 INFO - PROCESS | 17730 | Cycle 1(1): loaded http://localhost:45335/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:45335/tests/kraken/driver.html?testName=imaging-desaturate) 19:31:16 INFO - PROCESS | 17730 | RSS: Main: 248635392 19:31:16 INFO - PROCESS | 17730 | 19:31:19 INFO - PROCESS | 17730 | Cycle 1(1): loaded http://localhost:45335/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:45335/tests/kraken/driver.html?testName=json-parse-financial) 19:31:19 INFO - PROCESS | 17730 | RSS: Main: 248332288 19:31:19 INFO - PROCESS | 17730 | 19:31:21 INFO - PROCESS | 17730 | Cycle 1(1): loaded http://localhost:45335/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:45335/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:31:21 INFO - PROCESS | 17730 | RSS: Main: 210567168 19:31:21 INFO - PROCESS | 17730 | 19:31:23 INFO - PROCESS | 17730 | Cycle 1(1): loaded http://localhost:45335/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:45335/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:31:24 INFO - PROCESS | 17730 | RSS: Main: 218505216 19:31:24 INFO - PROCESS | 17730 | 19:31:26 INFO - PROCESS | 17730 | Cycle 1(1): loaded http://localhost:45335/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:45335/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:31:27 INFO - PROCESS | 17730 | RSS: Main: 222150656 19:31:27 INFO - PROCESS | 17730 | 19:31:29 INFO - PROCESS | 17730 | Cycle 1(1): loaded http://localhost:45335/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:45335/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:31:30 INFO - PROCESS | 17730 | RSS: Main: 223117312 19:31:30 INFO - PROCESS | 17730 | 19:31:33 INFO - PROCESS | 17730 | Cycle 1(1): loaded http://localhost:45335/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:45335/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:31:33 INFO - PROCESS | 17730 | RSS: Main: 221503488 19:31:33 INFO - PROCESS | 17730 | 19:31:35 INFO - PROCESS | 17730 | Cycle 1(1): loaded http://localhost:45335/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:45335/tests/kraken/driver.html?testName=ai-astar) 19:31:35 INFO - PROCESS | 17730 | RSS: Main: 271826944 19:31:35 INFO - PROCESS | 17730 | 19:31:35 INFO - PROCESS | 17730 | __start_tp_report 19:31:35 INFO - PROCESS | 17730 | _x_x_mozilla_page_load 19:31:35 INFO - PROCESS | 17730 | _x_x_mozilla_page_load_details 19:31:35 INFO - PROCESS | 17730 | |i|pagename|runs| 19:31:35 INFO - PROCESS | 17730 | |0;ai-astar;110;112;113;113;113;111;112;111;108;110 19:31:35 INFO - PROCESS | 17730 | |1;audio-beat-detection;141;117;118;165;125;119;118;123;149;119 19:31:35 INFO - PROCESS | 17730 | |2;audio-dft;162;161;162;162;161;161;163;161;164;162 19:31:35 INFO - PROCESS | 17730 | |3;audio-fft;76;76;75;110;76;76;76;74;116;74 19:31:35 INFO - PROCESS | 17730 | |4;audio-oscillator;82;82;112;82;81;82;113;83;83;114 19:31:35 INFO - PROCESS | 17730 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;116;116;116 19:31:35 INFO - PROCESS | 17730 | |6;imaging-darkroom;145;147;146;146;146;147;146;145;145;145 19:31:35 INFO - PROCESS | 17730 | |7;imaging-desaturate;95;96;96;96;96;95;95;95;95;95 19:31:35 INFO - PROCESS | 17730 | |8;json-parse-financial;53;50;50;49;51;50;51;51;51;50 19:31:35 INFO - PROCESS | 17730 | |9;json-stringify-tinderbox;62;62;61;61;62;62;62;62;61;63 19:31:35 INFO - PROCESS | 17730 | |10;stanford-crypto-aes;82;77;79;78;77;84;78;78;79;78 19:31:35 INFO - PROCESS | 17730 | |11;stanford-crypto-ccm;130;123;123;139;133;133;123;122;167;125 19:31:35 INFO - PROCESS | 17730 | |12;stanford-crypto-pbkdf2;165;166;166;167;165;168;167;166;163;168 19:31:35 INFO - PROCESS | 17730 | |13;stanford-crypto-sha256-iterative;66;65;65;65;63;66;65;65;63;64 19:31:35 INFO - PROCESS | 17730 | __end_tp_report 19:31:35 INFO - PROCESS | 17730 | __start_cc_report 19:31:35 INFO - PROCESS | 17730 | _x_x_mozilla_cycle_collect,1634 19:31:35 INFO - PROCESS | 17730 | __end_cc_report 19:31:35 INFO - PROCESS | 17730 | __startTimestamp1474338695540__endTimestamp 19:31:35 INFO - PROCESS | 17730 | 19:31:35 INFO - PROCESS | 17730 | ------- Summary: start ------- 19:31:35 INFO - PROCESS | 17730 | Number of tests: 14 19:31:35 INFO - PROCESS | 17730 | 19:31:35 INFO - PROCESS | 17730 | [#0] ai-astar Cycles:10 Average:111.30 Median:112.00 stddev:1.64 (1.5%) stddev-sans-first:1.67 19:31:35 INFO - PROCESS | 17730 | Values: 110.0 112.0 113.0 113.0 113.0 111.0 112.0 111.0 108.0 110.0 19:31:35 INFO - PROCESS | 17730 | 19:31:35 INFO - PROCESS | 17730 | [#1] audio-beat-detection Cycles:10 Average:129.40 Median:124.00 stddev:16.59 (13.4%) stddev-sans-first:17.05 19:31:35 INFO - PROCESS | 17730 | Values: 141.0 117.0 118.0 165.0 125.0 119.0 118.0 123.0 149.0 119.0 19:31:35 INFO - PROCESS | 17730 | 19:31:35 INFO - PROCESS | 17730 | [#2] audio-dft Cycles:10 Average:161.90 Median:162.00 stddev:0.99 (0.6%) stddev-sans-first:1.05 19:31:35 INFO - PROCESS | 17730 | Values: 162.0 161.0 162.0 162.0 161.0 161.0 163.0 161.0 164.0 162.0 19:31:35 INFO - PROCESS | 17730 | 19:31:35 INFO - PROCESS | 17730 | [#3] audio-fft Cycles:10 Average:82.90 Median:76.00 stddev:15.95 (21.0%) stddev-sans-first:16.72 19:31:35 INFO - PROCESS | 17730 | Values: 76.0 76.0 75.0 110.0 76.0 76.0 76.0 74.0 116.0 74.0 19:31:35 INFO - PROCESS | 17730 | 19:31:35 INFO - PROCESS | 17730 | [#4] audio-oscillator Cycles:10 Average:91.40 Median:82.00 stddev:14.92 (18.2%) stddev-sans-first:15.44 19:31:35 INFO - PROCESS | 17730 | Values: 82.0 82.0 112.0 82.0 81.0 82.0 113.0 83.0 83.0 114.0 19:31:35 INFO - PROCESS | 17730 | 19:31:35 INFO - PROCESS | 17730 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 19:31:35 INFO - PROCESS | 17730 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 116.0 116.0 116.0 19:31:35 INFO - PROCESS | 17730 | 19:31:35 INFO - PROCESS | 17730 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.79 (0.5%) stddev-sans-first:0.78 19:31:35 INFO - PROCESS | 17730 | Values: 145.0 147.0 146.0 146.0 146.0 147.0 146.0 145.0 145.0 145.0 19:31:35 INFO - PROCESS | 17730 | 19:31:35 INFO - PROCESS | 17730 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 19:31:35 INFO - PROCESS | 17730 | Values: 95.0 96.0 96.0 96.0 96.0 95.0 95.0 95.0 95.0 95.0 19:31:35 INFO - PROCESS | 17730 | 19:31:35 INFO - PROCESS | 17730 | [#8] json-parse-financial Cycles:10 Average:50.60 Median:51.00 stddev:1.07 (2.1%) stddev-sans-first:0.71 19:31:35 INFO - PROCESS | 17730 | Values: 53.0 50.0 50.0 49.0 51.0 50.0 51.0 51.0 51.0 50.0 19:31:35 INFO - PROCESS | 17730 | 19:31:35 INFO - PROCESS | 17730 | [#9] json-stringify-tinderbox Cycles:10 Average:61.80 Median:62.00 stddev:0.63 (1.0%) stddev-sans-first:0.67 19:31:35 INFO - PROCESS | 17730 | Values: 62.0 62.0 61.0 61.0 62.0 62.0 62.0 62.0 61.0 63.0 19:31:35 INFO - PROCESS | 17730 | 19:31:35 INFO - PROCESS | 17730 | [#10] stanford-crypto-aes Cycles:10 Average:79.00 Median:78.50 stddev:2.26 (2.9%) stddev-sans-first:2.12 19:31:35 INFO - PROCESS | 17730 | Values: 82.0 77.0 79.0 78.0 77.0 84.0 78.0 78.0 79.0 78.0 19:31:35 INFO - PROCESS | 17730 | 19:31:35 INFO - PROCESS | 17730 | [#11] stanford-crypto-ccm Cycles:10 Average:131.80 Median:131.50 stddev:13.63 (10.4%) stddev-sans-first:14.44 19:31:35 INFO - PROCESS | 17730 | Values: 130.0 123.0 123.0 139.0 133.0 133.0 123.0 122.0 167.0 125.0 19:31:35 INFO - PROCESS | 17730 | 19:31:35 INFO - PROCESS | 17730 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.10 Median:166.50 stddev:1.52 (0.9%) stddev-sans-first:1.56 19:31:35 INFO - PROCESS | 17730 | Values: 165.0 166.0 166.0 167.0 165.0 168.0 167.0 166.0 163.0 168.0 19:31:35 INFO - PROCESS | 17730 | 19:31:35 INFO - PROCESS | 17730 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.70 Median:65.00 stddev:1.06 (1.6%) stddev-sans-first:1.01 19:31:35 INFO - PROCESS | 17730 | Values: 66.0 65.0 65.0 65.0 63.0 66.0 65.0 65.0 63.0 64.0 19:31:35 INFO - PROCESS | 17730 | -------- Summary: end -------- 19:31:35 INFO - PROCESS | 17730 | 19:31:36 INFO - TEST-INFO | 17730: exit 0 19:31:36 INFO - TEST-OK | kraken | took 61153ms 19:31:36 INFO - SUITE-END | took 1177s 19:31:37 INFO - Completed test suite (00:19:37) 19:31:37 INFO - javascript benchmark 19:31:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2997.5803387525752, 3051.9237019074526, 3061.678523755007, 3082.044566184484, 3083.5528848316994, 5085.923711144335, 5185.274061631375, 5249.86875328117, 5325.041492531343, 5352.571794256465, 5189.8183563575285, 5261.526462618363, 5273.367195936484, 5309.150535914255, 5321.095413779657, 4712.976435117819, 4762.095201911643, 4802.207635740098, 4811.398575178103, 4845.297431872394, 4828.299896515004, 4861.586765124968, 4862.6839255448285, 4868.56182943535, 4900.779464924087, 4731.266653668669, 4737.857864264078, 4748.7863046163, 4769.284607308931, 4847.321375007502, 4815.349927759743, 4841.515848415165, 4841.85474435764, 4847.345608342899, 4850.223964165716, 4753.453352864396, 4760.476347601761, 4761.619070474354, 4761.6904901181315, 4777.617790576745, 5192.8961420771375, 5208.322918020633, 5215.348081489814, 5221.843344699665, 5275.340582427197, 5371.248025276425, 5420.83737487876, 5422.376426710939, 5498.3127109111365, 5505.834824955257, 5506.779728810843, 5509.917351239735, 5521.972390138024, 5524.06090964537, 5526.447355264481, 5144.845654630367, 5186.559142472895, 5189.40321862983, 5256.816011439658, 5272.05103081449, 5787.797427090031, 5794.768209271582, 5843.474087332161, 5854.531637469077, 5857.15071314657, 5553.305836770403, 5557.055300598908, 5557.166425036196, 5560.304961879765, 5563.610547261653, 5574.526165275997, 5641.153826925994, 5642.097264437671, 5649.774009039675, 5654.15187721845, 5612.102063669793, 5624.184493248456, 5626.071698169838, 5626.746796394172, 5629.634073785272, 5523.25436066134, 5569.248151499496, 5631.662100273915, 5644.3508996465935, 5644.8306550803545, 4252.213340532012, 4261.91476170481, 4399.12017596482, 4401.0, 4408.559144085566, 3798.658120769089, 3812.675922546559, 3824.78963656996, 3866.478025466582, 3872.496575445174, 5069.087564238399, 5228.137357336073, 5257.684538927677, 5261.158214685632, 5319.388270348882, 5364.436734142937, 5600.5799565032785, 5651.2935883014625, 5665.66006039639, 5684.6020778545935, 5338.3593968724, 5339.546138578265, 5354.839354819362, 5367.194920761917, 5372.274742909735, 4685.9531404686195, 4859.312192943265, 4879.609631229493, 4884.560389564886, 4938.160512712848, 5596.916046259228, 5784.566157538202, 5832.737526811219, 5848.1227815827115, 5848.561357898089, 5638.351589567253, 5639.689817060101, 5643.238162847963, 5643.266375371176, 5644.9435505644415, 5788.797392091256, 5796.362400135898, 5797.739101740347, 5803.187553742395, 5803.7388317524965, 5416.431274716098, 5469.726513674221, 5518.668879867221, 5541.972290138524, 5558.194061860927, 5634.887302254014, 5671.914921276267, 5679.801206957736, 5681.17622944679, 5737.540996720336, 5511.751971161388, 5545.223668686306, 5558.527525160245, 5562.054450743464, 5566.666000039849, 5356.919646205388, 5408.75660595282, 5478.780848766164, 5535.0590399632965, 5618.129189975561, 5431.972840135774, 5518.2826232589505, 5526.0, 5581.10702287632, 5586.0, 5575.330960284792, 5575.414581468887, 5576.5817563683695, 5582.49757521825, 5587.692676902647, 5190.351206099238, 5236.711980841091, 5469.0, 5472.370677372153, 5536.861578460571], "value": 5183.635539579947, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [569.1576466829093, 603.7072020070265, 619.355869895309, 631.1195881744966, 659.7262136213471, 33216.18365337027, 42600.360994585106, 43689.34465983013, 43700.125997480034, 43704.56295437045, 44168.33747493782, 44406.33390499137, 44621.55378446214, 44681.0, 44779.55220447794, 9007.954960225206, 9162.725118246462, 9170.770730731718, 9173.862392064124, 9176.265898728087, 8965.731028069169, 8969.820603587925, 9047.276217902585, 9050.0, 9060.229880460169, 9063.45619262843, 9105.726828195131, 9116.270698344117, 9118.772030699252, 9122.407043542182, 9030.051844556325, 9040.457572545603, 9068.455892646396, 9070.682526111588, 9081.046490118542, 8838.646454141826, 8873.29013678904, 8962.37263391563, 8963.193312601898, 8968.775780605538, 41960.370594441105, 42049.579504205176, 42084.78957605223, 42135.157296854195, 42550.0, 3474.739394545394, 3575.481555174511, 3595.928081438383, 3622.5290712207507, 3665.2486240320936, 32386.838065809756, 32571.674283257336, 32582.83708581442, 32644.6735532644, 32751.6724832751, 4305.160493703729, 4469.195544801934, 4485.551444855521, 4513.6684678020065, 4641.582257596832, 333928.0, 334896.0, 336868.0, 337492.3125384382, 339889.0, 56949.430505694414, 57349.71325143431, 57417.425825742044, 57417.71291143518, 57465.42534574601, 59485.0, 59734.0, 59788.0, 59789.70105149446, 59816.0, 15657.0, 15840.366385344458, 15899.682006359808, 15902.0, 15936.0, 12391.690207744698, 12448.75102497945, 12455.688607784878, 12461.068534377186, 12466.937665311616, 14588.270586470633, 15791.0, 15873.365065397486, 15878.9206053969, 16092.0, 6089.878202435926, 6244.501319683334, 6348.492120630339, 6351.157549682468, 6472.255690595548, 915.9038300978434, 1007.4509392381024, 1046.926715129949, 1076.7469644633504, 1080.5515710979855, 31291.0, 31530.527042094385, 31557.526637100003, 31662.525062124085, 32347.191320216712, 859.879616853641, 1052.7894421115807, 1134.7219931116929, 1177.3701069927547, 1193.9223950443193, 15618.84381156174, 16176.67646647084, 16250.918745406434, 16372.754408683879, 16599.585010374594, 506580.0, 508976.0, 509687.160949365, 510300.0, 511742.0, 56872.146917795435, 56918.43081569131, 57131.0, 57174.42825571691, 57512.0, 1195.3664557784389, 1225.1117939493975, 1304.804279358104, 1345.8654134586463, 1348.736996285715, 29973.700262997092, 32806.0, 32945.835270823496, 32947.835260823544, 33466.83266583652, 52861.20708189303, 54731.0, 54739.72630136824, 59072.70463647654, 59129.45223286616, 467751.0, 470434.0, 472264.0, 472291.0, 473005.0, 26744.732552675003, 27664.861675692297, 27701.168964931083, 28694.704423645642, 28785.280367990967, 46992.53007470018, 47717.76141119411, 47863.0, 47954.0, 48323.0, 40343.0, 40602.79698601488, 42278.577214228695, 42502.57497424986, 42926.0, 39509.604903950596, 39708.0, 40095.799521002205, 40734.38898416467, 41469.43836178078, 39441.60558394494, 40070.79964600275, 40385.798071009456, 40527.59472405238, 40662.18675626412, 33030.834845825615, 36148.0, 36363.45454818127, 37043.259134817694, 37069.6293037077, 27463.588046178924, 27492.45015099647, 27681.72318276872, 27942.3014424641, 28269.57595636026], "value": 19889.884608884062, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.76651242741906, 385.59705108161984, 395.29834543684944, 406.33361287488526, 416.3795944043374, 13540.0, 15352.846471535282, 16998.150092495387, 19376.128074236658, 19562.11970461329, 18493.630127397442, 19198.23207071719, 19245.23019079235, 19830.206791728313, 19868.50328741782, 5579.0, 5629.802956896499, 5886.117082437626, 5892.351841297454, 5893.263342082239, 5621.381648018714, 5694.316681998166, 5718.628289161191, 5734.741936612862, 5746.770129194827, 5593.52455041322, 5670.0927851543765, 5670.121131224667, 5674.687892165929, 5677.205191273225, 5841.912371314434, 5939.70301484924, 5949.077892926625, 5950.851228719273, 5956.910646340309, 5639.915401268984, 5742.196092547047, 5770.653760774325, 5774.758426938229, 5782.45066718661, 13811.240381778998, 14052.227127507984, 14053.08654937436, 16767.736355591962, 22197.445063873372, 2247.2471721973156, 2251.977480225193, 2451.1552332263313, 2463.19946017545, 2753.2703833484143, 9629.759256018586, 10107.090361867467, 10375.481225938749, 10571.841422378671, 10715.410652414113, 1904.1431355889904, 1916.0802814649046, 1952.0922770911595, 1961.5390383259924, 1962.0, 61929.38070619281, 65749.3425065748, 65848.0, 79346.6032669833, 83302.0, 8683.522406267715, 9990.400575965465, 10116.747081323027, 10785.83821242682, 11201.43179954807, 15532.301046452936, 15556.766648500281, 15593.376264949275, 15691.215439227994, 15825.446109386115, 7651.464397492234, 7958.363330933512, 8063.314618257396, 8327.750167494985, 8337.666493340319, 7585.924140758632, 7676.270754278335, 7679.0, 7880.724174653974, 7889.9211007890335, 4645.513435700544, 4732.716037037789, 4792.616590672737, 4800.73595952226, 4843.636727245471, 1930.3436831477366, 1962.0876292524044, 1992.641324561593, 2017.7175195472646, 2022.7029941195756, 841.6212704283097, 923.7921467669855, 935.0836180543106, 960.0399600399601, 999.7150812018685, 18187.909060454876, 18489.260429582668, 19070.61858762817, 19117.33089341894, 19413.8058619412, 919.4621146629163, 938.6010945348199, 982.3418309732497, 992.7418871093568, 1003.4430890855646, 10056.497175141214, 10714.41070741101, 10757.83863242052, 10773.676789696321, 10776.19178561611, 464523.0, 469777.0, 472184.0, 485740.0, 487781.6607422289, 86276.0, 87171.0, 89289.10710892938, 91785.54107229422, 92422.53788731013, 878.9165029322204, 955.9856602150974, 1025.0467065629036, 1028.095276156992, 1051.9053285204368, 15334.463293784664, 16494.092824894746, 16804.243809028867, 17724.0, 17738.91130544339, 39486.0, 39957.80021099876, 40258.0, 41010.1797964045, 41509.58490415057, 54197.0, 54715.45284547104, 54867.725661371434, 56717.43282567122, 56860.0, 7993.280604745601, 8125.146859579895, 8158.224968627971, 8353.24820766134, 8361.790955226168, 11113.499892505019, 12359.196652217577, 12370.13409061357, 12476.937615312228, 12549.686257843261, 10579.523921423708, 10780.29928054674, 10843.0, 10858.0, 11113.722156946142, 5220.269162317202, 8103.149169337371, 10793.19051071157, 10881.0, 11108.222424430212, 10768.3538987661, 11077.889221108007, 11144.60993865211, 11573.247738896946, 11577.826332604849, 8356.164383561838, 8453.239208471266, 8584.227419532272, 8652.78368040804, 8717.91282087171, 9839.901600983898, 11241.887144473296, 11492.885071149181, 11563.88436115628, 11758.294502329887], "value": 8870.216132860616, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1472.7569950958093, 1499.902506337088, 1591.8487743664348, 1725.896446213228, 1775.3519965212693, 8502.234798868101, 9190.44857308562, 9192.35653504255, 9194.080591940803, 9194.402363846346, 7542.170361260257, 7817.257360550739, 8466.872996905051, 8603.91396086039, 8607.827843443127, 2402.1352313167245, 2432.8661923594195, 2433.221369161869, 2434.415740222352, 2435.659007738918, 2372.0511795281855, 2386.653935179398, 2429.8056155507606, 2430.161594249981, 2432.6351047342864, 2416.613341865302, 2422.5397174536783, 2437.3297343230574, 2437.500312435955, 2444.8655323957173, 2338.298510446859, 2341.1220792202926, 2344.6248600223967, 2348.847324923883, 2373.715154181492, 2344.906203751848, 2347.213683416058, 2355.0462062864567, 2356.7643235676464, 2368.9526209475885, 8902.554872256362, 8938.46369217849, 8979.820403591957, 8980.640774369018, 8980.955095224548, 5237.79048838046, 5366.409694933554, 5383.192521121824, 5390.110631745757, 5392.541633961078, 3484.4599087141282, 3485.9651403485896, 3544.946825797615, 3562.1806984393734, 3562.69717074049, 1116.402724542374, 1123.3428444360059, 1126.4030064065976, 1134.1267224237374, 1141.9657410277705, 12395.504179832886, 12448.25310481365, 12460.376981150906, 12498.937505312506, 12502.249865008129, 4502.864914052584, 4567.451905771329, 4658.231391820312, 4703.435587729495, 4705.929411058837, 10387.636432724817, 10394.532246048946, 10446.425446600359, 10460.163186945178, 10460.52927618259, 5336.839894803162, 5346.091164501966, 5351.304330437096, 5402.621816472809, 5405.459454054641, 4211.494620645542, 4224.0, 4398.824047038082, 4898.191798353232, 4957.033378491232, 3909.218156368681, 4038.4749982502676, 4137.731047481904, 4146.771927543954, 4176.895577610523, 1295.831541899566, 1300.024981264052, 1331.8668133186604, 1350.372076984207, 1357.321339330335, 855.0466230153335, 881.969131080409, 887.0, 896.5965315607872, 935.2144198872979, 5686.658800471985, 5850.385709500526, 5851.824445266649, 5904.940950590439, 6093.908591371133, 919.3013309884402, 952.8523078922778, 959.4818797849223, 976.4190306767462, 1011.5700827148432, 3649.3213121964195, 3926.234384295035, 4008.819603117867, 4081.163361510883, 4088.1823635273063, 11098.223124381217, 11289.774204515863, 11379.43102844871, 11402.600908968312, 11402.657920262405, 11276.887231127748, 11281.492332845042, 11308.773824523629, 11340.886591134147, 11354.205205635526, 8389.203025712548, 8496.490210587384, 8510.276626486819, 8516.914830851736, 8520.616572254263, 5996.730147143477, 6001.969990150022, 6120.510359171345, 6133.509319254538, 6304.243490781136, 9768.853467197856, 10194.286399951932, 10272.486375681336, 10320.0, 10324.22568307365, 10213.591456341663, 10409.115225205942, 10916.01755841978, 10948.069414099573, 10952.288101273392, 7226.494145409771, 7282.67227974753, 8351.081381047958, 8625.094365091574, 8835.955820220857, 8435.283000944988, 8903.376763626744, 8906.242969347679, 8926.73219803407, 8928.419652722552, 7500.36246919018, 7725.304722574995, 7730.574818385043, 7844.0, 7912.525248485109, 7624.771256862303, 7631.1605723371495, 7656.961715191611, 7729.9227007729205, 7755.0, 7121.394681452135, 7253.854922901618, 7259.818504537428, 7268.563886166847, 7268.963655181902, 4826.855194344175, 5058.64589478733, 5098.107831129611, 5125.308083408841, 5263.710495922761, 5031.245313203049, 5040.949590504048, 5143.099957507496, 5178.042062218502, 5231.607629427732], "value": 4706.058148385211, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [327.91095044791456, 342.39054483020226, 342.609425255209, 346.0, 346.9028671971848, 5232.895342093156, 5272.525472707455, 5305.257263983045, 5319.335083114611, 5324.680519168843, 4934.235193544997, 5064.417591976924, 5076.213186956019, 5090.974545127269, 5240.816571420001, 4014.1369605534805, 4064.796760161995, 4111.52717437494, 4141.399497072923, 4144.150449157915, 4140.875773726793, 4141.233871733739, 4144.709870309064, 4155.563665815092, 4166.708330416872, 3883.184531248441, 4077.9592204078026, 4090.284200264957, 4122.175564887019, 4140.192662430827, 4074.6943979201383, 4083.203775263825, 4086.4483294755423, 4087.6321131098343, 4093.7953102344914, 3598.46023096535, 3601.891943241707, 3603.6756691897854, 3611.205534782344, 3612.9522438492804, 4804.95195048052, 5037.219231019198, 5107.8507335849135, 5113.411957624883, 5119.2321151827155, 4558.316252562109, 4589.495155532889, 4605.134920357227, 4609.216433206363, 4631.16639004979, 5437.537809286176, 5449.7275136243425, 5458.563314934796, 5468.671879687232, 5477.424870388591, 3816.313063648542, 3918.921621567581, 3925.7840818754958, 3932.6067393260732, 3938.5864484229314, 5586.301712285965, 5603.2715746953045, 5603.327600687903, 5634.211210430543, 5638.4361563843695, 3918.7060970427333, 3923.6076392360537, 3926.4895563577124, 3926.784026878549, 3929.1945151243926, 3961.5246170459727, 3964.32606456903, 3970.3448930926074, 3976.443297938291, 3986.202759448064, 4406.603405693503, 4580.679352445363, 4632.143053535106, 4638.933045399577, 4654.883627909329, 5447.046766815792, 5452.727363631802, 5460.399356070829, 5461.781528738886, 5472.0, 1327.283267035809, 1396.7136736968896, 1417.3551034279378, 1420.034376623885, 1425.536700572318, 862.2584577263548, 891.4918496457087, 913.4154141349542, 926.6293482607011, 944.9858252126223, 4624.45080897903, 4627.472933931795, 4657.930131048037, 4659.297607387707, 4686.226772582554, 4867.488913664014, 4883.755812209375, 4900.436449808247, 4923.310736496894, 4928.186849169847, 4437.711548749321, 4622.491525932145, 4634.745089020136, 4662.090892276042, 4675.836345727883, 2605.3356394119755, 2605.387733882536, 2606.335384476984, 2612.8895219531623, 2613.9346516337246, 4971.527704868032, 5005.27423523587, 5028.547430731252, 5029.5221953914315, 5032.47159048302, 4647.582487341393, 4877.707337559757, 4949.975250123726, 4960.578350840219, 4994.550490455877, 4960.652754307236, 4975.189018204703, 4975.601951843843, 4995.9508503214, 4998.775055122455, 4848.781804818721, 4863.294822250683, 4864.270359446112, 4869.709526975284, 4908.239222920453, 4678.195510941851, 4709.905801884011, 4843.094143513864, 4910.508949105061, 4915.0, 4989.129076596277, 4999.525045120708, 5008.749562521786, 5060.291559181644, 5072.264521644414, 3674.375356189508, 3675.963240367562, 3683.8710645127294, 3687.539057617798, 3694.07648087978, 4753.502646666289, 4807.879803004953, 4882.072406242803, 4889.217725164099, 4942.851714448572, 4776.23580227162, 4782.210935195793, 4784.425868895755, 4812.422509298766, 4832.8791780205775, 4768.877849357003, 4778.163821331323, 4829.806807727651, 4830.082284365959, 4837.879053023703, 4770.976145119391, 4775.76121193932, 5040.445550989315, 5222.477752224899, 5429.647072940246, 5350.866228344323, 5399.163129714901, 5402.702851343214, 5455.454454554671, 5458.144230961492, 5413.133898576209, 5434.64674796137, 5438.4561543846885, 5439.401665816677, 5447.766415769674], "value": 3953.3052905162563, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [715.2542878004439, 737.8598066367391, 747.3908764357045, 751.113685850696, 762.6872982077349, 15877.206139693013, 17238.138093095327, 17250.913745431288, 17252.482425527254, 17270.0, 15094.16982065986, 15137.24313784312, 15221.315040823161, 15257.923710381432, 15288.770668439955, 655.416679155552, 770.4491288728558, 792.0455850699922, 795.5187111797366, 795.9283664470197, 663.5719960625394, 773.6093272897207, 791.9128895821484, 801.1787917384698, 805.4442434720024, 850.8255807559466, 854.6581367453007, 854.8418542569614, 854.8974123105236, 855.7732200966749, 695.401954319285, 842.3135144856952, 843.8059246373338, 844.5312851367488, 844.5566077809137, 512.344199424737, 590.748931704024, 602.105872778923, 606.75123199488, 608.9421504957022, 14999.475018374305, 15002.549923502313, 15023.77464338025, 15025.774613380809, 15040.699186016218, 12755.808662870064, 13339.866601334057, 13581.728365432637, 13601.115927464683, 13755.931220343835, 12048.939755301255, 12125.636230913087, 12155.817662735066, 12504.999600031975, 12849.807252891214, 4409.581089796464, 4459.3756874037335, 4472.530384309665, 4609.779672358677, 4686.133065382881, 69968.0, 71155.2884471159, 72963.27036729618, 80866.59566702129, 80893.59553202255, 11934.820977685342, 11935.940320298343, 11973.880261197452, 12070.27578345302, 12169.939150304192, 21843.453913651967, 21913.890430547744, 21927.561448770935, 21998.120075196814, 22074.22740204085, 8104.0680321762575, 8260.669573217125, 8270.518844346792, 8311.16888311176, 8583.313334933191, 4330.220560299176, 4710.552497512731, 4751.263554149113, 4840.128776820206, 4921.803127874845, 3473.704735097545, 3575.6960658344333, 3586.4398986440983, 3606.891793246207, 3611.7291203159343, 576.2278546747295, 579.8289504596154, 591.4263164730205, 591.5858898770791, 595.3183604772511, 726.3462883404873, 729.8686236477484, 757.7044952468542, 770.5838847022545, 786.3434032582858, 9734.41593504392, 10335.896641033643, 10365.067143956929, 10384.42885641297, 10461.320014199206, 623.5977794322637, 640.2924768131148, 674.9571911396904, 677.1298880937971, 745.1356426545182, 4411.20598292304, 4573.0, 4641.303804429295, 4708.115804461333, 4789.688670236424, 104042.0, 106364.0, 107761.0, 110325.44837275762, 110728.4463577677, 58277.0, 94768.0, 95055.52472237733, 98696.50651746696, 101409.0, 75507.24492755112, 82208.0, 83360.0, 84753.15246847576, 85687.0, 8798.38411311202, 9153.725388238363, 9162.862557061517, 9209.401388909699, 9258.30562707813, 47792.522074778804, 48370.61405701567, 48799.5120048795, 49650.255246172055, 49792.25311620256, 70155.29844701488, 73461.0, 73587.26412736018, 74484.62757686176, 74509.6274518624, 12297.44661490253, 12358.382080896099, 12458.252504849737, 12475.251484910934, 12568.622941311774, 13964.4414223434, 14075.648108797363, 14189.716205676035, 14353.712925741218, 15029.924850375679, 12422.37888105573, 13472.59582212496, 13549.86450135486, 13673.384697688429, 14134.575962721136, 12103.878961210276, 12618.621441356774, 12694.809577856524, 12727.80908286358, 12775.425105870445, 12402.75194496123, 12893.290869002294, 12943.223406595635, 13034.47862085506, 13462.361311507879, 10252.64115755945, 10664.200184986312, 10716.946415267874, 10867.510962006887, 11011.119110470983, 10162.39025658463, 10437.425941573287, 11291.830622540832, 11324.433778311217, 11379.146564007568], "value": 7441.201442919022, "unit": "score"}], "name": "dromaeo_css", "value": 7086.277621291144, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 112.0, 113.0, 113.0, 113.0, 111.0, 112.0, 111.0, 108.0, 110.0], "value": 111.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 117.0, 118.0, 165.0, 125.0, 119.0, 118.0, 123.0, 149.0, 119.0], "value": 129.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 162.0, 162.0, 161.0, 161.0, 163.0, 161.0, 164.0, 162.0], "value": 161.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 75.0, 110.0, 76.0, 76.0, 76.0, 74.0, 116.0, 74.0], "value": 82.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 112.0, 82.0, 81.0, 82.0, 113.0, 83.0, 83.0, 114.0], "value": 91.4, "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, 116.0, 116.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 147.0, 146.0, 146.0, 146.0, 147.0, 146.0, 145.0, 145.0, 145.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 96.0, 96.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 50.0, 50.0, 49.0, 51.0, 50.0, 51.0, 51.0, 51.0, 50.0], "value": 50.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 61.0, 61.0, 62.0, 62.0, 62.0, 62.0, 61.0, 63.0], "value": 61.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 77.0, 79.0, 78.0, 77.0, 84.0, 78.0, 78.0, 79.0, 78.0], "value": 79.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 123.0, 123.0, 139.0, 133.0, 133.0, 123.0, 122.0, 167.0, 125.0], "value": 131.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 166.0, 166.0, 167.0, 165.0, 168.0, 167.0, 166.0, 163.0, 168.0], "value": 166.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 65.0, 65.0, 63.0, 66.0, 65.0, 65.0, 63.0, 64.0], "value": 64.7, "unit": "score"}], "name": "kraken", "value": 1488.8, "alertThreshold": 2.0}]} 19:31:37 INFO - Return code: 0 19:31:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:31:37 INFO - # TBPL SUCCESS # 19:31:37 INFO - Running post-action listener: _resource_record_post_action 19:31:37 INFO - [mozharness: 2016-09-20 02:31:37.326473Z] Finished run-tests step (success) 19:31:37 INFO - Running post-run listener: _resource_record_post_run 19:31:37 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 4096; Write bytes: 401367040; Read time: 148; Write time: 211672 19:31:37 INFO - TinderboxPrint: CPU usage
13.2% 19:31:37 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 148 19:31:37 INFO - TinderboxPrint: I/O write bytes / time
401,367,040 / 211,672 19:31:37 INFO - TinderboxPrint: CPU idle
8,276.9 (86.8%) 19:31:37 INFO - TinderboxPrint: CPU user
1,204.1 (12.6%) 19:31:37 INFO - TinderboxPrint: Swap in / out
0 / 0 19:31:37 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 103247872; Read time: 0; Write time: 55332 19:31:37 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 4096; Write bytes: 297504768; Read time: 148; Write time: 156340 19:31:37 INFO - Running post-run listener: _upload_blobber_files 19:31:37 INFO - Blob upload gear active. 19:31:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:31:37 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 19:31:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:31:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:31:38 INFO - (blobuploader) - INFO - Open directory for files ... 19:31:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:31:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:31:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:31:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:31:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:31:39 INFO - (blobuploader) - INFO - Done attempting. 19:31:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:31:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:31:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:31:42 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:31:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:31:42 INFO - (blobuploader) - INFO - Done attempting. 19:31:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:31:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:31:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:31:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:31:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:31:43 INFO - (blobuploader) - INFO - Done attempting. 19:31:43 INFO - (blobuploader) - INFO - Iteration through files over. 19:31:43 INFO - Return code: 0 19:31:43 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:31:43 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:31:43 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a2dd0d6c4c42c3ffdfcf4b07c547ed5eaeccdeb758c8c9c73c073d10e819022507461ecfafdb90bd0128cd0baeb2a032979bd4802d5af14a8fcea34103f143d0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f71f9e1feda2c324de56bf3f498decc4de215a790eeff8146b73929c4df20dc82f22801abb3e92875377a0a9b3bd14b87f6752cb498de53da488e05760d6cf41", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/552753671d7c7d0f2165963a90c14c68857f7dee42a89ad0c4cfd6cf3447e3610be9c1cbe1cf2bab38fce81cf250942a8a5d5a3024c75b9e6797f070ea857920"} 19:31:43 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:31:43 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:31:43 INFO - Contents: 19:31:43 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a2dd0d6c4c42c3ffdfcf4b07c547ed5eaeccdeb758c8c9c73c073d10e819022507461ecfafdb90bd0128cd0baeb2a032979bd4802d5af14a8fcea34103f143d0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f71f9e1feda2c324de56bf3f498decc4de215a790eeff8146b73929c4df20dc82f22801abb3e92875377a0a9b3bd14b87f6752cb498de53da488e05760d6cf41", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/552753671d7c7d0f2165963a90c14c68857f7dee42a89ad0c4cfd6cf3447e3610be9c1cbe1cf2bab38fce81cf250942a8a5d5a3024c75b9e6797f070ea857920"} 19:31:43 INFO - Running post-run listener: copy_logs_to_upload_dir 19:31:43 INFO - Copying logs to upload dir... 19:31:43 INFO - mkdir: /builds/slave/test/build/upload/logs 19:31:43 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1237.505023 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-09-19 19:31:43.364146) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-19 19:31:43.368207) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1474337463.986437-1677715087 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a2dd0d6c4c42c3ffdfcf4b07c547ed5eaeccdeb758c8c9c73c073d10e819022507461ecfafdb90bd0128cd0baeb2a032979bd4802d5af14a8fcea34103f143d0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f71f9e1feda2c324de56bf3f498decc4de215a790eeff8146b73929c4df20dc82f22801abb3e92875377a0a9b3bd14b87f6752cb498de53da488e05760d6cf41", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/552753671d7c7d0f2165963a90c14c68857f7dee42a89ad0c4cfd6cf3447e3610be9c1cbe1cf2bab38fce81cf250942a8a5d5a3024c75b9e6797f070ea857920"} build_url:https://queue.taskcluster.net/v1/task/erbkBv0GSI23DVLqJEAB4g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011237 build_url: 'https://queue.taskcluster.net/v1/task/erbkBv0GSI23DVLqJEAB4g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a2dd0d6c4c42c3ffdfcf4b07c547ed5eaeccdeb758c8c9c73c073d10e819022507461ecfafdb90bd0128cd0baeb2a032979bd4802d5af14a8fcea34103f143d0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f71f9e1feda2c324de56bf3f498decc4de215a790eeff8146b73929c4df20dc82f22801abb3e92875377a0a9b3bd14b87f6752cb498de53da488e05760d6cf41", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/552753671d7c7d0f2165963a90c14c68857f7dee42a89ad0c4cfd6cf3447e3610be9c1cbe1cf2bab38fce81cf250942a8a5d5a3024c75b9e6797f070ea857920"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-19 19:31:43.393875) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 19:31:43.394220) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1474337463.986437-1677715087 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004424 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 19:31:43.478771) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-19 19:31:43.479133) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-19 19:31:43.479463) ========= ========= Total master_lag: 0.17 =========