builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-060 starttime: 1474060993.47 results: success (0) buildid: 20160916123013 builduid: b5f3b9c15c3e45078b9edd3a7d361a0a revision: 1e2642879de8f58efe366ccaedddafd4361c6777 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-16 14:23:13.466602) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-16 14:23:13.467395) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-16 14:23:13.467914) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-16 14:23:13.496343) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 14:23:13.496653) ========= 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=337fe3f4b1febe37b85fccc40000019e-1474059260.969406-1489388530 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-16 14:23:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 223M=0s 2016-09-16 14:23:14 (223 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.334659 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 14:23:13.845327) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 14:23:13.845733) ========= 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=337fe3f4b1febe37b85fccc40000019e-1474059260.969406-1489388530 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018290 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 14:23:13.904624) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-16 14:23:13.905507) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 1e2642879de8f58efe366ccaedddafd4361c6777 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 1e2642879de8f58efe366ccaedddafd4361c6777 --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=337fe3f4b1febe37b85fccc40000019e-1474059260.969406-1489388530 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-16 14:23:14,192 truncating revision to first 12 chars 2016-09-16 14:23:14,192 Setting DEBUG logging. 2016-09-16 14:23:14,192 attempt 1/10 2016-09-16 14:23:14,192 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/1e2642879de8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-16 14:23:15,426 unpacking tar archive at: fx-team-1e2642879de8/testing/mozharness/ program finished with exit code 0 elapsedTime=1.572002 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-16 14:23:15.493587) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-16 14:23:15.493962) ========= script_repo_revision: 1e2642879de8f58efe366ccaedddafd4361c6777 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-16 14:23:15.494390) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-16 14:23:15.494722) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-16 14:23:15.505997) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-09-16 14:23:15.506318) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=337fe3f4b1febe37b85fccc40000019e-1474059260.969406-1489388530 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:23:15 INFO - MultiFileLogger online at 20160916 14:23:15 in /builds/slave/test 14:23:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 14:23:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:23:15 INFO - {'append_to_log': False, 14:23:15 INFO - 'base_work_dir': '/builds/slave/test', 14:23:15 INFO - 'blob_upload_branch': 'Fx-Team', 14:23:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:23:15 INFO - 'branch': 'Fx-Team', 14:23:15 INFO - 'buildbot_json_path': 'buildprops.json', 14:23:15 INFO - 'config_files': ('talos/linux_config.py',), 14:23:15 INFO - 'default_actions': ('clobber', 14:23:15 INFO - 'read-buildbot-config', 14:23:15 INFO - 'download-and-extract', 14:23:15 INFO - 'populate-webroot', 14:23:15 INFO - 'create-virtualenv', 14:23:15 INFO - 'install', 14:23:15 INFO - 'run-tests'), 14:23:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:23:15 INFO - 'download_minidump_stackwalk': True, 14:23:15 INFO - 'download_symbols': 'ondemand', 14:23:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:23:15 INFO - 'tooltool.py': '/tools/tooltool.py', 14:23:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:23:15 INFO - '/tools/misc-python/virtualenv.py')}, 14:23:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:23:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:23:15 INFO - 'installer_path': 'installer.exe', 14:23:15 INFO - 'log_level': 'info', 14:23:15 INFO - 'log_name': 'talos', 14:23:15 INFO - 'log_to_console': True, 14:23:15 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:23:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:23:15 INFO - 'opt_config_files': (), 14:23:15 INFO - 'pip_index': False, 14:23:15 INFO - 'sps_profile': False, 14:23:15 INFO - 'sps_profile_interval': 0, 14:23:15 INFO - 'suite': 'dromaeojs', 14:23:15 INFO - 'system_bits': '32', 14:23:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:23:15 INFO - 'title': 'talos-linux64-ix-060', 14:23:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:23:15 INFO - 'use_talos_json': True, 14:23:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:23:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:23:15 INFO - 'work_dir': 'build'} 14:23:15 INFO - [mozharness: 2016-09-16 21:23:15.888180Z] Running clobber step. 14:23:15 INFO - Running pre-action listener: _resource_record_pre_action 14:23:15 INFO - Running main action method: clobber 14:23:15 INFO - rmtree: /builds/slave/test/build 14:23:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:23:16 INFO - Running post-action listener: _resource_record_post_action 14:23:16 INFO - [mozharness: 2016-09-16 21:23:16.858428Z] Finished clobber step (success) 14:23:16 INFO - [mozharness: 2016-09-16 21:23:16.858529Z] Running read-buildbot-config step. 14:23:16 INFO - Running pre-action listener: _resource_record_pre_action 14:23:16 INFO - Running main action method: read_buildbot_config 14:23:16 INFO - Using buildbot properties: 14:23:16 INFO - { 14:23:16 INFO - "project": "", 14:23:16 INFO - "product": "firefox", 14:23:16 INFO - "script_repo_revision": "production", 14:23:16 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 14:23:16 INFO - "repository": "", 14:23:16 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 14:23:16 INFO - "buildid": "20160916123013", 14:23:16 INFO - "pgo_build": "True", 14:23:16 INFO - "basedir": "/builds/slave/test", 14:23:16 INFO - "buildnumber": 453, 14:23:16 INFO - "slavename": "talos-linux64-ix-060", 14:23:16 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 14:23:16 INFO - "platform": "ubuntu64_hw", 14:23:16 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 14:23:16 INFO - "branch": "fx-team", 14:23:16 INFO - "repo_path": "integration/fx-team", 14:23:16 INFO - "revision": "1e2642879de8f58efe366ccaedddafd4361c6777", 14:23:16 INFO - "stage_platform": "linux64-pgo", 14:23:16 INFO - "builduid": "b5f3b9c15c3e45078b9edd3a7d361a0a", 14:23:16 INFO - "slavebuilddir": "test" 14:23:16 INFO - } 14:23:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/KQrHVPZGTHuHzy89jt5weA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 14:23:16 INFO - Running post-action listener: _resource_record_post_action 14:23:16 INFO - [mozharness: 2016-09-16 21:23:16.861002Z] Finished read-buildbot-config step (success) 14:23:16 INFO - [mozharness: 2016-09-16 21:23:16.861093Z] Running download-and-extract step. 14:23:16 INFO - Running pre-action listener: _resource_record_pre_action 14:23:16 INFO - Running main action method: download_and_extract 14:23:16 INFO - mkdir: /builds/slave/test/build/tests 14:23:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:23:16 INFO - trying https://queue.taskcluster.net/v1/task/KQrHVPZGTHuHzy89jt5weA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 14:23:16 INFO - Downloading https://queue.taskcluster.net/v1/task/KQrHVPZGTHuHzy89jt5weA/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 14:23:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KQrHVPZGTHuHzy89jt5weA/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 14:23:17 INFO - Downloaded 1361 bytes. 14:23:17 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 14:23:17 INFO - Using the following test package requirements: 14:23:17 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 14:23:17 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 14:23:17 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 14:23:17 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 14:23:17 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 14:23:17 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 14:23:17 INFO - u'jsshell-linux-x86_64.zip'], 14:23:17 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 14:23:17 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 14:23:17 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 14:23:17 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 14:23:17 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 14:23:17 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 14:23:17 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 14:23:17 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 14:23:17 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 14:23:17 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 14:23:17 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 14:23:17 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 14:23:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KQrHVPZGTHuHzy89jt5weA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 14:23:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KQrHVPZGTHuHzy89jt5weA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:23:17 INFO - Fetch https://queue.taskcluster.net/v1/task/KQrHVPZGTHuHzy89jt5weA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 14:23:18 INFO - Expected file size: 21935256 14:23:22 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 14:23:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KQrHVPZGTHuHzy89jt5weA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 14:23:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KQrHVPZGTHuHzy89jt5weA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:23:22 INFO - Fetch https://queue.taskcluster.net/v1/task/KQrHVPZGTHuHzy89jt5weA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 14:23:23 INFO - Expected file size: 21935256 14:23:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KQrHVPZGTHuHzy89jt5weA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 14:23:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KQrHVPZGTHuHzy89jt5weA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 14:23:27 INFO - Fetch https://queue.taskcluster.net/v1/task/KQrHVPZGTHuHzy89jt5weA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip into memory 14:23:28 INFO - Expected file size: 14414048 14:23: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')]} 14:23:28 INFO - trying https://queue.taskcluster.net/v1/task/KQrHVPZGTHuHzy89jt5weA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 14:23:28 INFO - Downloading https://queue.taskcluster.net/v1/task/KQrHVPZGTHuHzy89jt5weA/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 14:23:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KQrHVPZGTHuHzy89jt5weA/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 14:23:30 INFO - Downloaded 59543159 bytes. 14:23:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KQrHVPZGTHuHzy89jt5weA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 14:23:30 INFO - mkdir: /builds/slave/test/properties 14:23:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:23:30 INFO - Writing to file /builds/slave/test/properties/build_url 14:23:30 INFO - Contents: 14:23:30 INFO - build_url:https://queue.taskcluster.net/v1/task/KQrHVPZGTHuHzy89jt5weA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 14:23:30 INFO - Running post-action listener: _resource_record_post_action 14:23:30 INFO - Running post-action listener: set_extra_try_arguments 14:23:30 INFO - [mozharness: 2016-09-16 21:23:30.736892Z] Finished download-and-extract step (success) 14:23:30 INFO - [mozharness: 2016-09-16 21:23:30.737120Z] Running populate-webroot step. 14:23:30 INFO - Running pre-action listener: _resource_record_pre_action 14:23:30 INFO - Running main action method: populate_webroot 14:23:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:23:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:23:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:23:30 INFO - '--tppagecycles', 14:23:30 INFO - '7'], 14:23:30 INFO - 'tests': ['tsvgm']}}, 14:23:30 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:23:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:23:30 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:23:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:23:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:23:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:23:30 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:23:30 INFO - 'talos_options': ['--disable-e10s'], 14:23:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:23:30 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:23:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:23:30 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:23:30 INFO - 'talos_options': ['--disable-e10s'], 14:23:30 INFO - 'tests': ['damp', 'tps']}, 14:23:30 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:23:30 INFO - 'tests': ['damp', 'tps']}, 14:23:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:23:30 INFO - 'tests': ['dromaeo_dom']}, 14:23:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:23:30 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:23:30 INFO - 'tests': ['basic_compositor_video']}, 14:23:30 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:23:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:23:30 INFO - 'tests': ['a11yr', 14:23:30 INFO - 'ts_paint', 14:23:30 INFO - 'tpaint', 14:23:30 INFO - 'sessionrestore', 14:23:30 INFO - 'sessionrestore_no_auto_restore', 14:23:30 INFO - 'tabpaint']}, 14:23:30 INFO - 'other-e10s': {'tests': ['a11yr', 14:23:30 INFO - 'ts_paint', 14:23:30 INFO - 'tpaint', 14:23:30 INFO - 'sessionrestore', 14:23:30 INFO - 'sessionrestore_no_auto_restore', 14:23:30 INFO - 'tabpaint']}, 14:23:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:23:30 INFO - 'ts_paint', 14:23:30 INFO - 'tpaint', 14:23:30 INFO - 'sessionrestore', 14:23:30 INFO - 'sessionrestore_no_auto_restore', 14:23:30 INFO - 'tabpaint']}, 14:23:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:23:30 INFO - 'ts_paint', 14:23:30 INFO - 'tpaint', 14:23:30 INFO - 'sessionrestore', 14:23:30 INFO - 'sessionrestore_no_auto_restore', 14:23:30 INFO - 'tabpaint']}, 14:23:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:23:30 INFO - 'tests': ['a11yr', 14:23:30 INFO - 'ts_paint', 14:23:30 INFO - 'tpaint', 14:23:30 INFO - 'sessionrestore', 14:23:30 INFO - 'sessionrestore_no_auto_restore', 14:23:30 INFO - 'tabpaint']}, 14:23:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:23:30 INFO - 'tests': ['a11yr', 14:23:30 INFO - 'ts_paint', 14:23:30 INFO - 'tpaint', 14:23:30 INFO - 'sessionrestore', 14:23:30 INFO - 'sessionrestore_no_auto_restore', 14:23:30 INFO - 'tabpaint']}, 14:23:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:23:30 INFO - 'tests': ['tsvgx', 14:23:30 INFO - 'tsvgr_opacity', 14:23:30 INFO - 'tart', 14:23:30 INFO - 'tscrollx', 14:23:30 INFO - 'cart']}, 14:23:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:23:30 INFO - 'tsvgr_opacity', 14:23:30 INFO - 'tart', 14:23:30 INFO - 'tscrollx', 14:23:30 INFO - 'cart']}, 14:23:30 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:23:30 INFO - 'talos_options': ['--disable-e10s'], 14:23:30 INFO - 'tests': ['tp5o']}, 14:23:30 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:23:30 INFO - 'tests': ['tp5o']}, 14:23:30 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:23:30 INFO - 'talos_options': ['--disable-e10s', 14:23:30 INFO - '--xperf_path', 14:23:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:23:30 INFO - 'tests': ['tp5n']}, 14:23:30 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:23:30 INFO - 'talos_options': ['--xperf_path', 14:23:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:23:30 INFO - 'tests': ['tp5n']}}, 14:23:30 INFO - 'talos.zip': {'path': '', 14:23:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:23:30 INFO - Running post-action listener: _resource_record_post_action 14:23:30 INFO - [mozharness: 2016-09-16 21:23:30.754020Z] Finished populate-webroot step (success) 14:23:30 INFO - [mozharness: 2016-09-16 21:23:30.754109Z] Running create-virtualenv step. 14:23:30 INFO - Running pre-action listener: _resource_record_pre_action 14:23:30 INFO - Running main action method: create_virtualenv 14:23:30 INFO - Creating virtualenv /builds/slave/test/build/venv 14:23:30 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 14:23:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:23:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:23:30 INFO - Using real prefix '/usr' 14:23:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:23:31 INFO - Installing distribute.............................................................................................................................................................................................done. 14:23:33 INFO - Installing pip.................done. 14:23:33 INFO - Return code: 0 14:23:33 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:23:33 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')]} 14:23:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:23:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:33 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')]} 14:23:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:23:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:33 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 0x7f5ed974bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22e7790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e4930>, '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 0x22e5830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22e5cc0>, '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': '337fe3f4b1febe37b85fccc40000019e-1474059260.969406-1489388530', '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 14:23:33 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 14:23:33 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 14:23:33 INFO - Using env: {'DISPLAY': ':0', 14:23:33 INFO - 'HOME': '/home/cltbld', 14:23:33 INFO - 'LANG': 'en_US.UTF-8', 14:23:33 INFO - 'LANGUAGE': 'en_US:en', 14:23:33 INFO - 'LOGNAME': 'cltbld', 14:23:33 INFO - 'MAIL': '/var/mail/cltbld', 14:23:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:33 INFO - 'MOZ_NO_REMOTE': '1', 14:23:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:23:33 INFO - 'NO_EM_RESTART': '1', 14:23:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:23:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:23:33 INFO - 'PWD': '/builds/slave/test', 14:23:33 INFO - 'SHELL': '/bin/bash', 14:23:33 INFO - 'SHLVL': '1', 14:23:33 INFO - 'TERM': 'linux', 14:23:33 INFO - 'TMOUT': '86400', 14:23:33 INFO - 'USER': 'cltbld', 14:23:33 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1474059260.969406-1489388530', 14:23:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:23:33 INFO - '_': '/tools/buildbot/bin/python'} 14:23:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:23:33 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 14:23:33 INFO - Cleaning up... 14:23:33 INFO - Return code: 0 14:23:33 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:23:33 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')]} 14:23:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:23:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:33 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')]} 14:23:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:23:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:33 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 0x7f5ed974bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22e7790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e4930>, '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 0x22e5830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22e5cc0>, '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': '337fe3f4b1febe37b85fccc40000019e-1474059260.969406-1489388530', '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 14:23:33 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 14:23:33 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 14:23:33 INFO - Using env: {'DISPLAY': ':0', 14:23:33 INFO - 'HOME': '/home/cltbld', 14:23:33 INFO - 'LANG': 'en_US.UTF-8', 14:23:33 INFO - 'LANGUAGE': 'en_US:en', 14:23:33 INFO - 'LOGNAME': 'cltbld', 14:23:33 INFO - 'MAIL': '/var/mail/cltbld', 14:23:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:33 INFO - 'MOZ_NO_REMOTE': '1', 14:23:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:23:33 INFO - 'NO_EM_RESTART': '1', 14:23:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:23:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:23:33 INFO - 'PWD': '/builds/slave/test', 14:23:33 INFO - 'SHELL': '/bin/bash', 14:23:33 INFO - 'SHLVL': '1', 14:23:33 INFO - 'TERM': 'linux', 14:23:33 INFO - 'TMOUT': '86400', 14:23:33 INFO - 'USER': 'cltbld', 14:23:33 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1474059260.969406-1489388530', 14:23:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:23:33 INFO - '_': '/tools/buildbot/bin/python'} 14:23:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:23:33 INFO - Downloading/unpacking psutil>=3.1.1 14:23: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 14:23: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 14:23: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 14:23: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 14:23: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 14:23: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 14:23:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:23:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:23:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:23:35 INFO - Installing collected packages: psutil 14:23:35 INFO - Running setup.py install for psutil 14:23:35 INFO - building 'psutil._psutil_linux' extension 14:23:35 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 14:23:35 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 14:23:35 INFO - building 'psutil._psutil_posix' extension 14:23:35 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 14:23:36 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 14:23:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:23:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:23:36 INFO - Successfully installed psutil 14:23:36 INFO - Cleaning up... 14:23:36 INFO - Return code: 0 14:23:36 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:23:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:23:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:23:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:23:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:23:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f5ed974bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22e7790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e4930>, '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 0x22e5830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22e5cc0>, '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': '337fe3f4b1febe37b85fccc40000019e-1474059260.969406-1489388530', '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 14:23:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:23:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:23:36 INFO - Using env: {'DISPLAY': ':0', 14:23:36 INFO - 'HOME': '/home/cltbld', 14:23:36 INFO - 'LANG': 'en_US.UTF-8', 14:23:36 INFO - 'LANGUAGE': 'en_US:en', 14:23:36 INFO - 'LOGNAME': 'cltbld', 14:23:36 INFO - 'MAIL': '/var/mail/cltbld', 14:23:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:36 INFO - 'MOZ_NO_REMOTE': '1', 14:23:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:23:36 INFO - 'NO_EM_RESTART': '1', 14:23:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:23:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:23:36 INFO - 'PWD': '/builds/slave/test', 14:23:36 INFO - 'SHELL': '/bin/bash', 14:23:36 INFO - 'SHLVL': '1', 14:23:36 INFO - 'TERM': 'linux', 14:23:36 INFO - 'TMOUT': '86400', 14:23:36 INFO - 'USER': 'cltbld', 14:23:36 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1474059260.969406-1489388530', 14:23:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:23:36 INFO - '_': '/tools/buildbot/bin/python'} 14:23:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:23:36 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:23:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:23:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:23:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:23:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:23:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:23:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:23:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:23:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:23:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:23:38 INFO - Installing collected packages: mozsystemmonitor 14:23:38 INFO - Running setup.py install for mozsystemmonitor 14:23:38 INFO - Successfully installed mozsystemmonitor 14:23:38 INFO - Cleaning up... 14:23:38 INFO - Return code: 0 14:23:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:23:38 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')]} 14:23:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:23:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:38 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')]} 14:23:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:23:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:38 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 0x7f5ed974bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22e7790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e4930>, '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 0x22e5830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22e5cc0>, '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': '337fe3f4b1febe37b85fccc40000019e-1474059260.969406-1489388530', '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 14:23:38 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 14:23:38 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 14:23:38 INFO - Using env: {'DISPLAY': ':0', 14:23:38 INFO - 'HOME': '/home/cltbld', 14:23:38 INFO - 'LANG': 'en_US.UTF-8', 14:23:38 INFO - 'LANGUAGE': 'en_US:en', 14:23:38 INFO - 'LOGNAME': 'cltbld', 14:23:38 INFO - 'MAIL': '/var/mail/cltbld', 14:23:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:38 INFO - 'MOZ_NO_REMOTE': '1', 14:23:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:23:38 INFO - 'NO_EM_RESTART': '1', 14:23:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:23:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:23:38 INFO - 'PWD': '/builds/slave/test', 14:23:38 INFO - 'SHELL': '/bin/bash', 14:23:38 INFO - 'SHLVL': '1', 14:23:38 INFO - 'TERM': 'linux', 14:23:38 INFO - 'TMOUT': '86400', 14:23:38 INFO - 'USER': 'cltbld', 14:23:38 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1474059260.969406-1489388530', 14:23:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:23:38 INFO - '_': '/tools/buildbot/bin/python'} 14:23:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:23:38 INFO - Downloading/unpacking jsonschema==2.5.1 14:23:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:23:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:23:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:23:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:23:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:23:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:23:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:23:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:23:40 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 14:23:40 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 14:23:40 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 14:23:40 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 14:23:40 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 14:23:40 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 14:23:40 INFO - Downloading functools32-3.2.3-2.tar.gz 14:23:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:23:40 INFO - warning: no files found matching '*.txt' 14:23:40 INFO - no previously-included directories found matching 'build' 14:23:40 INFO - no previously-included directories found matching 'dist' 14:23:40 INFO - no previously-included directories found matching '.git*' 14:23:40 INFO - Installing collected packages: jsonschema, functools32 14:23:41 INFO - Running setup.py install for functools32 14:23:41 INFO - warning: no files found matching '*.txt' 14:23:41 INFO - no previously-included directories found matching 'build' 14:23:41 INFO - no previously-included directories found matching 'dist' 14:23:41 INFO - no previously-included directories found matching '.git*' 14:23:41 INFO - Successfully installed jsonschema functools32 14:23:41 INFO - Cleaning up... 14:23:41 INFO - Return code: 0 14:23:41 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:23: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')]} 14:23:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:23:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23: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')]} 14:23:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:23:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23: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', '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 0x7f5ed974bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22e7790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e4930>, '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 0x22e5830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22e5cc0>, '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': '337fe3f4b1febe37b85fccc40000019e-1474059260.969406-1489388530', '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 14:23: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 14:23: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 functools32==3.2.3-2 14:23:41 INFO - Using env: {'DISPLAY': ':0', 14:23:41 INFO - 'HOME': '/home/cltbld', 14:23:41 INFO - 'LANG': 'en_US.UTF-8', 14:23:41 INFO - 'LANGUAGE': 'en_US:en', 14:23:41 INFO - 'LOGNAME': 'cltbld', 14:23:41 INFO - 'MAIL': '/var/mail/cltbld', 14:23:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:41 INFO - 'MOZ_NO_REMOTE': '1', 14:23:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:23:41 INFO - 'NO_EM_RESTART': '1', 14:23:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:23:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:23:41 INFO - 'PWD': '/builds/slave/test', 14:23:41 INFO - 'SHELL': '/bin/bash', 14:23:41 INFO - 'SHLVL': '1', 14:23:41 INFO - 'TERM': 'linux', 14:23:41 INFO - 'TMOUT': '86400', 14:23:41 INFO - 'USER': 'cltbld', 14:23:41 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1474059260.969406-1489388530', 14:23:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:23:41 INFO - '_': '/tools/buildbot/bin/python'} 14:23:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:23:41 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:23:41 INFO - Cleaning up... 14:23:41 INFO - Return code: 0 14:23:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:23: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')]} 14:23:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:23:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23: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')]} 14:23:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:23:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23: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', '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 0x7f5ed974bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22e7790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e4930>, '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 0x22e5830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22e5cc0>, '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': '337fe3f4b1febe37b85fccc40000019e-1474059260.969406-1489388530', '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 14:23: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:23: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 blobuploader==1.2.4 14:23:41 INFO - Using env: {'DISPLAY': ':0', 14:23:41 INFO - 'HOME': '/home/cltbld', 14:23:41 INFO - 'LANG': 'en_US.UTF-8', 14:23:41 INFO - 'LANGUAGE': 'en_US:en', 14:23:41 INFO - 'LOGNAME': 'cltbld', 14:23:41 INFO - 'MAIL': '/var/mail/cltbld', 14:23:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:41 INFO - 'MOZ_NO_REMOTE': '1', 14:23:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:23:41 INFO - 'NO_EM_RESTART': '1', 14:23:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:23:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:23:41 INFO - 'PWD': '/builds/slave/test', 14:23:41 INFO - 'SHELL': '/bin/bash', 14:23:41 INFO - 'SHLVL': '1', 14:23:41 INFO - 'TERM': 'linux', 14:23:41 INFO - 'TMOUT': '86400', 14:23:41 INFO - 'USER': 'cltbld', 14:23:41 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1474059260.969406-1489388530', 14:23:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:23:41 INFO - '_': '/tools/buildbot/bin/python'} 14:23:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:23:41 INFO - Downloading/unpacking blobuploader==1.2.4 14:23:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:23:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:23:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:23:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:23:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:23:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:23:43 INFO - Downloading blobuploader-1.2.4.tar.gz 14:23:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:23:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:23:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:23:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:23:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:23:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:23:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:23:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:23:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:23:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:23:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:23:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:23:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:23:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:23:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:23:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:23:44 INFO - Downloading docopt-0.6.1.tar.gz 14:23:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:23:44 INFO - Installing collected packages: blobuploader, requests, docopt 14:23:44 INFO - Running setup.py install for blobuploader 14:23:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:23:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:23:44 INFO - Running setup.py install for requests 14:23:44 INFO - Running setup.py install for docopt 14:23:44 INFO - Successfully installed blobuploader requests docopt 14:23:44 INFO - Cleaning up... 14:23:44 INFO - Return code: 0 14:23:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:23:44 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')]} 14:23:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:23:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:44 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')]} 14:23:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:23:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:44 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 0x7f5ed974bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22e7790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e4930>, '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 0x22e5830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22e5cc0>, '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': '337fe3f4b1febe37b85fccc40000019e-1474059260.969406-1489388530', '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 14:23:44 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 14:23:44 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 14:23:44 INFO - Using env: {'DISPLAY': ':0', 14:23:44 INFO - 'HOME': '/home/cltbld', 14:23:44 INFO - 'LANG': 'en_US.UTF-8', 14:23:44 INFO - 'LANGUAGE': 'en_US:en', 14:23:44 INFO - 'LOGNAME': 'cltbld', 14:23:44 INFO - 'MAIL': '/var/mail/cltbld', 14:23:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:44 INFO - 'MOZ_NO_REMOTE': '1', 14:23:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:23:44 INFO - 'NO_EM_RESTART': '1', 14:23:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:23:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:23:44 INFO - 'PWD': '/builds/slave/test', 14:23:44 INFO - 'SHELL': '/bin/bash', 14:23:44 INFO - 'SHLVL': '1', 14:23:44 INFO - 'TERM': 'linux', 14:23:44 INFO - 'TMOUT': '86400', 14:23:44 INFO - 'USER': 'cltbld', 14:23:44 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1474059260.969406-1489388530', 14:23:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:23:44 INFO - '_': '/tools/buildbot/bin/python'} 14:23:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:23:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:23:45 INFO - Running setup.py (path:/tmp/pip-ndPdXO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:23:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:23:45 INFO - Running setup.py (path:/tmp/pip-O9YDnG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:23:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:23:45 INFO - Running setup.py (path:/tmp/pip-qhtTmN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:23:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:23:45 INFO - Running setup.py (path:/tmp/pip-Xbiph4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:23:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:23:45 INFO - Running setup.py (path:/tmp/pip-XsmCZI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:23:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:23:45 INFO - Running setup.py (path:/tmp/pip-Kp695s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:23:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:23:45 INFO - Running setup.py (path:/tmp/pip-WG7kE6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:23:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:23:45 INFO - Running setup.py (path:/tmp/pip-HYXjFl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:23:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:23:45 INFO - Running setup.py (path:/tmp/pip-_a0DeX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:23:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:23:45 INFO - Running setup.py (path:/tmp/pip-BtKkF5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:23:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:23:45 INFO - Running setup.py (path:/tmp/pip-wQWI4R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:23:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:23:45 INFO - Running setup.py (path:/tmp/pip-3PKHKM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:23:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:23:45 INFO - Running setup.py (path:/tmp/pip-8Iv1IR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:23:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:23:45 INFO - Running setup.py (path:/tmp/pip-44VvIB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:23:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:23:46 INFO - Running setup.py (path:/tmp/pip-GLP6BN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:23:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:23:46 INFO - Running setup.py (path:/tmp/pip-s3eR1z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:23:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:23:46 INFO - Running setup.py (path:/tmp/pip-SDu0bd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:23:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:23:46 INFO - Running setup.py install for manifestparser 14:23:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:23:46 INFO - Running setup.py install for mozcrash 14:23:46 INFO - Running setup.py install for mozdebug 14:23:46 INFO - Running setup.py install for mozdevice 14:23:46 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:23:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:23:46 INFO - Running setup.py install for mozfile 14:23:46 INFO - Running setup.py install for mozhttpd 14:23:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:23:46 INFO - Running setup.py install for mozinfo 14:23:46 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:23:46 INFO - Running setup.py install for mozInstall 14:23:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:23:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:23:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:23:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:23:47 INFO - Running setup.py install for mozleak 14:23:47 INFO - Running setup.py install for mozlog 14:23:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:23:47 INFO - Running setup.py install for moznetwork 14:23:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:23:47 INFO - Running setup.py install for mozprocess 14:23:47 INFO - Running setup.py install for mozprofile 14:23:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:23:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:23:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:23:47 INFO - Running setup.py install for mozrunner 14:23:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:23:47 INFO - Running setup.py install for mozscreenshot 14:23:47 INFO - Running setup.py install for moztest 14:23:47 INFO - Running setup.py install for mozversion 14:23:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:23:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:23:48 INFO - Cleaning up... 14:23:48 INFO - Return code: 0 14:23:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:23:48 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')]} 14:23:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:23:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:48 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')]} 14:23:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:23:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:48 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 0x7f5ed974bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22e7790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e4930>, '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 0x22e5830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22e5cc0>, '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': '337fe3f4b1febe37b85fccc40000019e-1474059260.969406-1489388530', '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 14:23:48 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 14:23:48 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 14:23:48 INFO - Using env: {'DISPLAY': ':0', 14:23:48 INFO - 'HOME': '/home/cltbld', 14:23:48 INFO - 'LANG': 'en_US.UTF-8', 14:23:48 INFO - 'LANGUAGE': 'en_US:en', 14:23:48 INFO - 'LOGNAME': 'cltbld', 14:23:48 INFO - 'MAIL': '/var/mail/cltbld', 14:23:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:48 INFO - 'MOZ_NO_REMOTE': '1', 14:23:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:23:48 INFO - 'NO_EM_RESTART': '1', 14:23:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:23:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:23:48 INFO - 'PWD': '/builds/slave/test', 14:23:48 INFO - 'SHELL': '/bin/bash', 14:23:48 INFO - 'SHLVL': '1', 14:23:48 INFO - 'TERM': 'linux', 14:23:48 INFO - 'TMOUT': '86400', 14:23:48 INFO - 'USER': 'cltbld', 14:23:48 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1474059260.969406-1489388530', 14:23:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:23:48 INFO - '_': '/tools/buildbot/bin/python'} 14:23:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:23:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:23:48 INFO - Running setup.py (path:/tmp/pip-Y4fRla-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:23:48 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)) 14:23:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:23:48 INFO - Running setup.py (path:/tmp/pip-2G0MHb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:23:48 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)) 14:23:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:23:48 INFO - Running setup.py (path:/tmp/pip-SfAKHy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:23:48 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)) 14:23:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:23:48 INFO - Running setup.py (path:/tmp/pip-gufHFY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:23:48 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)) 14:23:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:23:48 INFO - Running setup.py (path:/tmp/pip-Koil4D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:23:48 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)) 14:23:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:23:48 INFO - Running setup.py (path:/tmp/pip-L7WyMU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:23:48 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)) 14:23:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:23:48 INFO - Running setup.py (path:/tmp/pip-b1gm4s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:23:48 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)) 14:23:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:23:48 INFO - Running setup.py (path:/tmp/pip-1qeE47-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:23:48 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)) 14:23:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:23:48 INFO - Running setup.py (path:/tmp/pip-PRqPb_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:23:48 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)) 14:23:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:23:48 INFO - Running setup.py (path:/tmp/pip-pvIW0e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:23:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 14:23:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:23:48 INFO - Running setup.py (path:/tmp/pip-_9Qxmc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:23:49 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)) 14:23:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:23:49 INFO - Running setup.py (path:/tmp/pip-9h3D_f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:23:49 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)) 14:23:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:23:49 INFO - Running setup.py (path:/tmp/pip-UCnId6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:23:49 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)) 14:23:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:23:49 INFO - Running setup.py (path:/tmp/pip-iI9Muq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:23:49 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)) 14:23:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:23:49 INFO - Running setup.py (path:/tmp/pip-YXKzya-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:23:49 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)) 14:23:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:23:49 INFO - Running setup.py (path:/tmp/pip-OwEPg5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:23:49 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)) 14:23:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:23:49 INFO - Running setup.py (path:/tmp/pip-AsiQWG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:23:49 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)) 14:23:49 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)) 14:23:49 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)) 14:23:49 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)) 14:23:49 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)) 14:23:49 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)) 14:23:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:23:49 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 14:23:49 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 14:23:49 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 14:23:49 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 14:23:49 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 14:23:49 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 14:23:51 INFO - Downloading blessings-1.6.tar.gz 14:23:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:23:51 INFO - Installing collected packages: blessings 14:23:51 INFO - Running setup.py install for blessings 14:23:51 INFO - Successfully installed blessings 14:23:51 INFO - Cleaning up... 14:23:51 INFO - Return code: 0 14:23:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:23:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:23:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:23:51 INFO - Reading from file tmpfile_stdout 14:23:51 INFO - Current package versions: 14:23:51 INFO - argparse == 1.2.1 14:23:51 INFO - blessings == 1.6 14:23:51 INFO - blobuploader == 1.2.4 14:23:51 INFO - docopt == 0.6.1 14:23:51 INFO - functools32 == 3.2.3-2 14:23:51 INFO - jsonschema == 2.5.1 14:23:51 INFO - manifestparser == 1.1 14:23:51 INFO - mozInstall == 1.12 14:23:51 INFO - mozcrash == 0.17 14:23:51 INFO - mozdebug == 0.1 14:23:51 INFO - mozdevice == 0.48 14:23:51 INFO - mozfile == 1.2 14:23:51 INFO - mozhttpd == 0.7 14:23:51 INFO - mozinfo == 0.9 14:23:51 INFO - mozleak == 0.1 14:23:51 INFO - mozlog == 3.3 14:23:51 INFO - moznetwork == 0.27 14:23:51 INFO - mozprocess == 0.23 14:23:51 INFO - mozprofile == 0.28 14:23:51 INFO - mozrunner == 6.12 14:23:51 INFO - mozscreenshot == 0.1 14:23:51 INFO - mozsystemmonitor == 0.3 14:23:51 INFO - moztest == 0.7 14:23:51 INFO - mozversion == 1.4 14:23:51 INFO - psutil == 3.1.1 14:23:51 INFO - requests == 1.2.3 14:23:51 INFO - wsgiref == 0.1.2 14:23:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:23:51 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')]} 14:23:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:23:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:51 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')]} 14:23:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:23:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:51 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 0x7f5ed974bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22e7790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e4930>, '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 0x22e5830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22e5cc0>, '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': '337fe3f4b1febe37b85fccc40000019e-1474059260.969406-1489388530', '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 14:23:51 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 14:23:51 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 14:23:51 INFO - Using env: {'DISPLAY': ':0', 14:23:51 INFO - 'HOME': '/home/cltbld', 14:23:51 INFO - 'LANG': 'en_US.UTF-8', 14:23:51 INFO - 'LANGUAGE': 'en_US:en', 14:23:51 INFO - 'LOGNAME': 'cltbld', 14:23:51 INFO - 'MAIL': '/var/mail/cltbld', 14:23:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:51 INFO - 'MOZ_NO_REMOTE': '1', 14:23:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:23:51 INFO - 'NO_EM_RESTART': '1', 14:23:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:23:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:23:51 INFO - 'PWD': '/builds/slave/test', 14:23:51 INFO - 'SHELL': '/bin/bash', 14:23:51 INFO - 'SHLVL': '1', 14:23:51 INFO - 'TERM': 'linux', 14:23:51 INFO - 'TMOUT': '86400', 14:23:51 INFO - 'USER': 'cltbld', 14:23:51 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1474059260.969406-1489388530', 14:23:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:23:51 INFO - '_': '/tools/buildbot/bin/python'} 14:23:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:23:52 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)) 14:23:52 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)) 14:23:52 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)) 14:23:52 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)) 14:23:52 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)) 14:23:52 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)) 14:23:52 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)) 14:23:52 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)) 14:23:52 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)) 14:23:52 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)) 14:23:52 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)) 14:23:52 INFO - Cleaning up... 14:23:52 INFO - Return code: 0 14:23:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:23:52 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')]} 14:23:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:23:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:52 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')]} 14:23:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:23:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:52 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 0x7f5ed974bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22e7790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e4930>, '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 0x22e5830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22e5cc0>, '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': '337fe3f4b1febe37b85fccc40000019e-1474059260.969406-1489388530', '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 14:23:52 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 14:23:52 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 14:23:52 INFO - Using env: {'DISPLAY': ':0', 14:23:52 INFO - 'HOME': '/home/cltbld', 14:23:52 INFO - 'LANG': 'en_US.UTF-8', 14:23:52 INFO - 'LANGUAGE': 'en_US:en', 14:23:52 INFO - 'LOGNAME': 'cltbld', 14:23:52 INFO - 'MAIL': '/var/mail/cltbld', 14:23:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:52 INFO - 'MOZ_NO_REMOTE': '1', 14:23:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:23:52 INFO - 'NO_EM_RESTART': '1', 14:23:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:23:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:23:52 INFO - 'PWD': '/builds/slave/test', 14:23:52 INFO - 'SHELL': '/bin/bash', 14:23:52 INFO - 'SHLVL': '1', 14:23:52 INFO - 'TERM': 'linux', 14:23:52 INFO - 'TMOUT': '86400', 14:23:52 INFO - 'USER': 'cltbld', 14:23:52 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1474059260.969406-1489388530', 14:23:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:23:52 INFO - '_': '/tools/buildbot/bin/python'} 14:23:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:23:52 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:23:52 INFO - Cleaning up... 14:23:52 INFO - Return code: 0 14:23:52 INFO - Running post-action listener: _resource_record_post_action 14:23:52 INFO - Running post-action listener: _start_resource_monitoring 14:23:52 INFO - Starting resource monitoring. 14:23:52 INFO - [mozharness: 2016-09-16 21:23:52.334716Z] Finished create-virtualenv step (success) 14:23:52 INFO - [mozharness: 2016-09-16 21:23:52.335235Z] Running install step. 14:23:52 INFO - Running pre-action listener: _resource_record_pre_action 14:23:52 INFO - Running main action method: install 14:23:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:23:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:23:52 INFO - Reading from file tmpfile_stdout 14:23:52 INFO - Detecting whether we're running mozinstall >=1.0... 14:23:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:23:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:23:52 INFO - Reading from file tmpfile_stdout 14:23:52 INFO - Output received: 14:23:52 INFO - Usage: mozinstall [options] installer 14:23:52 INFO - Options: 14:23:52 INFO - -h, --help show this help message and exit 14:23:52 INFO - -d DEST, --destination=DEST 14:23:52 INFO - Directory to install application into. [default: 14:23:52 INFO - "/builds/slave/test"] 14:23:52 INFO - --app=APP Application being installed. [default: firefox] 14:23:52 INFO - mkdir: /builds/slave/test/build/application 14:23:52 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'] 14:23:52 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 14:24:09 INFO - Reading from file tmpfile_stdout 14:24:09 INFO - Output received: 14:24:09 INFO - /builds/slave/test/build/application/firefox/firefox 14:24:09 INFO - Running post-action listener: _resource_record_post_action 14:24:09 INFO - [mozharness: 2016-09-16 21:24:09.604532Z] Finished install step (success) 14:24:09 INFO - [mozharness: 2016-09-16 21:24:09.604776Z] Running run-tests step. 14:24:09 INFO - Running pre-action listener: _resource_record_pre_action 14:24:09 INFO - Running main action method: run_tests 14:24:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:24:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:24:09 INFO - Python 2.7.3 14:24:09 INFO - Return code: 0 14:24:09 INFO - grabbing minidump binary from tooltool 14:24:09 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')]} 14:24:09 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 0x22e4930>, '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 0x22e5830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22e5cc0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:24:09 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 14:24:09 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 14:24:09 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 14:24:09 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:24:09 INFO - Return code: 0 14:24:09 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:24:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:24:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:24:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:24:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:24:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KQrHVPZGTHuHzy89jt5weA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-060', '--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 14:24:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/KQrHVPZGTHuHzy89jt5weA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-060 --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 14:24:09 INFO - Using env: {'DISPLAY': ':0', 14:24:09 INFO - 'HOME': '/home/cltbld', 14:24:09 INFO - 'LANG': 'en_US.UTF-8', 14:24:09 INFO - 'LANGUAGE': 'en_US:en', 14:24:09 INFO - 'LOGNAME': 'cltbld', 14:24:09 INFO - 'MAIL': '/var/mail/cltbld', 14:24:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:24:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:24:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:24:09 INFO - 'MOZ_NO_REMOTE': '1', 14:24:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:24:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:24:09 INFO - 'NO_EM_RESTART': '1', 14:24:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:24:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:24:09 INFO - 'PWD': '/builds/slave/test', 14:24:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:24:09 INFO - 'SHELL': '/bin/bash', 14:24:09 INFO - 'SHLVL': '1', 14:24:09 INFO - 'TERM': 'linux', 14:24:09 INFO - 'TMOUT': '86400', 14:24:09 INFO - 'USER': 'cltbld', 14:24:09 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1474059260.969406-1489388530', 14:24:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:24:09 INFO - '_': '/tools/buildbot/bin/python'} 14:24:09 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KQrHVPZGTHuHzy89jt5weA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-060', '--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 14:24:09 INFO - mozversion application_buildid: 20160916123013 14:24:09 INFO - mozversion application_changeset: 1e2642879de8f58efe366ccaedddafd4361c6777 14:24:09 INFO - mozversion application_display_name: Nightly 14:24:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:24:09 INFO - mozversion application_name: Firefox 14:24:09 INFO - mozversion application_remotingname: firefox 14:24:09 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 14:24:09 INFO - mozversion application_vendor: Mozilla 14:24:09 INFO - mozversion application_version: 51.0a1 14:24:09 INFO - mozversion platform_buildid: 20160916123013 14:24:09 INFO - mozversion platform_changeset: 1e2642879de8f58efe366ccaedddafd4361c6777 14:24:09 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 14:24:09 INFO - mozversion platform_version: 51.0a1 14:24:09 INFO - using testdate: 1474061049 14:24:09 INFO - actual date: 1474061049 14:24:09 INFO - starting webserver on 'localhost:41306' 14:24:09 INFO - SUITE-START | Running 2 tests 14:24:09 INFO - TEST-START | dromaeo_css 14:24:09 INFO - operating with platform_type : linux_ 14:24:09 INFO - Initialising browser for dromaeo_css test... 14:24:09 INFO - TEST-INFO | started process 29042 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdtjO0U/profile http://localhost:41306/getInfo.html) 14:24:14 INFO - PROCESS | 29042 | __metrics Screen width/height:1600/1200 14:24:14 INFO - PROCESS | 29042 | colorDepth:24 14:24:14 INFO - PROCESS | 29042 | Browser inner width/height: 1024/697 14:24:14 INFO - PROCESS | 29042 | __metrics 14:24:14 INFO - PROCESS | 29042 | 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" 14:24:14 INFO - PROCESS | 29042 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:24:15 INFO - PROCESS | 29042 | console.error: PushService: 14:24:15 INFO - PROCESS | 29042 | stateChangeProcessEnqueue: Error transitioning state 14:24:15 INFO - PROCESS | 29042 | UnknownError 14:24:16 INFO - TEST-INFO | 29042: exit 0 14:24:16 INFO - Browser initialized. 14:24:16 INFO - Running cycle 1/1 for dromaeo_css test... 14:24:16 INFO - TEST-INFO | started process 29122 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdtjO0U/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:24:28 INFO - PROCESS | 29122 | RSS: Main: 172806144 14:24:28 INFO - PROCESS | 29122 | 14:24:47 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 14:25:02 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.05005 seconds. 14:25:07 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.03336 seconds. 14:25:19 INFO - PROCESS | 29122 | 1474061119440 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] 14:25:29 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 14:25:31 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.06671 seconds. 14:25:45 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 14:25:52 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 14:25:54 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.05002 seconds. 14:26:07 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.16671 seconds. 14:26:14 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 14:26:19 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 14:26:28 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 14:26:39 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 14:26:46 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 14:26:51 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.01666 seconds. 14:27:02 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.05003 seconds. 14:27:09 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 14:27:13 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.05003 seconds. 14:27:15 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.28341 seconds. 14:27:19 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.7834 seconds. 14:27:28 INFO - PROCESS | 29122 | Cycle 1(1): loaded http://localhost:41306/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41306/tests/dromaeo/cssquery-ext.html) 14:27:29 INFO - PROCESS | 29122 | RSS: Main: 269033472 14:27:29 INFO - PROCESS | 29122 | 14:27:33 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 14:27:35 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.06672 seconds. 14:27:41 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 14:27:43 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 14:28:35 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 14:28:55 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 14:29:03 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 14:29:05 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 14:29:07 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 14:29:09 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 14:29:29 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 14:29:31 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 14:29:57 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 14:30:06 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 14:30:24 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 14:30:32 INFO - PROCESS | 29122 | Cycle 1(1): loaded http://localhost:41306/tests/dromaeo/cssquery-ext.html (next: http://localhost:41306/tests/dromaeo/cssquery-jquery.html) 14:30:32 INFO - PROCESS | 29122 | RSS: Main: 279011328 14:30:32 INFO - PROCESS | 29122 | 14:30:38 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 14:30:43 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 14:30:47 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 14:30:58 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 14:30:59 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 14:31:05 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 14:31:11 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 14:31:21 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 14:31:35 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 14:31:52 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 14:32:05 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 14:32:08 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 14:32:12 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 14:32:14 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 14:32:26 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 14:32:28 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 14:32:30 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 14:32:47 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 2.00002 seconds. 14:32:47 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 2.00002 seconds. 14:32:48 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 14:32:50 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 14:33:00 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 14:33:08 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 14:33:20 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 14:33:34 INFO - PROCESS | 29122 | Cycle 1(1): loaded http://localhost:41306/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41306/tests/dromaeo/cssquery-mootools.html) 14:33:35 INFO - PROCESS | 29122 | RSS: Main: 262193152 14:33:35 INFO - PROCESS | 29122 | 14:33:38 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 14:33:56 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 14:33:58 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 14:34:10 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 14:34:12 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 14:34:14 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 14:34:22 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 14:34:36 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 14:34:42 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 14:34:44 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 14:34:52 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 14:34:58 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 14:35:02 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 14:35:08 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 14:35:34 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 14:35:53 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 14:35:55 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 14:36:07 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 14:36:09 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 14:36:15 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 14:36:19 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00008 seconds. 14:36:25 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 14:36:31 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 14:36:37 INFO - PROCESS | 29122 | Cycle 1(1): loaded http://localhost:41306/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41306/tests/dromaeo/cssquery-prototype.html) 14:36:38 INFO - PROCESS | 29122 | RSS: Main: 262184960 14:36:38 INFO - PROCESS | 29122 | 14:36:46 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 14:36:50 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 14:36:56 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 14:37:04 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 14:37:23 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 14:37:27 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 14:37:43 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 14:38:22 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 14:38:23 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 2.01672 seconds. 14:38:24 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 14:38:31 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 14:38:32 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 14:38:40 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 14:38:45 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 14:38:52 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 14:39:06 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 14:39:20 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 14:39:26 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 14:39:34 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 14:39:40 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 14:39:40 INFO - PROCESS | 29122 | Cycle 1(1): loaded http://localhost:41306/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41306/tests/dromaeo/cssquery-yui.html) 14:39:41 INFO - PROCESS | 29122 | RSS: Main: 219660288 14:39:41 INFO - PROCESS | 29122 | 14:39:44 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 14:39:55 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 14:40:10 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 14:40:32 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 14:40:48 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 14:40:59 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 14:41:00 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 14:41:02 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 14:41:07 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 14:41:11 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 14:41:16 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 14:41:33 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 14:41:35 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 14:42:03 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 14:42:11 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 14:42:17 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.01664 seconds. 14:42:19 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 14:42:23 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 14:42:25 INFO - PROCESS | 29122 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 14:42:44 INFO - PROCESS | 29122 | Cycle 1(1): loaded http://localhost:41306/tests/dromaeo/cssquery-yui.html (next: http://localhost:41306/tests/dromaeo/cssquery-dojo.html) 14:42:44 INFO - PROCESS | 29122 | RSS: Main: 308158464 14:42:44 INFO - PROCESS | 29122 | 14:42:44 INFO - PROCESS | 29122 | __start_tp_report 14:42:44 INFO - PROCESS | 29122 | _x_x_mozilla_page_load 14:42:44 INFO - PROCESS | 29122 | _x_x_mozilla_page_load_details 14:42:44 INFO - PROCESS | 29122 | |i|pagename|runs| 14:42:44 INFO - PROCESS | 29122 | |0;dojo.html;2949.6607890092637;2986.328076182859;2988.656304524979;3014.728674419302;3029.5304227844676;4962.131626965284;5027.2459131130345;5115.820946266881;5146.691198528091;5168.173092305232;5181.818636347729;5194.818181363653;5216.973915130428;5250.107481728106;5269.077911365505;4637.211674015425;4677.041206552665;4724.4094488188975;4726.432828060638;4742.170120228963;4794.592459640935;4818.542238487338;4823.34884790554;4840.5159484051665;4851.054044461313;4624.05206932578;4643.651726120521;4660.207764680013;4719.669623126382;4735.928961065587;4688.906221875543;4700;4756.143894099061;4771.403574553181;4788.449328327252;4667.6732628716;4702.670813043088;4712.787924543404;4728.905421891577;4763.618910487153;5019.64862459628;5043.36957880265;5216.347956505437;5217.295665085241;5269.209618557209;5482.150266708666;5505.256790333294;5511.724413779295;5512;5567.916481252784;5554.91667624986;5572.944270557283;5579.246801681802;5579.7489112990015;5583.553315734732;5012.749362531858;5197.5322220999915;5217.921731174036;5224.947750522522;5279.313689220377;5549.084401073777;5610.326760788732;5663.405342439067;5676.8864622707315;5751.712414379264;5426.9185962210595;5432.212329212242;5433.592480563894;5435.483629055233;5441.183822426668;5462.426445223274;5522.723863806794;5524.171374293888;5526.640768350045;5537.6954267515675;5295.338082739658;5340.91988620171;5467.425920278313;5511.862203444865;5525.530329922001;5546.334439867242;5551.417101204396;5556.055470569932;5611.663300202001;5612.017896868032;4219.767912764766;4242.703010789215;4243;4245.0448649053715;4289.120730250291;3710.4990826238654;3730.0488375464083;3764.5294338207723;3803.448499967489;3839.1745774658584;5342.252084708144;5342.358916929916;5345.625806193607;5374.919376209282;5387.892242155135;5580.748866301026;5601.187827765033;5609.242752228479;5620.269364982526;5620.297462817148;5087.64386492942;5130.9230361544605;5249.580033597303;5347.144456886957;5406.91889621656;4773.331733557305;4774.976125119352;4778.617710583145;4824.445188803263;4826.61387089032;5796.797112101056;5799.94200057994;5808.970955145197;5814.418558144152;5829.38791426911;5367.409584945731;5415.052365836042;5562.360328562268;5563.58273129525;5600.187972743848;5641.435856414326;5695.715214239189;5710.514606258348;5712.685802280915;5795.7391917362975;5361.624686271924;5401.972990135024;5409.269748584048;5411.323584551931;5421.674699518042;5360.115580929259;5490.149026900836;5497.39528651856;5501.312335958005;5564.38791732901;5259.237410575522;5292.576593872558;5347.812826551052;5350.143976963667;5394.136938089887;5376.758045888023;5377.112776391851;5378.112611419072;5382.058139825594;5392.35291764975;5138.640295179451;5284.656497327662;5295.36455625312;5296.073187192149;5357.732113394392;5208.713520756395;5412.864678383072;5467.425920278313;5472.945270547243;5522.972385138049;5293.841184764463;5307.734613269244;5318.601104917069;5326.786928522815;5336.066188416934 14:42:44 INFO - PROCESS | 29122 | |1;ext.html;550.5925615044868;582.4612233683843;584.6492104737156;588.2000479348088;588.8763359694464;32071.518927216082;33064.83467582662;34909.82545087274;42410.36384454236;43848.780756096254;44585.55414445855;44975.55024449754;44985;45006.7749661252;45011.77494112525;8742.694005709802;8899.021107678183;8899.955500222522;8903.421277616966;8906.821863562725;8752.212300892917;8843.734687959372;8848.115188481164;8852.291816654684;8855.380123391365;8844.513551754651;8905.243054340388;8910.955445222764;8912.24245939096;8915.821683566357;8820.8676869847;8822.823543529094;8841.115888411172;8862.20240178387;8877.224555088977;8690.087540808187;8797.604107815163;8805.207531322212;8813.206811387006;8818.514981676006;42273.57726422727;42459;42529.57470425318;42551.361729574084;42638.57361426408;3387.796732196076;3480.5475288212624;3521.8415171317097;3655.9086022849374;3885.1646895917515;31713.52429713533;31898.681013189802;31955.520667190012;32153;32364.838175809207;4234.597713217239;4329.502107257674;4338.566143385668;4469.9776501117285;4543.750093744843;331268;335127;335337;336092;337327;56883.71558142266;56993.43006569964;57018.42981570214;57093.42906570964;57202;59795;59871.700641496514;60006.69996649989;60009.699951500836;60150.699246503485;16866.831331686528;17045.829541704672;17117.004524208212;17132.486025419257;17135.400260991053;13279.269640169883;13362.198268103944;13419.932900335436;13425.194488330731;13434.462621495031;15254;16609.169541522875;16618.833811661727;16652.50042498727;16703.33186672544;6079.452849243589;6170.562627762009;6191.84520386994;6339.904901426482;6344;909.8999110097884;965.5558443116105;995.4326034160459;1050.3277902142636;1121.5962253588702;30709.846450767604;31080.378392432023;31572.368552628817;31649.36701265962;32241.193970150463;872.4154816273112;1042.9739256518496;1056.6422147540527;1084.029758461809;1105.0496572947259;15475.380984760708;16178.27197776103;16419.91790041066;16496.42262520806;16550.6689866202;481732;486742;487298;488129;489002;57832.421675783546;57837.373010159085;57865.421345786;57967.71016144892;57969;1201.501376928565;1217.2148963918223;1317.8155058291848;1329.6609364611961;1343.3484759891444;28836.855815721625;30447.391052179275;31079.689203108584;31153.79423777761;31668.683313167494;49636.751816240685;50307.496925031744;50308.49691503184;50317;50329.245061323374;396833;397147;397180;397697;398096;28404.715952841034;29236.468347406186;29307.267318317212;29715.405691885608;29783.255418614706;46163.76918115522;46690;47317;47508.52491475041;48467.51532484771;39600.2079958405;39966.2006759869;40098.59901401065;40144;41643.16713665649;39516.10907344912;39688.80155599319;39969.80015099906;41056.384154237116;41573.168536628495;39615.80192099021;40186.79906600448;40533;41058.1788364237;41127.794361028005;33397;38117;38154.236915262096;38867.6113238864;39008;27676.446471070867;27782.5832612515;27845.860770696017;28091.246050896625;28096.578551322156 14:42:44 INFO - PROCESS | 29122 | |2;jquery.html;377.3603741657891;381.1405281091141;396.8511808071973;400.2095860675166;421.1892107692691;13308.667283317922;15217.923910380447;16412.75380869288;19553.21787128513;19635.214591416327;17528.211230494628;17982.91008544959;17999.280028798832;18204.90897545514;19007.429777106674;5636.436356364351;5785.71071446426;5807.070868661015;5816.040353341694;5821.068629019358;5371.81198658047;5643;5666.405027472118;5667.546596272309;5670.950874088287;5623.7469313880765;5635.098384258499;5638.182463542784;5639.746211420475;5639.915401268984;5497.39528651848;5732;5763.769449222047;5813.476787089182;5866.735996880108;5462.508374246337;5619.185218143387;5677.233573467571;5687.431256874321;5692;13548.187108773505;13562.728745425036;13618.523351682643;13820.37808298629;13872.653183670389;2250.628646273363;2276.2260831317267;2339.847909885869;2355.705536807899;2379.773921477457;12264.632061038183;13827.654308642264;14026.228557429336;14088.577342679737;14759.92620036893;1828.6708392489347;1857.4427671698436;1896.715492676096;1915.7701075870846;1981.5838673878359;74118;78989.60505197494;80167.5991620044;81260.46009566398;81311;10664.146868250522;10947.4526273686;10951.397673127894;11014.953579409943;11058.391788451712;15497.767533487007;15498.612534686497;15523.767143492856;15531.689366212613;15532.534023979299;7840.764777056697;8010.239027292398;8173.141820108922;8173.5504547250475;8183.304419124322;7314.089536925571;7371.373433258126;7378.557286562824;7626.694932202649;7732.536047837148;4382.737035777864;4481.686281960297;4532.750698711537;4778.8767115150795;4848.757562121879;1942.1745758053053;1975.52587379028;2001.889896055731;2005.1077270614437;2006.2977957714684;837.9874301885477;927.596495524449;939.7791518993031;965.4834663455018;1005.1054561440324;17547.64904701899;18894.81105188958;19294;19311.420657380302;19421.90289048574;956.4187541675436;1008.7679833638305;1029.891861354547;1044.6970378590277;1055.4300677633992;9917.851232231522;10722.89277107219;10743.623973160902;10767.246292759588;10795.946020269848;454972;460443;469999;473064;475542;87683.1793564833;89303.55348223218;90018;90566;93906;976.8778902637171;984.7341217870992;997.8553109799038;998.0418797954045;998.5157198758596;14971.401879376093;16097.919510402373;16463.588410289838;16619;16630.833691662927;40894.38658420066;41335;41359.37960930649;41449.171016580105;41765.1646967065;55289.1706624409;56486.43513564812;56759.71620141873;58071.709641451525;58505;5668.801591944262;6569.981652843817;7477.738279160203;7957.8408431832195;7991;9433.57548910287;11802.527898884291;12122.636320910387;12302.507899683913;12355.814662779885;10763.569457221623;10856.620018299322;11011.504482298476;11287.097032237565;11492.94253528727;10770.353778773298;10810.35137891729;10880.510377032893;10897.12822974176;10966.287191332538;10879.238453308193;11067.889321106686;11513.654590362303;11701.356425396685;11703;7934.636034771942;7937.6031198438695;7945.404094693036;8292.212239837207;8339.583020849055;8466.66133354659;9940.701778946643;10698.518566664674;10882.129429645767;11107.222494425312 14:42:44 INFO - PROCESS | 29122 | |3;mootools.html;1351.8783309502144;1493.0668332292316;1506.6007508010375;1665.5253252822947;1807.1506391995758;7311.268873112693;8317.293030092442;8544.78638034048;8615.353848461367;8848.2478989286;8635.827283454342;8686.869696954534;8691.435056721324;8702.91297087029;8703.303735701145;2371.205646108556;2410.505846301513;2412.5657381671294;2414.577448946436;2417.347316224618;2282.2354511238673;2314.2941402872116;2318.0727708916484;2321.4776675248017;2408.1089996701157;2344.367020904355;2364.420716924356;2370.585147599163;2374.4182675244588;2375.406148462884;2320.3734991552187;2338.8128949683983;2340.918067867625;2356.175338631474;2364.9408764780846;2306.688597039395;2322.7212734471973;2324.140068174786;2332.603457412244;2336.0889253191267;8323.1260717625;8453.365997550209;8462.4076314658;8467.237948584596;8887.866681999774;4979.526944940225;5066.316047333637;5178.378594568639;5192.1952097424955;5195.7402129893735;3272.7381809455187;3326.900192994214;3355.1612096975755;3359.1434184283094;3368.7810292331164;1057.799018186542;1085.940273284969;1099.543689368911;2126.319577735125;2190.3319487556287;12434.316112613802;12544.749105017849;12585.811212831815;12588.622341329774;12592.118551701384;4378.759168245713;4505.166544189336;4646.395968524071;4651.09303685778;4659.138059458943;9773.169280611086;9777;9779.364341317934;9784.510774461249;9787.706368808947;5174.6377753557645;5186.507281808223;5187.221916712448;5194.766235519411;5205.167173252262;4128.112455822013;4147.730397524151;4156.314208155681;4291.635211007037;4824.614030877521;3428.9485657715154;3841.4429907663234;3847.2690188864026;3890.844366225376;3901.648851603313;1098.2751384086464;1128.8363187337955;1130.4856290387854;1136.9602063927723;1143.2854465958776;930.8231436027133;938.174406522256;945.8108378324225;961.2646325560952;973.4743238651191;5609.354924183689;5633.774649013993;5904.114382842608;5932.79235226765;6133.662648554372;463.5688809407216;465.3043699669017;469.55626932548415;470.97174169549936;477.398477917826;4085.0604360997136;4113.280175969194;4121.505419349697;4126.154138401681;4142;10667.786644267071;11094.500747466222;11100.44497775108;11257.211995160424;11259.099272058216;10967.83548246761;11111.499982500807;11121.332720036824;11149.554017839195;11159.944200278947;7718.652660630173;8269.255766981001;8333.083360830304;8350.33197344211;8352.373571982003;5687.658740475585;5781;5879.176915231957;5926.348101708895;5953.910691339547;9704.417734935927;9951.15415189688;9956.402615843073;10046.196304295785;10047.346922450017;8665.956670216608;10249.180065594586;10308.690739277834;10423.791524169323;10539.051485366048;6945.617991010543;7478.925210747823;7716.691332346868;7724.459287849797;7830.608469576385;8280.751577452686;8324.209200125979;8356.665733370597;8371.539565323965;8418.200270974248;7390.187079421152;7422.777316680508;7627.580483073484;7668.80827979305;7685.423593230642;7561.09266887977;7600.46796724224;7803.375729941705;7822.804429889071;7838.843223135391;6988.615626140611;7104.431645468453;7112.893306600508;7195.100612423447;7268.164161121327;5168.131686913799;5207.635465517529;5217.739113044257;5274.472552744694;5355.518003379714;5142.254373115952;5220.869478263074;5350.30446042012;5408.8377348679605;5450.264214331173 14:42:44 INFO - PROCESS | 29122 | |4;prototype.html;317.9697928696773;323.66824005394466;325.98533066012027;331.26128732925577;331.4315948148927;5141.742912854356;5161.096808058589;5192.117340052196;5198;5205.297284866551;4883.829065982692;4894.730789806568;5000.874978125539;5036.949630503694;5191.351081114861;3972.9006774830714;3992.940105898414;3997.3604223324273;3997.98001009996;4037.132016616427;4006.479157709494;4022.155347377054;4029.1941611677635;4049.5297868300313;4053.5338436079787;3774.981125094371;3834.3481608126685;3971.26531591655;3990.1221731219084;4014.4580481635044;3938.35017222158;3943.1270146680386;3965.4646622706;3966.226585815767;3982.6614737747327;3499.212677147647;3500.2124521982605;3501.581859346943;3504.3867323218333;3504.684578387957;4737.313089602023;4923.729194894279;4966.627502937295;4977.377827771528;4978.228374601942;4449.599536041772;4513.977430112828;4549.931751023737;4554.7039442436135;4555.544445555451;5252;5252.579793616502;5265;5297.311349524575;5319.787208511655;3594.245208506217;3851.595582463829;3871.187050719352;3876.4379165021196;3887.697621296869;5463.869091054492;5500.614956953015;5508.91736623947;5513.9448605513835;5515.365732940683;3906.160175562211;3914.941275880864;3920.1179734560064;3921.470601468822;3924.862629807943;3909.159530700913;3942.4677668514382;3943.861964831217;3959.6835851026894;3974.4435778990965;4431.135928493913;4466.665000125004;4508.407665592297;4515.68390212682;4534.274516077412;5317.1226747586215;5333.519983201453;5336.599755018389;5381.48875856793;5381.919271210935;1350.4463170100212;1357.8777909988148;1362.768329384007;1363.604554679132;1365.2900491744238;878.8637761147032;880.4497189256715;903.2006674093475;904.8552231643038;913.2739472119548;5167.58659307262;5182.844514664566;5192.221166825007;5203.349581302337;5224.869378265574;5381.623286369917;5405.864853378618;5421.647592906448;5444.50999410055;5460.426655201226;5009.974950125226;5072.746362681851;5097.413797413271;5115.646802470831;5223.164293712987;2843.5308174151446;2848.273690209025;2848.643919510061;2866.097179388486;2875.7699384049224;5427.3758517770175;5581.413951535112;5617.915731264035;5634.323881134208;5651.576131790132;5465.890682186334;5473.726313684379;5475.698836563947;5484.314460692413;5501.944980550224;5572.777088916398;5594.916076258941;5597.216389705444;5600;5648.4633959773755;5212.661177023481;5297.867553311198;5325.467453254643;5364.195370694427;5415.620906536662;5300.1254792958725;5300.681959082467;5301.787928482818;5305.9204111939125;5316.308879845596;5584.497395234448;5587.804426845041;5608.523275521627;5630.3525094613005;5638.746256418389;3737;3797.601251868514;3800.7529510581726;3818.7135964802196;3878.5151856017997;5304.9734751326;5362.436944120811;5382.434844341445;5390.51485366319;5398.190271459155;5281.947180528299;5292.814751483679;5299.682019078868;5307.150855863043;5312.681239125664;5041.218611115283;5209.879875826716;5219.615522960731;5226.555742761908;5288.9471105289995;5035.546800787946;5143.768530416215;5151.536361727312;5158.690478571298;5199.480051994921;5185.729496273287;5296.119877443452;5333.466653334635;5358.196270559447;5375.4087050425205;5179.611529135254;5205.6356055075785;5206.401263854554;5222.138347172674;5225.503577160163 14:42:44 INFO - PROCESS | 29122 | |5;yui.html;637.1653134393941;671.4762485261498;735.588070680419;776.8912352270684;780.9765707028789;14872.851271487296;15553.92223038886;15568.766468502954;15623.218839058045;15644.217789110557;12419.503219871216;12545.560905368315;12549.874501254984;12584.93707531461;12599.55901543446;652.9640869752162;667.933206679333;779.2129948751744;794.9682012719513;797.7726347990824;790.683726509398;800.371708209056;800.9879851802199;801.2508304735062;801.4990630855715;838.2162677896156;838.6771093129129;838.9496630202208;838.9973981092597;839.1734141870253;683.641088428574;695.2074634916149;825.0470706334194;831.8253166834971;845.2266176448542;623.3548277532777;623.3703959001396;623.5821999260506;623.7130919776909;624.1792043462832;14620.195889226088;14729.337179826933;14737.11577305365;14756.704865902622;14767.409303627843;14148.434062637481;14374.281285935662;14419.567412977627;14530.636734081627;14664.926675366662;12676.95852305737;12803.5518756844;12847.229166249961;12912.483500659962;13284.601461956061;4270.701050926436;4420.756858372788;4454.8886277842985;4557.020240648277;4585.4726706428855;81548.59225703892;82404;82664;83443;83588;11128.1653875958;11224.719382015352;11230.887691123147;11387.373694446886;11511.19421640477;21533;21680.241191558536;21825.563488730135;21829.126834926425;22086.006129724203;7701;7768.262015108556;7825.060992680915;7855.960720196363;7883.448158628841;4111.568285330057;4163.7293575918075;4186.958130418718;4201.074878566595;4204.3903633972905;3096.210466331071;3187;3216.46928256835;3216.967830321714;3218.5011323244935;538.4534697282262;547.206550501774;549.4152581312961;556.9053260945649;561.9100943848964;490.76443307212736;518.3934796288352;557.1920714963319;594.2482759309477;596.4780816785313;7872.330851877655;8267.809030606893;8735.34484913634;9424.95287523558;9863.98439864832;635.8855406026867;647.9092926990226;649.2760571962323;663.7776344924408;726.3317747672154;3529.735269854771;3922.7450215735885;4097.528784189797;4103.97948010258;4171.645410140111;111642.44178779054;112797;113294;114458;116364.41817791027;86105.5694721535;100278;100350.4982475083;100483.49758251308;103392.48303758433;55554.44445555493;55657.44342556604;66905.66547167233;86152;87507.56246218855;8763.298936085226;8830.249428798625;8874.29005679557;9254.629814807333;9279.907200927904;44828.77585612051;48225;50115.24827127669;51967;52000;48626.02747945092;71622;72396.27603723896;75400;75922.62038689991;11976.58081967127;12029.398530073637;12037.578684745991;12107.33409662477;12342.259464432163;12880.742385152433;13280.800787987995;13340.733185336045;13711.108777929403;14218.360173791998;11093.667189984313;13256.204627722367;13459.932700336827;13478.123921945042;13618;13852.515161969282;14304.708587423524;14534.418623254951;15172.089674619558;15206.923965380101;12468.127231093737;12472.886106791899;12522.624321270376;12602.87397126017;13206;9168.816623667355;9350.345475816628;9788.168005719594;10348.844767328346;10549.525271362654;9972.351797133453;10426.113780328757;10449.477526123512;10578.735531611772;10600.575976961143 14:42:44 INFO - PROCESS | 29122 | __end_tp_report 14:42:44 INFO - PROCESS | 29122 | __start_cc_report 14:42:44 INFO - PROCESS | 29122 | _x_x_mozilla_cycle_collect,1031 14:42:44 INFO - PROCESS | 29122 | __end_cc_report 14:42:44 INFO - PROCESS | 29122 | __startTimestamp1474062164835__endTimestamp 14:42:44 INFO - PROCESS | 29122 | 14:42:44 INFO - PROCESS | 29122 | ------- Summary: start ------- 14:42:44 INFO - PROCESS | 29122 | Number of tests: 6 14:42:44 INFO - PROCESS | 29122 | 14:42:44 INFO - PROCESS | 29122 | [#0] dojo.html Cycles:165 Average:5140.60 Median:5342.25 stddev:580.08 (10.9%) stddev-sans-first:555.81 14:42:44 INFO - PROCESS | 29122 | Values: 2949.7 2986.3 2988.7 3014.7 3029.5 4962.1 5027.2 5115.8 5146.7 5168.2 5181.8 5194.8 5217.0 5250.1 5269.1 4637.2 4677.0 4724.4 4726.4 4742.2 4794.6 4818.5 4823.3 4840.5 4851.1 4624.1 4643.7 4660.2 4719.7 4735.9 4688.9 4700.0 4756.1 4771.4 4788.4 4667.7 4702.7 4712.8 4728.9 4763.6 5019.6 5043.4 5216.3 5217.3 5269.2 5482.2 5505.3 5511.7 5512.0 5567.9 5554.9 5572.9 5579.2 5579.7 5583.6 5012.7 5197.5 5217.9 5224.9 5279.3 5549.1 5610.3 5663.4 5676.9 5751.7 5426.9 5432.2 5433.6 5435.5 5441.2 5462.4 5522.7 5524.2 5526.6 5537.7 5295.3 5340.9 5467.4 5511.9 5525.5 5546.3 5551.4 5556.1 5611.7 5612.0 4219.8 4242.7 4243.0 4245.0 4289.1 3710.5 3730.0 3764.5 3803.4 3839.2 5342.3 5342.4 5345.6 5374.9 5387.9 5580.7 5601.2 5609.2 5620.3 5620.3 5087.6 5130.9 5249.6 5347.1 5406.9 4773.3 4775.0 4778.6 4824.4 4826.6 5796.8 5799.9 5809.0 5814.4 5829.4 5367.4 5415.1 5562.4 5563.6 5600.2 5641.4 5695.7 5710.5 5712.7 5795.7 5361.6 5402.0 5409.3 5411.3 5421.7 5360.1 5490.1 5497.4 5501.3 5564.4 5259.2 5292.6 5347.8 5350.1 5394.1 5376.8 5377.1 5378.1 5382.1 5392.4 5138.6 5284.7 5295.4 5296.1 5357.7 5208.7 5412.9 5467.4 5472.9 5523.0 5293.8 5307.7 5318.6 5326.8 5336.1 14:42:44 INFO - PROCESS | 29122 | 14:42:44 INFO - PROCESS | 29122 | [#1] ext.html Cycles:180 Average:56980.32 Median:41092.99 stddev:108648.69 (264.4%) stddev-sans-first:108870.87 14:42:44 INFO - PROCESS | 29122 | Values: 550.6 582.5 584.6 588.2 588.9 32071.5 33064.8 34909.8 42410.4 43848.8 44585.6 44975.6 44985.0 45006.8 45011.8 8742.7 8899.0 8900.0 8903.4 8906.8 8752.2 8843.7 8848.1 8852.3 8855.4 8844.5 8905.2 8911.0 8912.2 8915.8 8820.9 8822.8 8841.1 8862.2 8877.2 8690.1 8797.6 8805.2 8813.2 8818.5 42273.6 42459.0 42529.6 42551.4 42638.6 3387.8 3480.5 3521.8 3655.9 3885.2 31713.5 31898.7 31955.5 32153.0 32364.8 4234.6 4329.5 4338.6 4470.0 4543.8 331268.0 335127.0 335337.0 336092.0 337327.0 56883.7 56993.4 57018.4 57093.4 57202.0 59795.0 59871.7 60006.7 60009.7 60150.7 16866.8 17045.8 17117.0 17132.5 17135.4 13279.3 13362.2 13419.9 13425.2 13434.5 15254.0 16609.2 16618.8 16652.5 16703.3 6079.5 6170.6 6191.8 6339.9 6344.0 909.9 965.6 995.4 1050.3 1121.6 30709.8 31080.4 31572.4 31649.4 32241.2 872.4 1043.0 1056.6 1084.0 1105.0 15475.4 16178.3 16419.9 16496.4 16550.7 481732.0 486742.0 487298.0 488129.0 489002.0 57832.4 57837.4 57865.4 57967.7 57969.0 1201.5 1217.2 1317.8 1329.7 1343.3 28836.9 30447.4 31079.7 31153.8 31668.7 49636.8 50307.5 50308.5 50317.0 50329.2 396833.0 397147.0 397180.0 397697.0 398096.0 28404.7 29236.5 29307.3 29715.4 29783.3 46163.8 46690.0 47317.0 47508.5 48467.5 39600.2 39966.2 40098.6 40144.0 41643.2 39516.1 39688.8 39969.8 41056.4 41573.2 39615.8 40186.8 40533.0 41058.2 41127.8 33397.0 38117.0 38154.2 38867.6 39008.0 27676.4 27782.6 27845.9 28091.2 28096.6 14:42:44 INFO - PROCESS | 29122 | 14:42:44 INFO - PROCESS | 29122 | [#2] jquery.html Cycles:180 Average:27712.05 Median:41114.69 stddev:77107.87 (187.5%) stddev-sans-first:77296.86 14:42:44 INFO - PROCESS | 29122 | Values: 377.4 381.1 396.9 400.2 421.2 13308.7 15217.9 16412.8 19553.2 19635.2 17528.2 17982.9 17999.3 18204.9 19007.4 5636.4 5785.7 5807.1 5816.0 5821.1 5371.8 5643.0 5666.4 5667.5 5671.0 5623.7 5635.1 5638.2 5639.7 5639.9 5497.4 5732.0 5763.8 5813.5 5866.7 5462.5 5619.2 5677.2 5687.4 5692.0 13548.2 13562.7 13618.5 13820.4 13872.7 2250.6 2276.2 2339.8 2355.7 2379.8 12264.6 13827.7 14026.2 14088.6 14759.9 1828.7 1857.4 1896.7 1915.8 1981.6 74118.0 78989.6 80167.6 81260.5 81311.0 10664.1 10947.5 10951.4 11015.0 11058.4 15497.8 15498.6 15523.8 15531.7 15532.5 7840.8 8010.2 8173.1 8173.6 8183.3 7314.1 7371.4 7378.6 7626.7 7732.5 4382.7 4481.7 4532.8 4778.9 4848.8 1942.2 1975.5 2001.9 2005.1 2006.3 838.0 927.6 939.8 965.5 1005.1 17547.6 18894.8 19294.0 19311.4 19421.9 956.4 1008.8 1029.9 1044.7 1055.4 9917.9 10722.9 10743.6 10767.2 10795.9 454972.0 460443.0 469999.0 473064.0 475542.0 87683.2 89303.6 90018.0 90566.0 93906.0 976.9 984.7 997.9 998.0 998.5 14971.4 16097.9 16463.6 16619.0 16630.8 40894.4 41335.0 41359.4 41449.2 41765.2 55289.2 56486.4 56759.7 58071.7 58505.0 5668.8 6570.0 7477.7 7957.8 7991.0 9433.6 11802.5 12122.6 12302.5 12355.8 10763.6 10856.6 11011.5 11287.1 11492.9 10770.4 10810.4 10880.5 10897.1 10966.3 10879.2 11067.9 11513.7 11701.4 11703.0 7934.6 7937.6 7945.4 8292.2 8339.6 8466.7 9940.7 10698.5 10882.1 11107.2 14:42:44 INFO - PROCESS | 29122 | 14:42:44 INFO - PROCESS | 29122 | [#3] mootools.html Cycles:180 Average:5738.73 Median:4902.07 stddev:3289.75 (67.1%) stddev-sans-first:3282.45 14:42:44 INFO - PROCESS | 29122 | Values: 1351.9 1493.1 1506.6 1665.5 1807.2 7311.3 8317.3 8544.8 8615.4 8848.2 8635.8 8686.9 8691.4 8702.9 8703.3 2371.2 2410.5 2412.6 2414.6 2417.3 2282.2 2314.3 2318.1 2321.5 2408.1 2344.4 2364.4 2370.6 2374.4 2375.4 2320.4 2338.8 2340.9 2356.2 2364.9 2306.7 2322.7 2324.1 2332.6 2336.1 8323.1 8453.4 8462.4 8467.2 8887.9 4979.5 5066.3 5178.4 5192.2 5195.7 3272.7 3326.9 3355.2 3359.1 3368.8 1057.8 1085.9 1099.5 2126.3 2190.3 12434.3 12544.7 12585.8 12588.6 12592.1 4378.8 4505.2 4646.4 4651.1 4659.1 9773.2 9777.0 9779.4 9784.5 9787.7 5174.6 5186.5 5187.2 5194.8 5205.2 4128.1 4147.7 4156.3 4291.6 4824.6 3428.9 3841.4 3847.3 3890.8 3901.6 1098.3 1128.8 1130.5 1137.0 1143.3 930.8 938.2 945.8 961.3 973.5 5609.4 5633.8 5904.1 5932.8 6133.7 463.6 465.3 469.6 471.0 477.4 4085.1 4113.3 4121.5 4126.2 4142.0 10667.8 11094.5 11100.4 11257.2 11259.1 10967.8 11111.5 11121.3 11149.6 11159.9 7718.7 8269.3 8333.1 8350.3 8352.4 5687.7 5781.0 5879.2 5926.3 5953.9 9704.4 9951.2 9956.4 10046.2 10047.3 8666.0 10249.2 10308.7 10423.8 10539.1 6945.6 7478.9 7716.7 7724.5 7830.6 8280.8 8324.2 8356.7 8371.5 8418.2 7390.2 7422.8 7627.6 7668.8 7685.4 7561.1 7600.5 7803.4 7822.8 7838.8 6988.6 7104.4 7112.9 7195.1 7268.2 5168.1 5207.6 5217.7 5274.5 5355.5 5142.3 5220.9 5350.3 5408.8 5450.3 14:42:44 INFO - PROCESS | 29122 | 14:42:44 INFO - PROCESS | 29122 | [#4] prototype.html Cycles:180 Average:4464.46 Median:5173.60 stddev:1304.96 (25.2%) stddev-sans-first:1270.97 14:42:44 INFO - PROCESS | 29122 | Values: 318.0 323.7 326.0 331.3 331.4 5141.7 5161.1 5192.1 5198.0 5205.3 4883.8 4894.7 5000.9 5036.9 5191.4 3972.9 3992.9 3997.4 3998.0 4037.1 4006.5 4022.2 4029.2 4049.5 4053.5 3775.0 3834.3 3971.3 3990.1 4014.5 3938.4 3943.1 3965.5 3966.2 3982.7 3499.2 3500.2 3501.6 3504.4 3504.7 4737.3 4923.7 4966.6 4977.4 4978.2 4449.6 4514.0 4549.9 4554.7 4555.5 5252.0 5252.6 5265.0 5297.3 5319.8 3594.2 3851.6 3871.2 3876.4 3887.7 5463.9 5500.6 5508.9 5513.9 5515.4 3906.2 3914.9 3920.1 3921.5 3924.9 3909.2 3942.5 3943.9 3959.7 3974.4 4431.1 4466.7 4508.4 4515.7 4534.3 5317.1 5333.5 5336.6 5381.5 5381.9 1350.4 1357.9 1362.8 1363.6 1365.3 878.9 880.4 903.2 904.9 913.3 5167.6 5182.8 5192.2 5203.3 5224.9 5381.6 5405.9 5421.6 5444.5 5460.4 5010.0 5072.7 5097.4 5115.6 5223.2 2843.5 2848.3 2848.6 2866.1 2875.8 5427.4 5581.4 5617.9 5634.3 5651.6 5465.9 5473.7 5475.7 5484.3 5501.9 5572.8 5594.9 5597.2 5600.0 5648.5 5212.7 5297.9 5325.5 5364.2 5415.6 5300.1 5300.7 5301.8 5305.9 5316.3 5584.5 5587.8 5608.5 5630.4 5638.7 3737.0 3797.6 3800.8 3818.7 3878.5 5305.0 5362.4 5382.4 5390.5 5398.2 5281.9 5292.8 5299.7 5307.2 5312.7 5041.2 5209.9 5219.6 5226.6 5288.9 5035.5 5143.8 5151.5 5158.7 5199.5 5185.7 5296.1 5333.5 5358.2 5375.4 5179.6 5205.6 5206.4 5222.1 5225.5 14:42:44 INFO - PROCESS | 29122 | 14:42:44 INFO - PROCESS | 29122 | [#5] yui.html Cycles:180 Average:20001.15 Median:4345.73 stddev:29155.89 (670.9%) stddev-sans-first:29201.42 14:42:44 INFO - PROCESS | 29122 | Values: 637.2 671.5 735.6 776.9 781.0 14872.9 15553.9 15568.8 15623.2 15644.2 12419.5 12545.6 12549.9 12584.9 12599.6 653.0 667.9 779.2 795.0 797.8 790.7 800.4 801.0 801.3 801.5 838.2 838.7 838.9 839.0 839.2 683.6 695.2 825.0 831.8 845.2 623.4 623.4 623.6 623.7 624.2 14620.2 14729.3 14737.1 14756.7 14767.4 14148.4 14374.3 14419.6 14530.6 14664.9 12677.0 12803.6 12847.2 12912.5 13284.6 4270.7 4420.8 4454.9 4557.0 4585.5 81548.6 82404.0 82664.0 83443.0 83588.0 11128.2 11224.7 11230.9 11387.4 11511.2 21533.0 21680.2 21825.6 21829.1 22086.0 7701.0 7768.3 7825.1 7856.0 7883.4 4111.6 4163.7 4187.0 4201.1 4204.4 3096.2 3187.0 3216.5 3217.0 3218.5 538.5 547.2 549.4 556.9 561.9 490.8 518.4 557.2 594.2 596.5 7872.3 8267.8 8735.3 9425.0 9864.0 635.9 647.9 649.3 663.8 726.3 3529.7 3922.7 4097.5 4104.0 4171.6 111642.4 112797.0 113294.0 114458.0 116364.4 86105.6 100278.0 100350.5 100483.5 103392.5 55554.4 55657.4 66905.7 86152.0 87507.6 8763.3 8830.2 8874.3 9254.6 9279.9 44828.8 48225.0 50115.2 51967.0 52000.0 48626.0 71622.0 72396.3 75400.0 75922.6 11976.6 12029.4 12037.6 12107.3 12342.3 12880.7 13280.8 13340.7 13711.1 14218.4 11093.7 13256.2 13459.9 13478.1 13618.0 13852.5 14304.7 14534.4 15172.1 15206.9 12468.1 12472.9 12522.6 12602.9 13206.0 9168.8 9350.3 9788.2 10348.8 10549.5 9972.4 10426.1 10449.5 10578.7 10600.6 14:42:44 INFO - PROCESS | 29122 | -------- Summary: end -------- 14:42:44 INFO - PROCESS | 29122 | 14:42:45 INFO - TEST-INFO | 29122: exit 0 14:42:45 INFO - TEST-OK | dromaeo_css | took 1115861ms 14:42:45 INFO - TEST-START | kraken 14:42:45 INFO - operating with platform_type : linux_ 14:42:45 INFO - Initialising browser for kraken test... 14:42:45 INFO - TEST-INFO | started process 29430 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmps8GB1j/profile http://localhost:41306/getInfo.html) 14:42:50 INFO - PROCESS | 29430 | __metrics Screen width/height:1600/1200 14:42:50 INFO - PROCESS | 29430 | colorDepth:24 14:42:50 INFO - PROCESS | 29430 | Browser inner width/height: 1024/697 14:42:50 INFO - PROCESS | 29430 | __metrics 14:42:50 INFO - PROCESS | 29430 | 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" 14:42:50 INFO - PROCESS | 29430 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:42:51 INFO - PROCESS | 29430 | console.error: PushService: 14:42:51 INFO - PROCESS | 29430 | stateChangeProcessEnqueue: Error transitioning state 14:42:51 INFO - PROCESS | 29430 | UnknownError 14:42:51 INFO - TEST-INFO | 29430: exit 0 14:42:51 INFO - Browser initialized. 14:42:51 INFO - Running cycle 1/1 for kraken test... 14:42:51 INFO - TEST-INFO | started process 29512 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmps8GB1j/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:43:03 INFO - PROCESS | 29512 | RSS: Main: 173199360 14:43:03 INFO - PROCESS | 29512 | 14:43:06 INFO - PROCESS | 29512 | Cycle 1(1): loaded http://localhost:41306/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41306/tests/kraken/driver.html?testName=audio-beat-detection) 14:43:07 INFO - PROCESS | 29512 | RSS: Main: 232386560 14:43:07 INFO - PROCESS | 29512 | 14:43:09 INFO - PROCESS | 29512 | Cycle 1(1): loaded http://localhost:41306/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41306/tests/kraken/driver.html?testName=audio-dft) 14:43:10 INFO - PROCESS | 29512 | RSS: Main: 216760320 14:43:10 INFO - PROCESS | 29512 | 14:43:13 INFO - PROCESS | 29512 | Cycle 1(1): loaded http://localhost:41306/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41306/tests/kraken/driver.html?testName=audio-fft) 14:43:14 INFO - PROCESS | 29512 | RSS: Main: 298262528 14:43:14 INFO - PROCESS | 29512 | 14:43:16 INFO - PROCESS | 29512 | Cycle 1(1): loaded http://localhost:41306/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41306/tests/kraken/driver.html?testName=audio-oscillator) 14:43:16 INFO - PROCESS | 29512 | RSS: Main: 207409152 14:43:16 INFO - PROCESS | 29512 | 14:43:19 INFO - PROCESS | 29512 | Cycle 1(1): loaded http://localhost:41306/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41306/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:43:19 INFO - PROCESS | 29512 | RSS: Main: 211812352 14:43:19 INFO - PROCESS | 29512 | 14:43:22 INFO - PROCESS | 29512 | Cycle 1(1): loaded http://localhost:41306/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41306/tests/kraken/driver.html?testName=imaging-darkroom) 14:43:23 INFO - PROCESS | 29512 | RSS: Main: 248922112 14:43:23 INFO - PROCESS | 29512 | 14:43:26 INFO - PROCESS | 29512 | Cycle 1(1): loaded http://localhost:41306/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41306/tests/kraken/driver.html?testName=imaging-desaturate) 14:43:27 INFO - PROCESS | 29512 | RSS: Main: 248090624 14:43:27 INFO - PROCESS | 29512 | 14:43:30 INFO - PROCESS | 29512 | Cycle 1(1): loaded http://localhost:41306/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41306/tests/kraken/driver.html?testName=json-parse-financial) 14:43:30 INFO - PROCESS | 29512 | RSS: Main: 248131584 14:43:30 INFO - PROCESS | 29512 | 14:43:32 INFO - PROCESS | 29512 | Cycle 1(1): loaded http://localhost:41306/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41306/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:43:32 INFO - PROCESS | 29512 | RSS: Main: 210042880 14:43:32 INFO - PROCESS | 29512 | 14:43:34 INFO - PROCESS | 29512 | Cycle 1(1): loaded http://localhost:41306/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41306/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:43:35 INFO - PROCESS | 29512 | RSS: Main: 216875008 14:43:35 INFO - PROCESS | 29512 | 14:43:37 INFO - PROCESS | 29512 | Cycle 1(1): loaded http://localhost:41306/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41306/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:43:37 INFO - PROCESS | 29512 | RSS: Main: 221949952 14:43:37 INFO - PROCESS | 29512 | 14:43:40 INFO - PROCESS | 29512 | Cycle 1(1): loaded http://localhost:41306/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41306/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:43:41 INFO - PROCESS | 29512 | RSS: Main: 217657344 14:43:41 INFO - PROCESS | 29512 | 14:43:43 INFO - PROCESS | 29512 | Cycle 1(1): loaded http://localhost:41306/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41306/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:43:44 INFO - PROCESS | 29512 | RSS: Main: 220213248 14:43:44 INFO - PROCESS | 29512 | 14:43:46 INFO - PROCESS | 29512 | Cycle 1(1): loaded http://localhost:41306/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41306/tests/kraken/driver.html?testName=ai-astar) 14:43:46 INFO - PROCESS | 29512 | RSS: Main: 271151104 14:43:46 INFO - PROCESS | 29512 | 14:43:46 INFO - PROCESS | 29512 | __start_tp_report 14:43:46 INFO - PROCESS | 29512 | _x_x_mozilla_page_load 14:43:46 INFO - PROCESS | 29512 | _x_x_mozilla_page_load_details 14:43:46 INFO - PROCESS | 29512 | |i|pagename|runs| 14:43:46 INFO - PROCESS | 29512 | |0;ai-astar;109;113;111;111;112;114;112;112;113;112 14:43:46 INFO - PROCESS | 29512 | |1;audio-beat-detection;152;119;120;169;120;122;118;120;148;121 14:43:46 INFO - PROCESS | 29512 | |2;audio-dft;164;162;162;162;163;163;163;161;162;160 14:43:46 INFO - PROCESS | 29512 | |3;audio-fft;74;76;76;120;76;75;75;75;96;75 14:43:46 INFO - PROCESS | 29512 | |4;audio-oscillator;83;82;111;83;83;83;114;81;82;115 14:43:46 INFO - PROCESS | 29512 | |5;imaging-gaussian-blur;118;117;116;117;117;116;117;117;118;118 14:43:46 INFO - PROCESS | 29512 | |6;imaging-darkroom;147;147;146;146;147;146;146;148;147;146 14:43:46 INFO - PROCESS | 29512 | |7;imaging-desaturate;96;96;97;95;95;96;95;96;96;95 14:43:46 INFO - PROCESS | 29512 | |8;json-parse-financial;59;50;51;51;51;51;51;51;50;51 14:43:46 INFO - PROCESS | 29512 | |9;json-stringify-tinderbox;60;60;60;60;61;60;60;60;60;60 14:43:46 INFO - PROCESS | 29512 | |10;stanford-crypto-aes;79;80;79;79;79;79;79;79;84;78 14:43:46 INFO - PROCESS | 29512 | |11;stanford-crypto-ccm;128;134;123;126;132;125;131;137;177;124 14:43:46 INFO - PROCESS | 29512 | |12;stanford-crypto-pbkdf2;164;161;167;165;161;162;162;161;169;171 14:43:46 INFO - PROCESS | 29512 | |13;stanford-crypto-sha256-iterative;66;66;66;65;69;65;69;64;65;63 14:43:46 INFO - PROCESS | 29512 | __end_tp_report 14:43:46 INFO - PROCESS | 29512 | __start_cc_report 14:43:46 INFO - PROCESS | 29512 | _x_x_mozilla_cycle_collect,1652 14:43:46 INFO - PROCESS | 29512 | __end_cc_report 14:43:46 INFO - PROCESS | 29512 | __startTimestamp1474062226381__endTimestamp 14:43:46 INFO - PROCESS | 29512 | 14:43:46 INFO - PROCESS | 29512 | ------- Summary: start ------- 14:43:46 INFO - PROCESS | 29512 | Number of tests: 14 14:43:46 INFO - PROCESS | 29512 | 14:43:46 INFO - PROCESS | 29512 | [#0] ai-astar Cycles:10 Average:111.90 Median:112.00 stddev:1.37 (1.2%) stddev-sans-first:0.97 14:43:46 INFO - PROCESS | 29512 | Values: 109.0 113.0 111.0 111.0 112.0 114.0 112.0 112.0 113.0 112.0 14:43:46 INFO - PROCESS | 29512 | 14:43:46 INFO - PROCESS | 29512 | [#1] audio-beat-detection Cycles:10 Average:130.90 Median:121.50 stddev:18.35 (15.1%) stddev-sans-first:17.81 14:43:46 INFO - PROCESS | 29512 | Values: 152.0 119.0 120.0 169.0 120.0 122.0 118.0 120.0 148.0 121.0 14:43:46 INFO - PROCESS | 29512 | 14:43:46 INFO - PROCESS | 29512 | [#2] audio-dft Cycles:10 Average:162.20 Median:162.50 stddev:1.14 (0.7%) stddev-sans-first:1.00 14:43:46 INFO - PROCESS | 29512 | Values: 164.0 162.0 162.0 162.0 163.0 163.0 163.0 161.0 162.0 160.0 14:43:46 INFO - PROCESS | 29512 | 14:43:46 INFO - PROCESS | 29512 | [#3] audio-fft Cycles:10 Average:81.80 Median:75.50 stddev:14.94 (19.8%) stddev-sans-first:15.57 14:43:46 INFO - PROCESS | 29512 | Values: 74.0 76.0 76.0 120.0 76.0 75.0 75.0 75.0 96.0 75.0 14:43:46 INFO - PROCESS | 29512 | 14:43:46 INFO - PROCESS | 29512 | [#4] audio-oscillator Cycles:10 Average:91.70 Median:82.50 stddev:14.97 (18.2%) stddev-sans-first:15.55 14:43:46 INFO - PROCESS | 29512 | Values: 83.0 82.0 111.0 83.0 83.0 83.0 114.0 81.0 82.0 115.0 14:43:46 INFO - PROCESS | 29512 | 14:43:46 INFO - PROCESS | 29512 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.74 (0.6%) stddev-sans-first:0.71 14:43:46 INFO - PROCESS | 29512 | Values: 118.0 117.0 116.0 117.0 117.0 116.0 117.0 117.0 118.0 118.0 14:43:46 INFO - PROCESS | 29512 | 14:43:46 INFO - PROCESS | 29512 | [#6] imaging-darkroom Cycles:10 Average:146.60 Median:147.00 stddev:0.70 (0.5%) stddev-sans-first:0.73 14:43:46 INFO - PROCESS | 29512 | Values: 147.0 147.0 146.0 146.0 147.0 146.0 146.0 148.0 147.0 146.0 14:43:46 INFO - PROCESS | 29512 | 14:43:46 INFO - PROCESS | 29512 | [#7] imaging-desaturate Cycles:10 Average:95.70 Median:96.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 14:43:46 INFO - PROCESS | 29512 | Values: 96.0 96.0 97.0 95.0 95.0 96.0 95.0 96.0 96.0 95.0 14:43:46 INFO - PROCESS | 29512 | 14:43:46 INFO - PROCESS | 29512 | [#8] json-parse-financial Cycles:10 Average:51.60 Median:51.00 stddev:2.63 (5.2%) stddev-sans-first:0.44 14:43:46 INFO - PROCESS | 29512 | Values: 59.0 50.0 51.0 51.0 51.0 51.0 51.0 51.0 50.0 51.0 14:43:46 INFO - PROCESS | 29512 | 14:43:46 INFO - PROCESS | 29512 | [#9] json-stringify-tinderbox Cycles:10 Average:60.10 Median:60.00 stddev:0.32 (0.5%) stddev-sans-first:0.33 14:43:46 INFO - PROCESS | 29512 | Values: 60.0 60.0 60.0 60.0 61.0 60.0 60.0 60.0 60.0 60.0 14:43:46 INFO - PROCESS | 29512 | 14:43:46 INFO - PROCESS | 29512 | [#10] stanford-crypto-aes Cycles:10 Average:79.50 Median:79.00 stddev:1.65 (2.1%) stddev-sans-first:1.74 14:43:46 INFO - PROCESS | 29512 | Values: 79.0 80.0 79.0 79.0 79.0 79.0 79.0 79.0 84.0 78.0 14:43:46 INFO - PROCESS | 29512 | 14:43:46 INFO - PROCESS | 29512 | [#11] stanford-crypto-ccm Cycles:10 Average:133.70 Median:131.50 stddev:15.89 (12.1%) stddev-sans-first:16.72 14:43:46 INFO - PROCESS | 29512 | Values: 128.0 134.0 123.0 126.0 132.0 125.0 131.0 137.0 177.0 124.0 14:43:46 INFO - PROCESS | 29512 | 14:43:46 INFO - PROCESS | 29512 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.30 Median:164.50 stddev:3.62 (2.2%) stddev-sans-first:3.84 14:43:46 INFO - PROCESS | 29512 | Values: 164.0 161.0 167.0 165.0 161.0 162.0 162.0 161.0 169.0 171.0 14:43:46 INFO - PROCESS | 29512 | 14:43:46 INFO - PROCESS | 29512 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.80 Median:66.00 stddev:1.93 (2.9%) stddev-sans-first:2.05 14:43:46 INFO - PROCESS | 29512 | Values: 66.0 66.0 66.0 65.0 69.0 65.0 69.0 64.0 65.0 63.0 14:43:46 INFO - PROCESS | 29512 | -------- Summary: end -------- 14:43:46 INFO - PROCESS | 29512 | 14:43:47 INFO - TEST-INFO | 29512: exit 0 14:43:47 INFO - TEST-OK | kraken | took 61799ms 14:43:47 INFO - SUITE-END | took 1177s 14:43:48 INFO - Completed test suite (00:19:38) 14:43:48 INFO - javascript benchmark 14:43:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2949.6607890092637, 2986.328076182859, 2988.656304524979, 3014.728674419302, 3029.5304227844676, 4962.131626965284, 5027.2459131130345, 5115.820946266881, 5146.691198528091, 5168.173092305232, 5181.818636347729, 5194.818181363653, 5216.973915130428, 5250.107481728106, 5269.077911365505, 4637.211674015425, 4677.041206552665, 4724.4094488188975, 4726.432828060638, 4742.170120228963, 4794.592459640935, 4818.542238487338, 4823.34884790554, 4840.5159484051665, 4851.054044461313, 4624.05206932578, 4643.651726120521, 4660.207764680013, 4719.669623126382, 4735.928961065587, 4688.906221875543, 4700.0, 4756.143894099061, 4771.403574553181, 4788.449328327252, 4667.6732628716, 4702.670813043088, 4712.787924543404, 4728.905421891577, 4763.618910487153, 5019.64862459628, 5043.36957880265, 5216.347956505437, 5217.295665085241, 5269.209618557209, 5482.150266708666, 5505.256790333294, 5511.724413779295, 5512.0, 5567.916481252784, 5554.91667624986, 5572.944270557283, 5579.246801681802, 5579.7489112990015, 5583.553315734732, 5012.749362531858, 5197.5322220999915, 5217.921731174036, 5224.947750522522, 5279.313689220377, 5549.084401073777, 5610.326760788732, 5663.405342439067, 5676.8864622707315, 5751.712414379264, 5426.9185962210595, 5432.212329212242, 5433.592480563894, 5435.483629055233, 5441.183822426668, 5462.426445223274, 5522.723863806794, 5524.171374293888, 5526.640768350045, 5537.6954267515675, 5295.338082739658, 5340.91988620171, 5467.425920278313, 5511.862203444865, 5525.530329922001, 5546.334439867242, 5551.417101204396, 5556.055470569932, 5611.663300202001, 5612.017896868032, 4219.767912764766, 4242.703010789215, 4243.0, 4245.0448649053715, 4289.120730250291, 3710.4990826238654, 3730.0488375464083, 3764.5294338207723, 3803.448499967489, 3839.1745774658584, 5342.252084708144, 5342.358916929916, 5345.625806193607, 5374.919376209282, 5387.892242155135, 5580.748866301026, 5601.187827765033, 5609.242752228479, 5620.269364982526, 5620.297462817148, 5087.64386492942, 5130.9230361544605, 5249.580033597303, 5347.144456886957, 5406.91889621656, 4773.331733557305, 4774.976125119352, 4778.617710583145, 4824.445188803263, 4826.61387089032, 5796.797112101056, 5799.94200057994, 5808.970955145197, 5814.418558144152, 5829.38791426911, 5367.409584945731, 5415.052365836042, 5562.360328562268, 5563.58273129525, 5600.187972743848, 5641.435856414326, 5695.715214239189, 5710.514606258348, 5712.685802280915, 5795.7391917362975, 5361.624686271924, 5401.972990135024, 5409.269748584048, 5411.323584551931, 5421.674699518042, 5360.115580929259, 5490.149026900836, 5497.39528651856, 5501.312335958005, 5564.38791732901, 5259.237410575522, 5292.576593872558, 5347.812826551052, 5350.143976963667, 5394.136938089887, 5376.758045888023, 5377.112776391851, 5378.112611419072, 5382.058139825594, 5392.35291764975, 5138.640295179451, 5284.656497327662, 5295.36455625312, 5296.073187192149, 5357.732113394392, 5208.713520756395, 5412.864678383072, 5467.425920278313, 5472.945270547243, 5522.972385138049, 5293.841184764463, 5307.734613269244, 5318.601104917069, 5326.786928522815, 5336.066188416934], "value": 5101.667627225333, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [550.5925615044868, 582.4612233683843, 584.6492104737156, 588.2000479348088, 588.8763359694464, 32071.518927216082, 33064.83467582662, 34909.82545087274, 42410.36384454236, 43848.780756096254, 44585.55414445855, 44975.55024449754, 44985.0, 45006.7749661252, 45011.77494112525, 8742.694005709802, 8899.021107678183, 8899.955500222522, 8903.421277616966, 8906.821863562725, 8752.212300892917, 8843.734687959372, 8848.115188481164, 8852.291816654684, 8855.380123391365, 8844.513551754651, 8905.243054340388, 8910.955445222764, 8912.24245939096, 8915.821683566357, 8820.8676869847, 8822.823543529094, 8841.115888411172, 8862.20240178387, 8877.224555088977, 8690.087540808187, 8797.604107815163, 8805.207531322212, 8813.206811387006, 8818.514981676006, 42273.57726422727, 42459.0, 42529.57470425318, 42551.361729574084, 42638.57361426408, 3387.796732196076, 3480.5475288212624, 3521.8415171317097, 3655.9086022849374, 3885.1646895917515, 31713.52429713533, 31898.681013189802, 31955.520667190012, 32153.0, 32364.838175809207, 4234.597713217239, 4329.502107257674, 4338.566143385668, 4469.9776501117285, 4543.750093744843, 331268.0, 335127.0, 335337.0, 336092.0, 337327.0, 56883.71558142266, 56993.43006569964, 57018.42981570214, 57093.42906570964, 57202.0, 59795.0, 59871.700641496514, 60006.69996649989, 60009.699951500836, 60150.699246503485, 16866.831331686528, 17045.829541704672, 17117.004524208212, 17132.486025419257, 17135.400260991053, 13279.269640169883, 13362.198268103944, 13419.932900335436, 13425.194488330731, 13434.462621495031, 15254.0, 16609.169541522875, 16618.833811661727, 16652.50042498727, 16703.33186672544, 6079.452849243589, 6170.562627762009, 6191.84520386994, 6339.904901426482, 6344.0, 909.8999110097884, 965.5558443116105, 995.4326034160459, 1050.3277902142636, 1121.5962253588702, 30709.846450767604, 31080.378392432023, 31572.368552628817, 31649.36701265962, 32241.193970150463, 872.4154816273112, 1042.9739256518496, 1056.6422147540527, 1084.029758461809, 1105.0496572947259, 15475.380984760708, 16178.27197776103, 16419.91790041066, 16496.42262520806, 16550.6689866202, 481732.0, 486742.0, 487298.0, 488129.0, 489002.0, 57832.421675783546, 57837.373010159085, 57865.421345786, 57967.71016144892, 57969.0, 1201.501376928565, 1217.2148963918223, 1317.8155058291848, 1329.6609364611961, 1343.3484759891444, 28836.855815721625, 30447.391052179275, 31079.689203108584, 31153.79423777761, 31668.683313167494, 49636.751816240685, 50307.496925031744, 50308.49691503184, 50317.0, 50329.245061323374, 396833.0, 397147.0, 397180.0, 397697.0, 398096.0, 28404.715952841034, 29236.468347406186, 29307.267318317212, 29715.405691885608, 29783.255418614706, 46163.76918115522, 46690.0, 47317.0, 47508.52491475041, 48467.51532484771, 39600.2079958405, 39966.2006759869, 40098.59901401065, 40144.0, 41643.16713665649, 39516.10907344912, 39688.80155599319, 39969.80015099906, 41056.384154237116, 41573.168536628495, 39615.80192099021, 40186.79906600448, 40533.0, 41058.1788364237, 41127.794361028005, 33397.0, 38117.0, 38154.236915262096, 38867.6113238864, 39008.0, 27676.446471070867, 27782.5832612515, 27845.860770696017, 28091.246050896625, 28096.578551322156], "value": 19606.069785653857, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [377.3603741657891, 381.1405281091141, 396.8511808071973, 400.2095860675166, 421.1892107692691, 13308.667283317922, 15217.923910380447, 16412.75380869288, 19553.21787128513, 19635.214591416327, 17528.211230494628, 17982.91008544959, 17999.280028798832, 18204.90897545514, 19007.429777106674, 5636.436356364351, 5785.71071446426, 5807.070868661015, 5816.040353341694, 5821.068629019358, 5371.81198658047, 5643.0, 5666.405027472118, 5667.546596272309, 5670.950874088287, 5623.7469313880765, 5635.098384258499, 5638.182463542784, 5639.746211420475, 5639.915401268984, 5497.39528651848, 5732.0, 5763.769449222047, 5813.476787089182, 5866.735996880108, 5462.508374246337, 5619.185218143387, 5677.233573467571, 5687.431256874321, 5692.0, 13548.187108773505, 13562.728745425036, 13618.523351682643, 13820.37808298629, 13872.653183670389, 2250.628646273363, 2276.2260831317267, 2339.847909885869, 2355.705536807899, 2379.773921477457, 12264.632061038183, 13827.654308642264, 14026.228557429336, 14088.577342679737, 14759.92620036893, 1828.6708392489347, 1857.4427671698436, 1896.715492676096, 1915.7701075870846, 1981.5838673878359, 74118.0, 78989.60505197494, 80167.5991620044, 81260.46009566398, 81311.0, 10664.146868250522, 10947.4526273686, 10951.397673127894, 11014.953579409943, 11058.391788451712, 15497.767533487007, 15498.612534686497, 15523.767143492856, 15531.689366212613, 15532.534023979299, 7840.764777056697, 8010.239027292398, 8173.141820108922, 8173.5504547250475, 8183.304419124322, 7314.089536925571, 7371.373433258126, 7378.557286562824, 7626.694932202649, 7732.536047837148, 4382.737035777864, 4481.686281960297, 4532.750698711537, 4778.8767115150795, 4848.757562121879, 1942.1745758053053, 1975.52587379028, 2001.889896055731, 2005.1077270614437, 2006.2977957714684, 837.9874301885477, 927.596495524449, 939.7791518993031, 965.4834663455018, 1005.1054561440324, 17547.64904701899, 18894.81105188958, 19294.0, 19311.420657380302, 19421.90289048574, 956.4187541675436, 1008.7679833638305, 1029.891861354547, 1044.6970378590277, 1055.4300677633992, 9917.851232231522, 10722.89277107219, 10743.623973160902, 10767.246292759588, 10795.946020269848, 454972.0, 460443.0, 469999.0, 473064.0, 475542.0, 87683.1793564833, 89303.55348223218, 90018.0, 90566.0, 93906.0, 976.8778902637171, 984.7341217870992, 997.8553109799038, 998.0418797954045, 998.5157198758596, 14971.401879376093, 16097.919510402373, 16463.588410289838, 16619.0, 16630.833691662927, 40894.38658420066, 41335.0, 41359.37960930649, 41449.171016580105, 41765.1646967065, 55289.1706624409, 56486.43513564812, 56759.71620141873, 58071.709641451525, 58505.0, 5668.801591944262, 6569.981652843817, 7477.738279160203, 7957.8408431832195, 7991.0, 9433.57548910287, 11802.527898884291, 12122.636320910387, 12302.507899683913, 12355.814662779885, 10763.569457221623, 10856.620018299322, 11011.504482298476, 11287.097032237565, 11492.94253528727, 10770.353778773298, 10810.35137891729, 10880.510377032893, 10897.12822974176, 10966.287191332538, 10879.238453308193, 11067.889321106686, 11513.654590362303, 11701.356425396685, 11703.0, 7934.636034771942, 7937.6031198438695, 7945.404094693036, 8292.212239837207, 8339.583020849055, 8466.66133354659, 9940.701778946643, 10698.518566664674, 10882.129429645767, 11107.222494425312], "value": 8854.365421339955, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1351.8783309502144, 1493.0668332292316, 1506.6007508010375, 1665.5253252822947, 1807.1506391995758, 7311.268873112693, 8317.293030092442, 8544.78638034048, 8615.353848461367, 8848.2478989286, 8635.827283454342, 8686.869696954534, 8691.435056721324, 8702.91297087029, 8703.303735701145, 2371.205646108556, 2410.505846301513, 2412.5657381671294, 2414.577448946436, 2417.347316224618, 2282.2354511238673, 2314.2941402872116, 2318.0727708916484, 2321.4776675248017, 2408.1089996701157, 2344.367020904355, 2364.420716924356, 2370.585147599163, 2374.4182675244588, 2375.406148462884, 2320.3734991552187, 2338.8128949683983, 2340.918067867625, 2356.175338631474, 2364.9408764780846, 2306.688597039395, 2322.7212734471973, 2324.140068174786, 2332.603457412244, 2336.0889253191267, 8323.1260717625, 8453.365997550209, 8462.4076314658, 8467.237948584596, 8887.866681999774, 4979.526944940225, 5066.316047333637, 5178.378594568639, 5192.1952097424955, 5195.7402129893735, 3272.7381809455187, 3326.900192994214, 3355.1612096975755, 3359.1434184283094, 3368.7810292331164, 1057.799018186542, 1085.940273284969, 1099.543689368911, 2126.319577735125, 2190.3319487556287, 12434.316112613802, 12544.749105017849, 12585.811212831815, 12588.622341329774, 12592.118551701384, 4378.759168245713, 4505.166544189336, 4646.395968524071, 4651.09303685778, 4659.138059458943, 9773.169280611086, 9777.0, 9779.364341317934, 9784.510774461249, 9787.706368808947, 5174.6377753557645, 5186.507281808223, 5187.221916712448, 5194.766235519411, 5205.167173252262, 4128.112455822013, 4147.730397524151, 4156.314208155681, 4291.635211007037, 4824.614030877521, 3428.9485657715154, 3841.4429907663234, 3847.2690188864026, 3890.844366225376, 3901.648851603313, 1098.2751384086464, 1128.8363187337955, 1130.4856290387854, 1136.9602063927723, 1143.2854465958776, 930.8231436027133, 938.174406522256, 945.8108378324225, 961.2646325560952, 973.4743238651191, 5609.354924183689, 5633.774649013993, 5904.114382842608, 5932.79235226765, 6133.662648554372, 463.5688809407216, 465.3043699669017, 469.55626932548415, 470.97174169549936, 477.398477917826, 4085.0604360997136, 4113.280175969194, 4121.505419349697, 4126.154138401681, 4142.0, 10667.786644267071, 11094.500747466222, 11100.44497775108, 11257.211995160424, 11259.099272058216, 10967.83548246761, 11111.499982500807, 11121.332720036824, 11149.554017839195, 11159.944200278947, 7718.652660630173, 8269.255766981001, 8333.083360830304, 8350.33197344211, 8352.373571982003, 5687.658740475585, 5781.0, 5879.176915231957, 5926.348101708895, 5953.910691339547, 9704.417734935927, 9951.15415189688, 9956.402615843073, 10046.196304295785, 10047.346922450017, 8665.956670216608, 10249.180065594586, 10308.690739277834, 10423.791524169323, 10539.051485366048, 6945.617991010543, 7478.925210747823, 7716.691332346868, 7724.459287849797, 7830.608469576385, 8280.751577452686, 8324.209200125979, 8356.665733370597, 8371.539565323965, 8418.200270974248, 7390.187079421152, 7422.777316680508, 7627.580483073484, 7668.80827979305, 7685.423593230642, 7561.09266887977, 7600.46796724224, 7803.375729941705, 7822.804429889071, 7838.843223135391, 6988.615626140611, 7104.431645468453, 7112.893306600508, 7195.100612423447, 7268.164161121327, 5168.131686913799, 5207.635465517529, 5217.739113044257, 5274.472552744694, 5355.518003379714, 5142.254373115952, 5220.869478263074, 5350.30446042012, 5408.8377348679605, 5450.264214331173], "value": 4538.494706440716, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [317.9697928696773, 323.66824005394466, 325.98533066012027, 331.26128732925577, 331.4315948148927, 5141.742912854356, 5161.096808058589, 5192.117340052196, 5198.0, 5205.297284866551, 4883.829065982692, 4894.730789806568, 5000.874978125539, 5036.949630503694, 5191.351081114861, 3972.9006774830714, 3992.940105898414, 3997.3604223324273, 3997.98001009996, 4037.132016616427, 4006.479157709494, 4022.155347377054, 4029.1941611677635, 4049.5297868300313, 4053.5338436079787, 3774.981125094371, 3834.3481608126685, 3971.26531591655, 3990.1221731219084, 4014.4580481635044, 3938.35017222158, 3943.1270146680386, 3965.4646622706, 3966.226585815767, 3982.6614737747327, 3499.212677147647, 3500.2124521982605, 3501.581859346943, 3504.3867323218333, 3504.684578387957, 4737.313089602023, 4923.729194894279, 4966.627502937295, 4977.377827771528, 4978.228374601942, 4449.599536041772, 4513.977430112828, 4549.931751023737, 4554.7039442436135, 4555.544445555451, 5252.0, 5252.579793616502, 5265.0, 5297.311349524575, 5319.787208511655, 3594.245208506217, 3851.595582463829, 3871.187050719352, 3876.4379165021196, 3887.697621296869, 5463.869091054492, 5500.614956953015, 5508.91736623947, 5513.9448605513835, 5515.365732940683, 3906.160175562211, 3914.941275880864, 3920.1179734560064, 3921.470601468822, 3924.862629807943, 3909.159530700913, 3942.4677668514382, 3943.861964831217, 3959.6835851026894, 3974.4435778990965, 4431.135928493913, 4466.665000125004, 4508.407665592297, 4515.68390212682, 4534.274516077412, 5317.1226747586215, 5333.519983201453, 5336.599755018389, 5381.48875856793, 5381.919271210935, 1350.4463170100212, 1357.8777909988148, 1362.768329384007, 1363.604554679132, 1365.2900491744238, 878.8637761147032, 880.4497189256715, 903.2006674093475, 904.8552231643038, 913.2739472119548, 5167.58659307262, 5182.844514664566, 5192.221166825007, 5203.349581302337, 5224.869378265574, 5381.623286369917, 5405.864853378618, 5421.647592906448, 5444.50999410055, 5460.426655201226, 5009.974950125226, 5072.746362681851, 5097.413797413271, 5115.646802470831, 5223.164293712987, 2843.5308174151446, 2848.273690209025, 2848.643919510061, 2866.097179388486, 2875.7699384049224, 5427.3758517770175, 5581.413951535112, 5617.915731264035, 5634.323881134208, 5651.576131790132, 5465.890682186334, 5473.726313684379, 5475.698836563947, 5484.314460692413, 5501.944980550224, 5572.777088916398, 5594.916076258941, 5597.216389705444, 5600.0, 5648.4633959773755, 5212.661177023481, 5297.867553311198, 5325.467453254643, 5364.195370694427, 5415.620906536662, 5300.1254792958725, 5300.681959082467, 5301.787928482818, 5305.9204111939125, 5316.308879845596, 5584.497395234448, 5587.804426845041, 5608.523275521627, 5630.3525094613005, 5638.746256418389, 3737.0, 3797.601251868514, 3800.7529510581726, 3818.7135964802196, 3878.5151856017997, 5304.9734751326, 5362.436944120811, 5382.434844341445, 5390.51485366319, 5398.190271459155, 5281.947180528299, 5292.814751483679, 5299.682019078868, 5307.150855863043, 5312.681239125664, 5041.218611115283, 5209.879875826716, 5219.615522960731, 5226.555742761908, 5288.9471105289995, 5035.546800787946, 5143.768530416215, 5151.536361727312, 5158.690478571298, 5199.480051994921, 5185.729496273287, 5296.119877443452, 5333.466653334635, 5358.196270559447, 5375.4087050425205, 5179.611529135254, 5205.6356055075785, 5206.401263854554, 5222.138347172674, 5225.503577160163], "value": 4049.9294505776766, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [637.1653134393941, 671.4762485261498, 735.588070680419, 776.8912352270684, 780.9765707028789, 14872.851271487296, 15553.92223038886, 15568.766468502954, 15623.218839058045, 15644.217789110557, 12419.503219871216, 12545.560905368315, 12549.874501254984, 12584.93707531461, 12599.55901543446, 652.9640869752162, 667.933206679333, 779.2129948751744, 794.9682012719513, 797.7726347990824, 790.683726509398, 800.371708209056, 800.9879851802199, 801.2508304735062, 801.4990630855715, 838.2162677896156, 838.6771093129129, 838.9496630202208, 838.9973981092597, 839.1734141870253, 683.641088428574, 695.2074634916149, 825.0470706334194, 831.8253166834971, 845.2266176448542, 623.3548277532777, 623.3703959001396, 623.5821999260506, 623.7130919776909, 624.1792043462832, 14620.195889226088, 14729.337179826933, 14737.11577305365, 14756.704865902622, 14767.409303627843, 14148.434062637481, 14374.281285935662, 14419.567412977627, 14530.636734081627, 14664.926675366662, 12676.95852305737, 12803.5518756844, 12847.229166249961, 12912.483500659962, 13284.601461956061, 4270.701050926436, 4420.756858372788, 4454.8886277842985, 4557.020240648277, 4585.4726706428855, 81548.59225703892, 82404.0, 82664.0, 83443.0, 83588.0, 11128.1653875958, 11224.719382015352, 11230.887691123147, 11387.373694446886, 11511.19421640477, 21533.0, 21680.241191558536, 21825.563488730135, 21829.126834926425, 22086.006129724203, 7701.0, 7768.262015108556, 7825.060992680915, 7855.960720196363, 7883.448158628841, 4111.568285330057, 4163.7293575918075, 4186.958130418718, 4201.074878566595, 4204.3903633972905, 3096.210466331071, 3187.0, 3216.46928256835, 3216.967830321714, 3218.5011323244935, 538.4534697282262, 547.206550501774, 549.4152581312961, 556.9053260945649, 561.9100943848964, 490.76443307212736, 518.3934796288352, 557.1920714963319, 594.2482759309477, 596.4780816785313, 7872.330851877655, 8267.809030606893, 8735.34484913634, 9424.95287523558, 9863.98439864832, 635.8855406026867, 647.9092926990226, 649.2760571962323, 663.7776344924408, 726.3317747672154, 3529.735269854771, 3922.7450215735885, 4097.528784189797, 4103.97948010258, 4171.645410140111, 111642.44178779054, 112797.0, 113294.0, 114458.0, 116364.41817791027, 86105.5694721535, 100278.0, 100350.4982475083, 100483.49758251308, 103392.48303758433, 55554.44445555493, 55657.44342556604, 66905.66547167233, 86152.0, 87507.56246218855, 8763.298936085226, 8830.249428798625, 8874.29005679557, 9254.629814807333, 9279.907200927904, 44828.77585612051, 48225.0, 50115.24827127669, 51967.0, 52000.0, 48626.02747945092, 71622.0, 72396.27603723896, 75400.0, 75922.62038689991, 11976.58081967127, 12029.398530073637, 12037.578684745991, 12107.33409662477, 12342.259464432163, 12880.742385152433, 13280.800787987995, 13340.733185336045, 13711.108777929403, 14218.360173791998, 11093.667189984313, 13256.204627722367, 13459.932700336827, 13478.123921945042, 13618.0, 13852.515161969282, 14304.708587423524, 14534.418623254951, 15172.089674619558, 15206.923965380101, 12468.127231093737, 12472.886106791899, 12522.624321270376, 12602.87397126017, 13206.0, 9168.816623667355, 9350.345475816628, 9788.168005719594, 10348.844767328346, 10549.525271362654, 9972.351797133453, 10426.113780328757, 10449.477526123512, 10578.735531611772, 10600.575976961143], "value": 7158.960284005955, "unit": "score"}], "name": "dromaeo_css", "value": 6989.070322478279, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 113.0, 111.0, 111.0, 112.0, 114.0, 112.0, 112.0, 113.0, 112.0], "value": 111.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 119.0, 120.0, 169.0, 120.0, 122.0, 118.0, 120.0, 148.0, 121.0], "value": 130.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 162.0, 162.0, 162.0, 163.0, 163.0, 163.0, 161.0, 162.0, 160.0], "value": 162.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 76.0, 120.0, 76.0, 75.0, 75.0, 75.0, 96.0, 75.0], "value": 81.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 111.0, 83.0, 83.0, 83.0, 114.0, 81.0, 82.0, 115.0], "value": 91.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 116.0, 117.0, 117.0, 116.0, 117.0, 117.0, 118.0, 118.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 146.0, 146.0, 147.0, 146.0, 146.0, 148.0, 147.0, 146.0], "value": 146.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 97.0, 95.0, 95.0, 96.0, 95.0, 96.0, 96.0, 95.0], "value": 95.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 50.0, 51.0, 51.0, 51.0, 51.0, 51.0, 51.0, 50.0, 51.0], "value": 51.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 60.0, 60.0, 61.0, 60.0, 60.0, 60.0, 60.0, 60.0], "value": 60.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 80.0, 79.0, 79.0, 79.0, 79.0, 79.0, 79.0, 84.0, 78.0], "value": 79.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 134.0, 123.0, 126.0, 132.0, 125.0, 131.0, 137.0, 177.0, 124.0], "value": 133.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 161.0, 167.0, 165.0, 161.0, 162.0, 162.0, 161.0, 169.0, 171.0], "value": 164.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 65.0, 69.0, 65.0, 69.0, 64.0, 65.0, 63.0], "value": 65.8, "unit": "score"}], "name": "kraken", "value": 1492.9, "alertThreshold": 2.0}]} 14:43:48 INFO - Return code: 0 14:43:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:43:48 INFO - # TBPL SUCCESS # 14:43:48 INFO - Running post-action listener: _resource_record_post_action 14:43:48 INFO - [mozharness: 2016-09-16 21:43:48.210020Z] Finished run-tests step (success) 14:43:48 INFO - Running post-run listener: _resource_record_post_run 14:43:48 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 4096; Write bytes: 410513408; Read time: 20; Write time: 352152 14:43:48 INFO - TinderboxPrint: CPU usage
13.0% 14:43:48 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 20 14:43:48 INFO - TinderboxPrint: I/O write bytes / time
410,513,408 / 352,152 14:43:48 INFO - TinderboxPrint: CPU idle
8,291.1 (86.9%) 14:43:48 INFO - TinderboxPrint: CPU user
1,189.8 (12.5%) 14:43:48 INFO - TinderboxPrint: Swap in / out
0 / 0 14:43:48 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 120025088; Read time: 0; Write time: 138616 14:43:48 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 0; Write bytes: 289873920; Read time: 0; Write time: 213536 14:43:48 INFO - Running post-run listener: _upload_blobber_files 14:43:48 INFO - Blob upload gear active. 14:43:48 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:43:48 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 14:43:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:43:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:43:49 INFO - (blobuploader) - INFO - Open directory for files ... 14:43:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:43:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:43:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:43:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:43:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:43:50 INFO - (blobuploader) - INFO - Done attempting. 14:43:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:43:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:43:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:43:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:43:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:43:51 INFO - (blobuploader) - INFO - Done attempting. 14:43:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:43:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:43:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:43:55 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:43:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:43:55 INFO - (blobuploader) - INFO - Done attempting. 14:43:55 INFO - (blobuploader) - INFO - Iteration through files over. 14:43:55 INFO - Return code: 0 14:43:55 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:43:55 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:43:55 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6b72e7801bc217a6cced9a9217dd4de437536407fa3228b1f65aa19beed49c35d0c4ace9143f6130225f974b64bd30dd98181e3500af90f3de9c3ab9e94ef615", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cc00b69ef03dc5af9686e5e1ae9e578b0165c1e2aa6f39f7785cc88f4aa5523d401a22a263ccd8bdcee712037480d59686c7d441e759c71904a7a746083fda75", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d481db9457c89e47c4b5617c8c79ad27dff26282ee8f10bc4f2cafeb34e8dfbbffa6be4f4816d6c6a17725c47e53bd8a2be4e8f18fe8978d826d05b829511bbd"} 14:43:55 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:43:55 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:43:55 INFO - Contents: 14:43:55 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6b72e7801bc217a6cced9a9217dd4de437536407fa3228b1f65aa19beed49c35d0c4ace9143f6130225f974b64bd30dd98181e3500af90f3de9c3ab9e94ef615", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cc00b69ef03dc5af9686e5e1ae9e578b0165c1e2aa6f39f7785cc88f4aa5523d401a22a263ccd8bdcee712037480d59686c7d441e759c71904a7a746083fda75", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d481db9457c89e47c4b5617c8c79ad27dff26282ee8f10bc4f2cafeb34e8dfbbffa6be4f4816d6c6a17725c47e53bd8a2be4e8f18fe8978d826d05b829511bbd"} 14:43:55 INFO - Running post-run listener: copy_logs_to_upload_dir 14:43:55 INFO - Copying logs to upload dir... 14:43:55 INFO - mkdir: /builds/slave/test/build/upload/logs 14:43:55 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.967149 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-09-16 14:43:55.486502) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-16 14:43:55.490350) ========= 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=337fe3f4b1febe37b85fccc40000019e-1474059260.969406-1489388530 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6b72e7801bc217a6cced9a9217dd4de437536407fa3228b1f65aa19beed49c35d0c4ace9143f6130225f974b64bd30dd98181e3500af90f3de9c3ab9e94ef615", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cc00b69ef03dc5af9686e5e1ae9e578b0165c1e2aa6f39f7785cc88f4aa5523d401a22a263ccd8bdcee712037480d59686c7d441e759c71904a7a746083fda75", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d481db9457c89e47c4b5617c8c79ad27dff26282ee8f10bc4f2cafeb34e8dfbbffa6be4f4816d6c6a17725c47e53bd8a2be4e8f18fe8978d826d05b829511bbd"} build_url:https://queue.taskcluster.net/v1/task/KQrHVPZGTHuHzy89jt5weA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011299 build_url: 'https://queue.taskcluster.net/v1/task/KQrHVPZGTHuHzy89jt5weA/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/Fx-Team/sha512/6b72e7801bc217a6cced9a9217dd4de437536407fa3228b1f65aa19beed49c35d0c4ace9143f6130225f974b64bd30dd98181e3500af90f3de9c3ab9e94ef615", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cc00b69ef03dc5af9686e5e1ae9e578b0165c1e2aa6f39f7785cc88f4aa5523d401a22a263ccd8bdcee712037480d59686c7d441e759c71904a7a746083fda75", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d481db9457c89e47c4b5617c8c79ad27dff26282ee8f10bc4f2cafeb34e8dfbbffa6be4f4816d6c6a17725c47e53bd8a2be4e8f18fe8978d826d05b829511bbd"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-16 14:43:55.515259) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 14:43:55.515563) ========= 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=337fe3f4b1febe37b85fccc40000019e-1474059260.969406-1489388530 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004537 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 14:43:55.601598) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-16 14:43:55.601899) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-16 14:43:55.602188) ========= ========= Total master_lag: 0.18 =========