builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-002 starttime: 1474140548.24 results: success (0) buildid: 20160917111018 builduid: 9f49ecdf3d7d47fea47a1930049b2965 revision: 8d211b6a94f603ef81ffcdee3a2b484c2ecd21cd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-17 12:29:08.241763) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-17 12:29:08.242207) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-17 12:29:08.242486) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-17 12:29:08.257945) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 12:29:08.258208) ========= 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=a0d2d2de893abaa8702a9954000001a7-1474140206.36685-2348691 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-17 12:29:08-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.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% 69.9M=0s 2016-09-17 12:29:08 (69.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.072888 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 12:29:08.347231) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 12:29:08.347521) ========= 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=a0d2d2de893abaa8702a9954000001a7-1474140206.36685-2348691 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014207 ========= master_lag: 0.22 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 12:29:08.583964) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 12:29:08.584284) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 8d211b6a94f603ef81ffcdee3a2b484c2ecd21cd --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 8d211b6a94f603ef81ffcdee3a2b484c2ecd21cd --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=a0d2d2de893abaa8702a9954000001a7-1474140206.36685-2348691 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-17 12:29:08,851 truncating revision to first 12 chars 2016-09-17 12:29:08,851 Setting DEBUG logging. 2016-09-17 12:29:08,852 attempt 1/10 2016-09-17 12:29:08,852 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/8d211b6a94f6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-17 12:29:09,149 unpacking tar archive at: mozilla-central-8d211b6a94f6/testing/mozharness/ program finished with exit code 0 elapsedTime=0.643254 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 12:29:09.244145) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-17 12:29:09.244446) ========= script_repo_revision: 8d211b6a94f603ef81ffcdee3a2b484c2ecd21cd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-17 12:29:09.247950) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-17 12:29:09.248253) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-17 12:29:09.258849) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-09-17 12:29:09.259217) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=a0d2d2de893abaa8702a9954000001a7-1474140206.36685-2348691 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:29:09 INFO - MultiFileLogger online at 20160917 12:29:09 in /builds/slave/test 12:29:09 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 12:29:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:29:09 INFO - {'append_to_log': False, 12:29:09 INFO - 'base_work_dir': '/builds/slave/test', 12:29:09 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 12:29:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:29:09 INFO - 'branch': 'Firefox-Non-PGO', 12:29:09 INFO - 'buildbot_json_path': 'buildprops.json', 12:29:09 INFO - 'config_files': ('talos/linux_config.py',), 12:29:09 INFO - 'default_actions': ('clobber', 12:29:09 INFO - 'read-buildbot-config', 12:29:09 INFO - 'download-and-extract', 12:29:09 INFO - 'populate-webroot', 12:29:09 INFO - 'create-virtualenv', 12:29:09 INFO - 'install', 12:29:09 INFO - 'run-tests'), 12:29:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:29:09 INFO - 'download_minidump_stackwalk': True, 12:29:09 INFO - 'download_symbols': 'ondemand', 12:29:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:29:09 INFO - 'tooltool.py': '/tools/tooltool.py', 12:29:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:29:09 INFO - '/tools/misc-python/virtualenv.py')}, 12:29:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:29:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:29:09 INFO - 'installer_path': 'installer.exe', 12:29:09 INFO - 'log_level': 'info', 12:29:09 INFO - 'log_name': 'talos', 12:29:09 INFO - 'log_to_console': True, 12:29:09 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:29:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:29:09 INFO - 'opt_config_files': (), 12:29:09 INFO - 'pip_index': False, 12:29:09 INFO - 'sps_profile': False, 12:29:09 INFO - 'sps_profile_interval': 0, 12:29:09 INFO - 'suite': 'dromaeojs', 12:29:09 INFO - 'system_bits': '32', 12:29:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:29:09 INFO - 'title': 'talos-linux64-ix-002', 12:29:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:29:09 INFO - 'use_talos_json': True, 12:29:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:29:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:29:09 INFO - 'work_dir': 'build'} 12:29:09 INFO - [mozharness: 2016-09-17 19:29:09.621975Z] Running clobber step. 12:29:09 INFO - Running pre-action listener: _resource_record_pre_action 12:29:09 INFO - Running main action method: clobber 12:29:09 INFO - rmtree: /builds/slave/test/build 12:29:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:29:10 INFO - Running post-action listener: _resource_record_post_action 12:29:10 INFO - [mozharness: 2016-09-17 19:29:10.599877Z] Finished clobber step (success) 12:29:10 INFO - [mozharness: 2016-09-17 19:29:10.599986Z] Running read-buildbot-config step. 12:29:10 INFO - Running pre-action listener: _resource_record_pre_action 12:29:10 INFO - Running main action method: read_buildbot_config 12:29:10 INFO - Using buildbot properties: 12:29:10 INFO - { 12:29:10 INFO - "project": "", 12:29:10 INFO - "product": "firefox", 12:29:10 INFO - "script_repo_revision": "production", 12:29:10 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 12:29:10 INFO - "repository": "", 12:29:10 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 12:29:10 INFO - "buildid": "20160917111018", 12:29:10 INFO - "pgo_build": "False", 12:29:10 INFO - "basedir": "/builds/slave/test", 12:29:10 INFO - "buildnumber": 745, 12:29:10 INFO - "slavename": "talos-linux64-ix-002", 12:29:10 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 12:29:10 INFO - "platform": "ubuntu64_hw", 12:29:10 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 12:29:10 INFO - "branch": "mozilla-central", 12:29:10 INFO - "repo_path": "mozilla-central", 12:29:10 INFO - "revision": "8d211b6a94f603ef81ffcdee3a2b484c2ecd21cd", 12:29:10 INFO - "stage_platform": "linux64", 12:29:10 INFO - "builduid": "9f49ecdf3d7d47fea47a1930049b2965", 12:29:10 INFO - "slavebuilddir": "test" 12:29:10 INFO - } 12:29:10 INFO - Found installer url https://queue.taskcluster.net/v1/task/PeWQRu36QMSCcjb2rVnZbQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 12:29:10 INFO - Running post-action listener: _resource_record_post_action 12:29:10 INFO - [mozharness: 2016-09-17 19:29:10.602418Z] Finished read-buildbot-config step (success) 12:29:10 INFO - [mozharness: 2016-09-17 19:29:10.602508Z] Running download-and-extract step. 12:29:10 INFO - Running pre-action listener: _resource_record_pre_action 12:29:10 INFO - Running main action method: download_and_extract 12:29:10 INFO - mkdir: /builds/slave/test/build/tests 12:29:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:29:10 INFO - trying https://queue.taskcluster.net/v1/task/PeWQRu36QMSCcjb2rVnZbQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 12:29:10 INFO - Downloading https://queue.taskcluster.net/v1/task/PeWQRu36QMSCcjb2rVnZbQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 12:29:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PeWQRu36QMSCcjb2rVnZbQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 12:29:11 INFO - Downloaded 1361 bytes. 12:29:11 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 12:29:11 INFO - Using the following test package requirements: 12:29:11 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 12:29:11 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 12:29:11 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 12:29:11 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 12:29:11 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 12:29:11 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 12:29:11 INFO - u'jsshell-linux-x86_64.zip'], 12:29:11 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 12:29:11 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 12:29:11 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 12:29:11 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 12:29:11 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 12:29:11 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 12:29:11 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 12:29:11 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 12:29:11 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 12:29:11 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 12:29:11 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 12:29:11 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 12:29:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PeWQRu36QMSCcjb2rVnZbQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 12:29:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PeWQRu36QMSCcjb2rVnZbQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 12:29:11 INFO - Fetch https://queue.taskcluster.net/v1/task/PeWQRu36QMSCcjb2rVnZbQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 12:29:11 INFO - Expected file size: 20365179 12:29:16 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 12:29:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PeWQRu36QMSCcjb2rVnZbQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 12:29:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PeWQRu36QMSCcjb2rVnZbQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 12:29:16 INFO - Fetch https://queue.taskcluster.net/v1/task/PeWQRu36QMSCcjb2rVnZbQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 12:29:16 INFO - Expected file size: 20365179 12:29:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PeWQRu36QMSCcjb2rVnZbQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 12:29:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PeWQRu36QMSCcjb2rVnZbQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 12:29:21 INFO - Fetch https://queue.taskcluster.net/v1/task/PeWQRu36QMSCcjb2rVnZbQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip into memory 12:29:22 INFO - Expected file size: 14414048 12:29:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:29:23 INFO - trying https://queue.taskcluster.net/v1/task/PeWQRu36QMSCcjb2rVnZbQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 12:29:23 INFO - Downloading https://queue.taskcluster.net/v1/task/PeWQRu36QMSCcjb2rVnZbQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 12:29:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PeWQRu36QMSCcjb2rVnZbQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 12:29:28 INFO - Downloaded 55591906 bytes. 12:29:28 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PeWQRu36QMSCcjb2rVnZbQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 12:29:28 INFO - mkdir: /builds/slave/test/properties 12:29:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:29:28 INFO - Writing to file /builds/slave/test/properties/build_url 12:29:28 INFO - Contents: 12:29:28 INFO - build_url:https://queue.taskcluster.net/v1/task/PeWQRu36QMSCcjb2rVnZbQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 12:29:28 INFO - Running post-action listener: _resource_record_post_action 12:29:28 INFO - Running post-action listener: set_extra_try_arguments 12:29:28 INFO - [mozharness: 2016-09-17 19:29:28.892162Z] Finished download-and-extract step (success) 12:29:28 INFO - [mozharness: 2016-09-17 19:29:28.892392Z] Running populate-webroot step. 12:29:28 INFO - Running pre-action listener: _resource_record_pre_action 12:29:28 INFO - Running main action method: populate_webroot 12:29:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:29:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:29:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:29:28 INFO - '--tppagecycles', 12:29:28 INFO - '7'], 12:29:28 INFO - 'tests': ['tsvgm']}}, 12:29:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:29:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:29:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:29:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:29:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:29:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:29:28 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:29:28 INFO - 'talos_options': ['--disable-e10s'], 12:29:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:29:28 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:29:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:29:28 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:29:28 INFO - 'talos_options': ['--disable-e10s'], 12:29:28 INFO - 'tests': ['damp', 'tps']}, 12:29:28 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:29:28 INFO - 'tests': ['damp', 'tps']}, 12:29:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:29:28 INFO - 'tests': ['dromaeo_dom']}, 12:29:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:29:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:29:28 INFO - 'tests': ['basic_compositor_video']}, 12:29:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:29:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:29:28 INFO - 'tests': ['a11yr', 12:29:28 INFO - 'ts_paint', 12:29:28 INFO - 'tpaint', 12:29:28 INFO - 'sessionrestore', 12:29:28 INFO - 'sessionrestore_no_auto_restore', 12:29:28 INFO - 'tabpaint']}, 12:29:28 INFO - 'other-e10s': {'tests': ['a11yr', 12:29:28 INFO - 'ts_paint', 12:29:28 INFO - 'tpaint', 12:29:28 INFO - 'sessionrestore', 12:29:28 INFO - 'sessionrestore_no_auto_restore', 12:29:28 INFO - 'tabpaint']}, 12:29:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:29:28 INFO - 'ts_paint', 12:29:28 INFO - 'tpaint', 12:29:28 INFO - 'sessionrestore', 12:29:28 INFO - 'sessionrestore_no_auto_restore', 12:29:28 INFO - 'tabpaint']}, 12:29:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:29:28 INFO - 'ts_paint', 12:29:28 INFO - 'tpaint', 12:29:28 INFO - 'sessionrestore', 12:29:28 INFO - 'sessionrestore_no_auto_restore', 12:29:28 INFO - 'tabpaint']}, 12:29:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:29:28 INFO - 'tests': ['a11yr', 12:29:28 INFO - 'ts_paint', 12:29:28 INFO - 'tpaint', 12:29:28 INFO - 'sessionrestore', 12:29:28 INFO - 'sessionrestore_no_auto_restore', 12:29:28 INFO - 'tabpaint']}, 12:29:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:29:28 INFO - 'tests': ['a11yr', 12:29:28 INFO - 'ts_paint', 12:29:28 INFO - 'tpaint', 12:29:28 INFO - 'sessionrestore', 12:29:28 INFO - 'sessionrestore_no_auto_restore', 12:29:28 INFO - 'tabpaint']}, 12:29:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:29:28 INFO - 'tests': ['tsvgx', 12:29:28 INFO - 'tsvgr_opacity', 12:29:28 INFO - 'tart', 12:29:28 INFO - 'tscrollx', 12:29:28 INFO - 'cart']}, 12:29:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:29:28 INFO - 'tsvgr_opacity', 12:29:28 INFO - 'tart', 12:29:28 INFO - 'tscrollx', 12:29:28 INFO - 'cart']}, 12:29:28 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:29:28 INFO - 'talos_options': ['--disable-e10s'], 12:29:28 INFO - 'tests': ['tp5o']}, 12:29:28 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:29:28 INFO - 'tests': ['tp5o']}, 12:29:28 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:29:28 INFO - 'talos_options': ['--disable-e10s', 12:29:28 INFO - '--xperf_path', 12:29:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:29:28 INFO - 'tests': ['tp5n']}, 12:29:28 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:29:28 INFO - 'talos_options': ['--xperf_path', 12:29:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:29:28 INFO - 'tests': ['tp5n']}}, 12:29:28 INFO - 'talos.zip': {'path': '', 12:29:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:29:28 INFO - Running post-action listener: _resource_record_post_action 12:29:28 INFO - [mozharness: 2016-09-17 19:29:28.913076Z] Finished populate-webroot step (success) 12:29:28 INFO - [mozharness: 2016-09-17 19:29:28.913164Z] Running create-virtualenv step. 12:29:28 INFO - Running pre-action listener: _resource_record_pre_action 12:29:28 INFO - Running main action method: create_virtualenv 12:29:28 INFO - Creating virtualenv /builds/slave/test/build/venv 12:29:28 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 12:29:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:29:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:29:29 INFO - Using real prefix '/usr' 12:29:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:29:29 INFO - Installing distribute.............................................................................................................................................................................................done. 12:29:31 INFO - Installing pip.................done. 12:29:31 INFO - Return code: 0 12:29:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:29:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:29:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:29:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:29:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:29:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f93d57dbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a288a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1af5ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1af2f60>, '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 0x1af3d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1af41d0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1474140206.36685-2348691', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:29:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:29:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:29:31 INFO - Using env: {'DISPLAY': ':0', 12:29:31 INFO - 'HOME': '/home/cltbld', 12:29:31 INFO - 'LANG': 'en_US.UTF-8', 12:29:31 INFO - 'LANGUAGE': 'en_US:en', 12:29:31 INFO - 'LOGNAME': 'cltbld', 12:29:31 INFO - 'MAIL': '/var/mail/cltbld', 12:29:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:29:31 INFO - 'MOZ_NO_REMOTE': '1', 12:29:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:29:31 INFO - 'NO_EM_RESTART': '1', 12:29:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:29:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:29:31 INFO - 'PWD': '/builds/slave/test', 12:29:31 INFO - 'SHELL': '/bin/bash', 12:29:31 INFO - 'SHLVL': '1', 12:29:31 INFO - 'TERM': 'linux', 12:29:31 INFO - 'TMOUT': '86400', 12:29:31 INFO - 'USER': 'cltbld', 12:29:31 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1474140206.36685-2348691', 12:29:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:29:31 INFO - '_': '/tools/buildbot/bin/python'} 12:29:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:29:31 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 12:29:31 INFO - Cleaning up... 12:29:31 INFO - Return code: 0 12:29:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:29:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:29:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:29:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:29:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:29:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f93d57dbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a288a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1af5ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1af2f60>, '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 0x1af3d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1af41d0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1474140206.36685-2348691', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:29:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 12:29:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 12:29:31 INFO - Using env: {'DISPLAY': ':0', 12:29:31 INFO - 'HOME': '/home/cltbld', 12:29:31 INFO - 'LANG': 'en_US.UTF-8', 12:29:31 INFO - 'LANGUAGE': 'en_US:en', 12:29:31 INFO - 'LOGNAME': 'cltbld', 12:29:31 INFO - 'MAIL': '/var/mail/cltbld', 12:29:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:29:31 INFO - 'MOZ_NO_REMOTE': '1', 12:29:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:29:31 INFO - 'NO_EM_RESTART': '1', 12:29:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:29:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:29:31 INFO - 'PWD': '/builds/slave/test', 12:29:31 INFO - 'SHELL': '/bin/bash', 12:29:31 INFO - 'SHLVL': '1', 12:29:31 INFO - 'TERM': 'linux', 12:29:31 INFO - 'TMOUT': '86400', 12:29:31 INFO - 'USER': 'cltbld', 12:29:31 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1474140206.36685-2348691', 12:29:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:29:31 INFO - '_': '/tools/buildbot/bin/python'} 12:29:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:29:31 INFO - Downloading/unpacking psutil>=3.1.1 12:29:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:29:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:29:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:29:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:29:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:29:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:29:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:29:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:29:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:29:33 INFO - Installing collected packages: psutil 12:29:33 INFO - Running setup.py install for psutil 12:29:33 INFO - building 'psutil._psutil_linux' extension 12:29:33 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 12:29:34 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 12:29:34 INFO - building 'psutil._psutil_posix' extension 12:29:34 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 12:29:34 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 12:29:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:29:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:29:34 INFO - Successfully installed psutil 12:29:34 INFO - Cleaning up... 12:29:34 INFO - Return code: 0 12:29:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:29:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:29:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:29:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:29:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:29:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f93d57dbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a288a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1af5ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1af2f60>, '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 0x1af3d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1af41d0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1474140206.36685-2348691', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:29:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:29:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 12:29:34 INFO - Using env: {'DISPLAY': ':0', 12:29:34 INFO - 'HOME': '/home/cltbld', 12:29:34 INFO - 'LANG': 'en_US.UTF-8', 12:29:34 INFO - 'LANGUAGE': 'en_US:en', 12:29:34 INFO - 'LOGNAME': 'cltbld', 12:29:34 INFO - 'MAIL': '/var/mail/cltbld', 12:29:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:29:34 INFO - 'MOZ_NO_REMOTE': '1', 12:29:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:29:34 INFO - 'NO_EM_RESTART': '1', 12:29:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:29:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:29:34 INFO - 'PWD': '/builds/slave/test', 12:29:34 INFO - 'SHELL': '/bin/bash', 12:29:34 INFO - 'SHLVL': '1', 12:29:34 INFO - 'TERM': 'linux', 12:29:34 INFO - 'TMOUT': '86400', 12:29:34 INFO - 'USER': 'cltbld', 12:29:34 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1474140206.36685-2348691', 12:29:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:29:34 INFO - '_': '/tools/buildbot/bin/python'} 12:29:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:29:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:29:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:29:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:29:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:29:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:29:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:29:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:29:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:29:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:29:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:29:36 INFO - Installing collected packages: mozsystemmonitor 12:29:36 INFO - Running setup.py install for mozsystemmonitor 12:29:36 INFO - Successfully installed mozsystemmonitor 12:29:36 INFO - Cleaning up... 12:29:36 INFO - Return code: 0 12:29:36 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:29:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:29:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:29:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:29:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:29:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f93d57dbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a288a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1af5ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1af2f60>, '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 0x1af3d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1af41d0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1474140206.36685-2348691', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:29:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 12:29:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 12:29:36 INFO - Using env: {'DISPLAY': ':0', 12:29:36 INFO - 'HOME': '/home/cltbld', 12:29:36 INFO - 'LANG': 'en_US.UTF-8', 12:29:36 INFO - 'LANGUAGE': 'en_US:en', 12:29:36 INFO - 'LOGNAME': 'cltbld', 12:29:36 INFO - 'MAIL': '/var/mail/cltbld', 12:29:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:29:36 INFO - 'MOZ_NO_REMOTE': '1', 12:29:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:29:36 INFO - 'NO_EM_RESTART': '1', 12:29:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:29:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:29:36 INFO - 'PWD': '/builds/slave/test', 12:29:36 INFO - 'SHELL': '/bin/bash', 12:29:36 INFO - 'SHLVL': '1', 12:29:36 INFO - 'TERM': 'linux', 12:29:36 INFO - 'TMOUT': '86400', 12:29:36 INFO - 'USER': 'cltbld', 12:29:36 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1474140206.36685-2348691', 12:29:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:29:36 INFO - '_': '/tools/buildbot/bin/python'} 12:29:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:29:36 INFO - Downloading/unpacking jsonschema==2.5.1 12:29:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:29:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:29:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:29:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:29:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:29:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:29:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:29:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:29:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:29:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:29:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:29:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:29:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:29:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:29:39 INFO - Downloading functools32-3.2.3-2.tar.gz 12:29:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:29:39 INFO - warning: no files found matching '*.txt' 12:29:39 INFO - no previously-included directories found matching 'build' 12:29:39 INFO - no previously-included directories found matching 'dist' 12:29:39 INFO - no previously-included directories found matching '.git*' 12:29:39 INFO - Installing collected packages: jsonschema, functools32 12:29:39 INFO - Running setup.py install for functools32 12:29:39 INFO - warning: no files found matching '*.txt' 12:29:39 INFO - no previously-included directories found matching 'build' 12:29:39 INFO - no previously-included directories found matching 'dist' 12:29:39 INFO - no previously-included directories found matching '.git*' 12:29:39 INFO - Successfully installed jsonschema functools32 12:29:39 INFO - Cleaning up... 12:29:39 INFO - Return code: 0 12:29:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:29:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:29:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:29:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:29:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:29:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f93d57dbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a288a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1af5ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1af2f60>, '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 0x1af3d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1af41d0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1474140206.36685-2348691', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:29:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:29:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 12:29:39 INFO - Using env: {'DISPLAY': ':0', 12:29:39 INFO - 'HOME': '/home/cltbld', 12:29:39 INFO - 'LANG': 'en_US.UTF-8', 12:29:39 INFO - 'LANGUAGE': 'en_US:en', 12:29:39 INFO - 'LOGNAME': 'cltbld', 12:29:39 INFO - 'MAIL': '/var/mail/cltbld', 12:29:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:29:39 INFO - 'MOZ_NO_REMOTE': '1', 12:29:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:29:39 INFO - 'NO_EM_RESTART': '1', 12:29:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:29:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:29:39 INFO - 'PWD': '/builds/slave/test', 12:29:39 INFO - 'SHELL': '/bin/bash', 12:29:39 INFO - 'SHLVL': '1', 12:29:39 INFO - 'TERM': 'linux', 12:29:39 INFO - 'TMOUT': '86400', 12:29:39 INFO - 'USER': 'cltbld', 12:29:39 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1474140206.36685-2348691', 12:29:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:29:39 INFO - '_': '/tools/buildbot/bin/python'} 12:29:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:29:39 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:29:39 INFO - Cleaning up... 12:29:39 INFO - Return code: 0 12:29:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:29:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:29:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:29:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:29:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:29:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f93d57dbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a288a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1af5ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1af2f60>, '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 0x1af3d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1af41d0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1474140206.36685-2348691', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:29:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:29:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:29:39 INFO - Using env: {'DISPLAY': ':0', 12:29:39 INFO - 'HOME': '/home/cltbld', 12:29:39 INFO - 'LANG': 'en_US.UTF-8', 12:29:39 INFO - 'LANGUAGE': 'en_US:en', 12:29:39 INFO - 'LOGNAME': 'cltbld', 12:29:39 INFO - 'MAIL': '/var/mail/cltbld', 12:29:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:29:39 INFO - 'MOZ_NO_REMOTE': '1', 12:29:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:29:39 INFO - 'NO_EM_RESTART': '1', 12:29:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:29:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:29:39 INFO - 'PWD': '/builds/slave/test', 12:29:39 INFO - 'SHELL': '/bin/bash', 12:29:39 INFO - 'SHLVL': '1', 12:29:39 INFO - 'TERM': 'linux', 12:29:39 INFO - 'TMOUT': '86400', 12:29:39 INFO - 'USER': 'cltbld', 12:29:39 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1474140206.36685-2348691', 12:29:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:29:39 INFO - '_': '/tools/buildbot/bin/python'} 12:29:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:29:39 INFO - Downloading/unpacking blobuploader==1.2.4 12:29:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:29:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:29:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:29:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:29:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:29:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:29:41 INFO - Downloading blobuploader-1.2.4.tar.gz 12:29:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:29:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:29:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:29:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:29:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:29:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:29:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:29:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:29:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:29:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:29:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:29:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:29:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:29:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:29:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:29:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:29:42 INFO - Downloading docopt-0.6.1.tar.gz 12:29:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:29:42 INFO - Installing collected packages: blobuploader, requests, docopt 12:29:42 INFO - Running setup.py install for blobuploader 12:29:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:29:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:29:42 INFO - Running setup.py install for requests 12:29:42 INFO - Running setup.py install for docopt 12:29:42 INFO - Successfully installed blobuploader requests docopt 12:29:42 INFO - Cleaning up... 12:29:43 INFO - Return code: 0 12:29:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:29:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:29:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:29:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:29:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:29:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:43 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 0x7f93d57dbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a288a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1af5ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1af2f60>, '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 0x1af3d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1af41d0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1474140206.36685-2348691', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:29:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:29:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:29:43 INFO - Using env: {'DISPLAY': ':0', 12:29:43 INFO - 'HOME': '/home/cltbld', 12:29:43 INFO - 'LANG': 'en_US.UTF-8', 12:29:43 INFO - 'LANGUAGE': 'en_US:en', 12:29:43 INFO - 'LOGNAME': 'cltbld', 12:29:43 INFO - 'MAIL': '/var/mail/cltbld', 12:29:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:29:43 INFO - 'MOZ_NO_REMOTE': '1', 12:29:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:29:43 INFO - 'NO_EM_RESTART': '1', 12:29:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:29:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:29:43 INFO - 'PWD': '/builds/slave/test', 12:29:43 INFO - 'SHELL': '/bin/bash', 12:29:43 INFO - 'SHLVL': '1', 12:29:43 INFO - 'TERM': 'linux', 12:29:43 INFO - 'TMOUT': '86400', 12:29:43 INFO - 'USER': 'cltbld', 12:29:43 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1474140206.36685-2348691', 12:29:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:29:43 INFO - '_': '/tools/buildbot/bin/python'} 12:29:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:29:43 INFO - Running setup.py (path:/tmp/pip-I7weA0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:29:43 INFO - Running setup.py (path:/tmp/pip-Q9Spnx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:29:43 INFO - Running setup.py (path:/tmp/pip-Xt60I6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:29:43 INFO - Running setup.py (path:/tmp/pip-zD7Kdq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:29:43 INFO - Running setup.py (path:/tmp/pip-WKFEV7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:29:43 INFO - Running setup.py (path:/tmp/pip-YwD9Vi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:29:43 INFO - Running setup.py (path:/tmp/pip-MFhp_5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:29:43 INFO - Running setup.py (path:/tmp/pip-6z_W5c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:29:43 INFO - Running setup.py (path:/tmp/pip-eRcyAZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:29:43 INFO - Running setup.py (path:/tmp/pip-QRP0du-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:29:43 INFO - Running setup.py (path:/tmp/pip-I8YtrL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:29:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:29:44 INFO - Running setup.py (path:/tmp/pip-_YZSvb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:29:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:29:44 INFO - Running setup.py (path:/tmp/pip-qIlVF5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:29:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:29:44 INFO - Running setup.py (path:/tmp/pip-vEMMLd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:29:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:29:44 INFO - Running setup.py (path:/tmp/pip-iDXPLZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:29:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:29:44 INFO - Running setup.py (path:/tmp/pip-hkEZch-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:29:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:29:44 INFO - Running setup.py (path:/tmp/pip-UoUobO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:29:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:29:44 INFO - Running setup.py install for manifestparser 12:29:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:29:44 INFO - Running setup.py install for mozcrash 12:29:44 INFO - Running setup.py install for mozdebug 12:29:44 INFO - Running setup.py install for mozdevice 12:29:44 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:29:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:29:44 INFO - Running setup.py install for mozfile 12:29:44 INFO - Running setup.py install for mozhttpd 12:29:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:29:45 INFO - Running setup.py install for mozinfo 12:29:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:29:45 INFO - Running setup.py install for mozInstall 12:29:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:29:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:29:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:29:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:29:45 INFO - Running setup.py install for mozleak 12:29:45 INFO - Running setup.py install for mozlog 12:29:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:29:45 INFO - Running setup.py install for moznetwork 12:29:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:29:45 INFO - Running setup.py install for mozprocess 12:29:45 INFO - Running setup.py install for mozprofile 12:29:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:29:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:29:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:29:45 INFO - Running setup.py install for mozrunner 12:29:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:29:45 INFO - Running setup.py install for mozscreenshot 12:29:46 INFO - Running setup.py install for moztest 12:29:46 INFO - Running setup.py install for mozversion 12:29:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:29:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:29:46 INFO - Cleaning up... 12:29:46 INFO - Return code: 0 12:29:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:29:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:29:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:29:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:29:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:29:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:46 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 0x7f93d57dbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a288a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1af5ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1af2f60>, '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 0x1af3d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1af41d0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1474140206.36685-2348691', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:29:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:29:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:29:46 INFO - Using env: {'DISPLAY': ':0', 12:29:46 INFO - 'HOME': '/home/cltbld', 12:29:46 INFO - 'LANG': 'en_US.UTF-8', 12:29:46 INFO - 'LANGUAGE': 'en_US:en', 12:29:46 INFO - 'LOGNAME': 'cltbld', 12:29:46 INFO - 'MAIL': '/var/mail/cltbld', 12:29:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:29:46 INFO - 'MOZ_NO_REMOTE': '1', 12:29:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:29:46 INFO - 'NO_EM_RESTART': '1', 12:29:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:29:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:29:46 INFO - 'PWD': '/builds/slave/test', 12:29:46 INFO - 'SHELL': '/bin/bash', 12:29:46 INFO - 'SHLVL': '1', 12:29:46 INFO - 'TERM': 'linux', 12:29:46 INFO - 'TMOUT': '86400', 12:29:46 INFO - 'USER': 'cltbld', 12:29:46 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1474140206.36685-2348691', 12:29:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:29:46 INFO - '_': '/tools/buildbot/bin/python'} 12:29:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:29:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:29:46 INFO - Running setup.py (path:/tmp/pip-8v42aa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:29:46 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 12:29:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:29:46 INFO - Running setup.py (path:/tmp/pip-xEG6pa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:29:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:29:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:29:46 INFO - Running setup.py (path:/tmp/pip-7jOZvX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:29:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:29:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:29:46 INFO - Running setup.py (path:/tmp/pip-nXr5Y1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:29:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:29:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:29:46 INFO - Running setup.py (path:/tmp/pip-_DNrJB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:29:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 12:29:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:29:46 INFO - Running setup.py (path:/tmp/pip-ixK24B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:29:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 12:29:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:29:46 INFO - Running setup.py (path:/tmp/pip-5cJwan-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:29:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 12:29:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:29:46 INFO - Running setup.py (path:/tmp/pip-GBuuB9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:29:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 12:29:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:29:47 INFO - Running setup.py (path:/tmp/pip-73uGNm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:29:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 12:29:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:29:47 INFO - Running setup.py (path:/tmp/pip-OH1kA7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:29:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:29:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:29:47 INFO - Running setup.py (path:/tmp/pip-0bNnux-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:29:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 12:29:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:29:47 INFO - Running setup.py (path:/tmp/pip-TDfmmY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:29:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 12:29:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:29:47 INFO - Running setup.py (path:/tmp/pip-rtlkfR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:29:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 12:29:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:29:47 INFO - Running setup.py (path:/tmp/pip-cimBQk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:29:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 12:29:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:29:47 INFO - Running setup.py (path:/tmp/pip-LERypI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:29:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 12:29:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:29:47 INFO - Running setup.py (path:/tmp/pip-20a6R6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:29:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 12:29:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:29:47 INFO - Running setup.py (path:/tmp/pip-aWeshH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:29:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 12:29:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:29:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:29:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:29:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:29:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:29:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:29:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:29:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:29:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:29:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:29:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:29:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:29:49 INFO - Downloading blessings-1.6.tar.gz 12:29:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:29:49 INFO - Installing collected packages: blessings 12:29:49 INFO - Running setup.py install for blessings 12:29:49 INFO - Successfully installed blessings 12:29:49 INFO - Cleaning up... 12:29:49 INFO - Return code: 0 12:29:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:29:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:29:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:29:50 INFO - Reading from file tmpfile_stdout 12:29:50 INFO - Current package versions: 12:29:50 INFO - argparse == 1.2.1 12:29:50 INFO - blessings == 1.6 12:29:50 INFO - blobuploader == 1.2.4 12:29:50 INFO - docopt == 0.6.1 12:29:50 INFO - functools32 == 3.2.3-2 12:29:50 INFO - jsonschema == 2.5.1 12:29:50 INFO - manifestparser == 1.1 12:29:50 INFO - mozInstall == 1.12 12:29:50 INFO - mozcrash == 0.17 12:29:50 INFO - mozdebug == 0.1 12:29:50 INFO - mozdevice == 0.48 12:29:50 INFO - mozfile == 1.2 12:29:50 INFO - mozhttpd == 0.7 12:29:50 INFO - mozinfo == 0.9 12:29:50 INFO - mozleak == 0.1 12:29:50 INFO - mozlog == 3.4 12:29:50 INFO - moznetwork == 0.27 12:29:50 INFO - mozprocess == 0.23 12:29:50 INFO - mozprofile == 0.28 12:29:50 INFO - mozrunner == 6.12 12:29:50 INFO - mozscreenshot == 0.1 12:29:50 INFO - mozsystemmonitor == 0.3 12:29:50 INFO - moztest == 0.7 12:29:50 INFO - mozversion == 1.4 12:29:50 INFO - psutil == 3.1.1 12:29:50 INFO - requests == 1.2.3 12:29:50 INFO - wsgiref == 0.1.2 12:29:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:29:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:29:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:29:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:29:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:29:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:50 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 0x7f93d57dbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a288a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1af5ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1af2f60>, '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 0x1af3d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1af41d0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1474140206.36685-2348691', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:29:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 12:29:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:29:50 INFO - Using env: {'DISPLAY': ':0', 12:29:50 INFO - 'HOME': '/home/cltbld', 12:29:50 INFO - 'LANG': 'en_US.UTF-8', 12:29:50 INFO - 'LANGUAGE': 'en_US:en', 12:29:50 INFO - 'LOGNAME': 'cltbld', 12:29:50 INFO - 'MAIL': '/var/mail/cltbld', 12:29:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:29:50 INFO - 'MOZ_NO_REMOTE': '1', 12:29:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:29:50 INFO - 'NO_EM_RESTART': '1', 12:29:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:29:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:29:50 INFO - 'PWD': '/builds/slave/test', 12:29:50 INFO - 'SHELL': '/bin/bash', 12:29:50 INFO - 'SHLVL': '1', 12:29:50 INFO - 'TERM': 'linux', 12:29:50 INFO - 'TMOUT': '86400', 12:29:50 INFO - 'USER': 'cltbld', 12:29:50 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1474140206.36685-2348691', 12:29:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:29:50 INFO - '_': '/tools/buildbot/bin/python'} 12:29:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:29:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:29:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 12:29:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 12:29:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:29:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 12:29:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 12:29:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 12:29:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 12:29:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 12:29:50 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:29:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:29:50 INFO - Cleaning up... 12:29:50 INFO - Return code: 0 12:29:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:29:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:29:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:29:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:29:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:29:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:50 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 0x7f93d57dbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a288a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1af5ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1af2f60>, '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 0x1af3d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1af41d0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1474140206.36685-2348691', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:29:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:29:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:29:50 INFO - Using env: {'DISPLAY': ':0', 12:29:50 INFO - 'HOME': '/home/cltbld', 12:29:50 INFO - 'LANG': 'en_US.UTF-8', 12:29:50 INFO - 'LANGUAGE': 'en_US:en', 12:29:50 INFO - 'LOGNAME': 'cltbld', 12:29:50 INFO - 'MAIL': '/var/mail/cltbld', 12:29:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:29:50 INFO - 'MOZ_NO_REMOTE': '1', 12:29:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:29:50 INFO - 'NO_EM_RESTART': '1', 12:29:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:29:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:29:50 INFO - 'PWD': '/builds/slave/test', 12:29:50 INFO - 'SHELL': '/bin/bash', 12:29:50 INFO - 'SHLVL': '1', 12:29:50 INFO - 'TERM': 'linux', 12:29:50 INFO - 'TMOUT': '86400', 12:29:50 INFO - 'USER': 'cltbld', 12:29:50 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1474140206.36685-2348691', 12:29:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:29:50 INFO - '_': '/tools/buildbot/bin/python'} 12:29:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:29:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:29:50 INFO - Cleaning up... 12:29:50 INFO - Return code: 0 12:29:50 INFO - Running post-action listener: _resource_record_post_action 12:29:50 INFO - Running post-action listener: _start_resource_monitoring 12:29:50 INFO - Starting resource monitoring. 12:29:50 INFO - [mozharness: 2016-09-17 19:29:50.531844Z] Finished create-virtualenv step (success) 12:29:50 INFO - [mozharness: 2016-09-17 19:29:50.532416Z] Running install step. 12:29:50 INFO - Running pre-action listener: _resource_record_pre_action 12:29:50 INFO - Running main action method: install 12:29:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:29:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:29:50 INFO - Reading from file tmpfile_stdout 12:29:50 INFO - Detecting whether we're running mozinstall >=1.0... 12:29:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:29:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:29:50 INFO - Reading from file tmpfile_stdout 12:29:50 INFO - Output received: 12:29:50 INFO - Usage: mozinstall [options] installer 12:29:50 INFO - Options: 12:29:50 INFO - -h, --help show this help message and exit 12:29:50 INFO - -d DEST, --destination=DEST 12:29:50 INFO - Directory to install application into. [default: 12:29:50 INFO - "/builds/slave/test"] 12:29:50 INFO - --app=APP Application being installed. [default: firefox] 12:29:50 INFO - mkdir: /builds/slave/test/build/application 12:29:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 12:29:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 12:30:06 INFO - Reading from file tmpfile_stdout 12:30:06 INFO - Output received: 12:30:06 INFO - /builds/slave/test/build/application/firefox/firefox 12:30:06 INFO - Running post-action listener: _resource_record_post_action 12:30:06 INFO - [mozharness: 2016-09-17 19:30:06.876296Z] Finished install step (success) 12:30:06 INFO - [mozharness: 2016-09-17 19:30:06.876541Z] Running run-tests step. 12:30:06 INFO - Running pre-action listener: _resource_record_pre_action 12:30:06 INFO - Running main action method: run_tests 12:30:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:30:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:30:06 INFO - Python 2.7.3 12:30:06 INFO - Return code: 0 12:30:06 INFO - grabbing minidump binary from tooltool 12:30:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:30:06 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1af2f60>, '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 0x1af3d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1af41d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:30:06 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 12:30:06 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 12:30:06 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 12:30:06 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:30:06 INFO - Return code: 0 12:30:06 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:30:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:30:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:30:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:30:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:30:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PeWQRu36QMSCcjb2rVnZbQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-002', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 12:30:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/PeWQRu36QMSCcjb2rVnZbQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-002 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 12:30:06 INFO - Using env: {'DISPLAY': ':0', 12:30:06 INFO - 'HOME': '/home/cltbld', 12:30:06 INFO - 'LANG': 'en_US.UTF-8', 12:30:06 INFO - 'LANGUAGE': 'en_US:en', 12:30:06 INFO - 'LOGNAME': 'cltbld', 12:30:06 INFO - 'MAIL': '/var/mail/cltbld', 12:30:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:30:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:30:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:30:06 INFO - 'MOZ_NO_REMOTE': '1', 12:30:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:30:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:30:06 INFO - 'NO_EM_RESTART': '1', 12:30:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:30:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:30:06 INFO - 'PWD': '/builds/slave/test', 12:30:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:30:06 INFO - 'SHELL': '/bin/bash', 12:30:06 INFO - 'SHLVL': '1', 12:30:06 INFO - 'TERM': 'linux', 12:30:06 INFO - 'TMOUT': '86400', 12:30:06 INFO - 'USER': 'cltbld', 12:30:06 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1474140206.36685-2348691', 12:30:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:30:06 INFO - '_': '/tools/buildbot/bin/python'} 12:30:06 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PeWQRu36QMSCcjb2rVnZbQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-002', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 12:30:07 INFO - mozversion application_buildid: 20160917111018 12:30:07 INFO - mozversion application_changeset: 8d211b6a94f603ef81ffcdee3a2b484c2ecd21cd 12:30:07 INFO - mozversion application_display_name: Nightly 12:30:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:30:07 INFO - mozversion application_name: Firefox 12:30:07 INFO - mozversion application_remotingname: firefox 12:30:07 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 12:30:07 INFO - mozversion application_vendor: Mozilla 12:30:07 INFO - mozversion application_version: 51.0a1 12:30:07 INFO - mozversion platform_buildid: 20160917111018 12:30:07 INFO - mozversion platform_changeset: 8d211b6a94f603ef81ffcdee3a2b484c2ecd21cd 12:30:07 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 12:30:07 INFO - mozversion platform_version: 51.0a1 12:30:07 INFO - using testdate: 1474140607 12:30:07 INFO - actual date: 1474140607 12:30:07 INFO - starting webserver on 'localhost:44782' 12:30:07 INFO - SUITE-START | Running 2 tests 12:30:07 INFO - TEST-START | dromaeo_css 12:30:07 INFO - operating with platform_type : linux_ 12:30:07 INFO - Initialising browser for dromaeo_css test... 12:30:07 INFO - TEST-INFO | started process 3892 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpM700Ty/profile http://localhost:44782/getInfo.html) 12:30:11 INFO - PROCESS | 3892 | __metrics Screen width/height:1600/1200 12:30:11 INFO - PROCESS | 3892 | colorDepth:24 12:30:11 INFO - PROCESS | 3892 | Browser inner width/height: 1024/697 12:30:11 INFO - PROCESS | 3892 | __metrics 12:30:12 INFO - PROCESS | 3892 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:30:12 INFO - PROCESS | 3892 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:30:12 INFO - PROCESS | 3892 | console.error: PushService: 12:30:12 INFO - PROCESS | 3892 | stateChangeProcessEnqueue: Error transitioning state 12:30:12 INFO - PROCESS | 3892 | UnknownError 12:30:13 INFO - TEST-INFO | 3892: exit 0 12:30:13 INFO - Browser initialized. 12:30:13 INFO - Running cycle 1/1 for dromaeo_css test... 12:30:13 INFO - TEST-INFO | started process 3975 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpM700Ty/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:30:25 INFO - PROCESS | 3975 | RSS: Main: 174841856 12:30:25 INFO - PROCESS | 3975 | 12:30:32 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 12:30:48 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 12:31:04 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.05007 seconds. 12:31:12 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.05003 seconds. 12:31:17 INFO - PROCESS | 3975 | 1474140677379 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 12:31:24 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.08335 seconds. 12:31:34 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 12:31:41 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.05004 seconds. 12:31:48 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 12:31:53 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 12:31:54 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 2.08343 seconds. 12:32:00 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.05005 seconds. 12:32:05 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 12:32:07 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 12:32:09 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 12:32:17 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.08338 seconds. 12:32:22 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 12:32:36 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.05004 seconds. 12:32:41 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 12:32:43 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 12:33:29 INFO - PROCESS | 3975 | Cycle 1(1): loaded http://localhost:44782/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44782/tests/dromaeo/cssquery-ext.html) 12:33:31 INFO - PROCESS | 3975 | RSS: Main: 251547648 12:33:31 INFO - PROCESS | 3975 | 12:33:34 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 12:33:39 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 12:33:43 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 12:34:08 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:34:16 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 12:34:30 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:34:36 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:34:43 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00009 seconds. 12:34:44 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:34:46 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:35:00 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 12:35:17 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:35:27 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 12:35:29 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:35:33 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 12:35:44 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 12:35:51 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 12:35:53 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 12:35:55 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 12:36:01 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 12:36:07 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 12:36:22 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 12:36:26 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 12:36:27 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 2.00007 seconds. 12:36:33 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.0334 seconds. 12:36:34 INFO - PROCESS | 3975 | Cycle 1(1): loaded http://localhost:44782/tests/dromaeo/cssquery-ext.html (next: http://localhost:44782/tests/dromaeo/cssquery-jquery.html) 12:36:34 INFO - PROCESS | 3975 | RSS: Main: 269180928 12:36:34 INFO - PROCESS | 3975 | 12:36:38 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.03341 seconds. 12:36:39 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.01659 seconds. 12:36:40 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 2.04997 seconds. 12:36:47 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00008 seconds. 12:36:55 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 12:37:00 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 12:37:10 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 12:37:19 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:37:21 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 12:37:23 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 12:37:27 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 12:37:44 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:37:46 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 12:37:48 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 12:37:50 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 12:38:03 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 12:38:04 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 12:38:05 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 2.00014 seconds. 12:38:06 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 12:38:08 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:38:10 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:38:12 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 12:38:14 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 12:38:22 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 12:38:24 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:38:32 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 12:38:34 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:38:45 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 12:38:54 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:39:00 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:39:02 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 12:39:22 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 12:39:25 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 12:39:29 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 12:39:31 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 12:39:37 INFO - PROCESS | 3975 | Cycle 1(1): loaded http://localhost:44782/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44782/tests/dromaeo/cssquery-mootools.html) 12:39:37 INFO - PROCESS | 3975 | RSS: Main: 248168448 12:39:37 INFO - PROCESS | 3975 | 12:39:41 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.03342 seconds. 12:39:52 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 12:40:00 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 12:40:08 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 12:40:30 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 12:40:36 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00012 seconds. 12:40:42 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 12:41:01 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 12:41:02 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 2.01671 seconds. 12:41:11 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 12:41:13 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00358 seconds. 12:41:30 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:41:47 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 12:41:49 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 12:41:51 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 12:41:55 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 12:41:58 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 12:41:59 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:42:03 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:42:11 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 12:42:17 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:42:33 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 12:42:40 INFO - PROCESS | 3975 | Cycle 1(1): loaded http://localhost:44782/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44782/tests/dromaeo/cssquery-prototype.html) 12:42:40 INFO - PROCESS | 3975 | RSS: Main: 218177536 12:42:40 INFO - PROCESS | 3975 | 12:42:50 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 12:42:51 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 12:42:57 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 12:43:19 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 12:43:26 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 12:43:27 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:43:30 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 12:43:36 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 12:43:38 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 12:43:40 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 12:43:52 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 12:43:54 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 12:44:06 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 12:44:09 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 12:44:12 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:44:18 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 12:44:23 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:44:28 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 12:44:32 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 12:44:35 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 12:44:39 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 12:44:41 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 12:44:43 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 12:44:47 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:44:49 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 12:44:53 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 12:44:55 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:44:57 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 12:45:01 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:45:03 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 12:45:06 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 12:45:13 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:45:19 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 12:45:22 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 12:45:33 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:45:36 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 12:45:43 INFO - PROCESS | 3975 | Cycle 1(1): loaded http://localhost:44782/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44782/tests/dromaeo/cssquery-yui.html) 12:45:44 INFO - PROCESS | 3975 | RSS: Main: 218714112 12:45:44 INFO - PROCESS | 3975 | 12:45:58 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:46:05 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 12:46:11 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 12:46:17 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:46:19 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:46:59 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 12:47:01 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 12:47:27 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 12:47:38 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 12:47:40 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 12:47:42 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 12:47:54 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 12:48:02 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 12:48:12 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:48:19 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 12:48:23 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 12:48:30 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 12:48:35 INFO - PROCESS | 3975 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 12:48:47 INFO - PROCESS | 3975 | Cycle 1(1): loaded http://localhost:44782/tests/dromaeo/cssquery-yui.html (next: http://localhost:44782/tests/dromaeo/cssquery-dojo.html) 12:48:47 INFO - PROCESS | 3975 | RSS: Main: 308940800 12:48:47 INFO - PROCESS | 3975 | 12:48:47 INFO - PROCESS | 3975 | __start_tp_report 12:48:47 INFO - PROCESS | 3975 | _x_x_mozilla_page_load 12:48:47 INFO - PROCESS | 3975 | _x_x_mozilla_page_load_details 12:48:47 INFO - PROCESS | 3975 | |i|pagename|runs| 12:48:47 INFO - PROCESS | 3975 | |0;dojo.html;2558.4243545202326;2588.8576128312934;2615;2615.5292047431467;2637.1297471834287;4576.405067341241;4632.374629425027;4740.715557066575;4760.761961901904;4782.258749893767;4677.929831052528;4701.576858082772;4708.199606066968;4718.504557021515;4751.097291514609;4311.698181127323;4335.371371151181;4354.629856462205;4354.804033818486;4426.667999900004;4353.368761529577;4383.232934236496;4394.494633117184;4420.9336859947125;4435.933460998088;4207.200631879933;4249.298865687158;4340.956590434087;4357.041450880801;4377.321515165155;4279.91440171198;4300.268954277781;4317.76252306123;4350.7607081610495;4411.448568928884;4308.633766129883;4324.200022995725;4382.978085109586;4383.035732138925;4394.516603173648;4699.295105734133;4707.223308154151;4718.598919091882;4735.076660051292;4786.282057691375;4903.583195428393;4953.356063711731;4994.400671919357;5033.546980771748;5037.672551284155;5050.191969284933;5078.289039534468;5120.974395128001;5139.486051394868;5139.7687104080405;4735.692180008288;4737.928931066037;4745.074710431432;4745.335653008581;4804.639652026112;5053;5166.638335316492;5177.948220517746;5181.119209734355;5192.376914770176;4877.170880950247;4897.8285759998225;4920.3111564381015;4923.655344125948;4938.160512712848;4992.750362481861;5003.424606170337;5010.849674509771;5076.289319495274;5139.614528910347;4999.975000124976;5004.699718016931;5031.949680503221;5049.495050494995;5100.1074811907765;4853.757312134449;4901.877453063702;4997.125503036954;5009.9729555441045;5022.648414610942;4373.518912919576;4375.890602734893;4387;4389.144116897238;4445.377647129404;3797.0697179191275;3807.3908174691915;3838.596947320547;3852.9229415411537;3869.4582758413844;4261.041265715189;4305.225059489322;4346.456692913386;4355.346697995326;4390.034192477649;5036.64743467961;5038.269450929668;5082.669626474268;5097.949020509747;5114.974425127851;4350.912981740411;4388.802503887333;4394.868153955386;4397.626201772821;4414.690971632019;4437.29003359461;4479.484859241229;4632.930506042412;4638.257878739386;4684.906301873943;4684.882877928079;4716.221823399101;4733.408323959505;4742.976285118552;4807;5048.570871475966;5053.29253904461;5061.822836200715;5062.190049592047;5118.97440512795;4613.331066995334;4725.952740472689;4735.644826638085;4741.051789642154;4792.856214313576;4879.146149423907;4964.578010868972;4994.275830004702;5013.774380152975;5085.084684756632;5082.669626474268;5090.2364645303505;5092.898142037213;5093.617978651542;5148.562372198405;4864.416270047617;4953.72754498506;4960.107180707508;4960.801567937242;4974.651774375759;4818.204996175592;4838.371011768448;4847.151748444078;4862.902741945212;4890.975545122252;4787.593054590398;4799.3041009052795;4852.369192005017;4862.659613826998;4865.513448655106;4857.53853383928;4897.4857639947295;4903.5586797188425;4906.533879281463;4964.180910149929;4834.685745426536;4836.202026665561;4837.419509658864;4844.346013288161;4845.685030473008 12:48:47 INFO - PROCESS | 3975 | |1;ext.html;562.1707980728426;564.5878508688658;575.792714622736;577.4139248662608;579.3916387792816;28039.439211215762;28818.711812881866;38766.612333876685;39790.602093979054;39873.800630996804;39996.80001599995;40421.7978910105;40430.39354409686;40434.7978260109;40442.797786011106;7554.169041405455;7721.72973945912;7725.150233474314;7728.381729461629;7730.884036739453;7278.126624805031;7486.62566871657;7535.9623201883915;9851.704448866545;10117.747056323577;10205.642802501914;10264.794704105914;10278.691639250834;10279.794404111914;10281.897181028206;10068;10117.747056323577;10132.290739648228;10137;10142.239332050125;10044.748881277954;10113.69658910234;10116.342437741523;10119.190464762874;10120.898791012069;37947;38272;38284.80857595722;38287.808560957295;38391.42412863809;2743.8353698778137;2761.8619069046667;2832.391035927265;3097.7366923811505;3104.1929098434343;28537.857310713312;28843;28909;28947.131586052452;29043.273918152005;3207.3571793510796;3225.70968612826;3262.755293353008;3281.6718328166994;3332.8333583320737;245245;245261;245412;245869;246241;32483.350332993206;33657.49513757296;51741.48258517442;52055.47944520582;52097;31329.373412532077;31589.052328430185;31645.52531712026;31681.36637267242;31688.841555792074;11432.94283528577;11471.369074700804;11493.540258389738;11502.274658801247;11518.136139789549;8916.286697064219;10257.384556926609;10381.896181038244;10382.844257336146;10383.636572719919;7216.494845360774;7509.662065207079;7749.690012399441;7786.299233069051;7871.0948240951875;3007.173027417478;3056.388722255513;3084.352286019916;3085.5525948737754;3123.094302652251;778.5056489129444;845.3321875718236;854.1159899503983;858.2490320969152;935.6070450410843;27574.862125689244;27717.58423623647;28576.714232857405;29699.40601187964;30010.699893001227;901.3915606965272;914.7941713114496;925.6112432778115;936.0452338614712;962.552413127899;14115.71768564623;14743.262836858328;15094.622634434227;15854.128022958848;16115.355385784438;363156;364223;365117;365532;366296;48216.5178348212;48770.512294877306;48840;48926;48957.75521122372;1037.8339465685453;1058.3068090400955;1127.2053202492386;1166.2535976974743;1170.8302296167008;25448.109316173846;28615.520940674873;29453.558196627495;29875.701242987292;30058.83793822305;40802;40969;41279.7936010318;41549.79225103976;41602.791986041084;288397;289741;289771.98306245054;289805;290211;24323.27030189097;25042.248732538053;25217.369565761004;25253.49493010093;26434.339141521617;40778.388324174564;41015;41196.79401602973;41362.172756545304;42118.36822447727;35484.29031419409;35718.107047324025;35826.28347433089;35909.28181436409;36202.81898590596;34692;35626;35786.28427431489;36075.27849443049;36172;34068.318633626695;34637.48043779396;34882.825585871906;35273;35304.82347588348;30853.537196942514;32622.83688581542;32726.50910236301;33141.680082394;33447.16382090467;24300.27099187027;24388.75611243865;25115.372115696522;25424.872875635505;25559.23322300334 12:48:47 INFO - PROCESS | 3975 | |2;jquery.html;334.5984818218136;353.63398882156974;357.61020487668435;359.9514065601143;384.90185002824273;10954.726131846697;15656.608584785372;16107.677846443079;16526.25631846567;17125.657486850254;16198.676026479492;16463.91768041158;16590.336386544524;16796.32814687411;16876.324947002136;4851.708897466146;4945.455999840015;4953.182724850404;5003.799848006075;5170.793168273283;3666.50502182206;4953.777080031389;5029;5032.11937910866;5037.3451451311275;3816.656500914917;5589.8602534936745;5942.5840191186635;5973.432523910222;5991;5934.643921364732;6110.175126357929;6114.327423983381;6665.700043498055;6987.266337034589;6850.280720524373;6855.383015528577;6861.451083913275;6864;6868.107146071029;22177.44556386087;23210;23411.297661070195;23718.40703982397;24076.157334493208;1849.4636555398918;1859.8605104617072;1889.1404410992966;1926.0466069295649;2149.9895049326947;13566.864331356659;13919.582412527641;14159.85840141606;14435.566932992027;15482.148481833494;1641.606014556498;1695.440504633468;1789.2216885654652;1802.3331367394017;1842.9262829486804;78738;78777.60611196965;80503;81214.59392702999;84006.57996709978;16522.669546609002;16803.41188058412;16863.409780657617;17048.659026819394;17089.31642734277;28649.856750716113;28706.71293287082;28767;28949.42101158007;29140.708592914223;8374.246317831425;8658;8747.900503879024;9015.14356136166;9038.638454461749;6988.371046605728;7000.509964302551;7094.11323584552;7247.855042899112;7252.202257751748;8201.343892488587;8293.709720159766;8413.611754060512;8519.787005324792;8699.826003480022;3150.212446888278;3216.903492895217;3239.3197428539797;3245.448273793461;3323.93352132956;729.2088084428349;742.9331360177501;782.2803021220491;832.4256263178509;840.5755093677653;14604.488842890449;15140.697186056437;15437.30532126057;15717.371305147895;15864.509807842256;760.4106817216701;780.2470615855726;802.293981296467;828.1966492502263;884.6903583745637;8460.8730869037;8513.40406171562;8913.153250441197;9064.365494415459;9148.719179314901;396031;398301;398627;400112.9881362415;405253.2645429642;74875.62562187154;76500.61749691216;77325.61337193391;77713.61143194248;79726;855.6149732620221;880.3793325705362;882.5614248774584;950.9667161649309;952.4618590496348;15226.923865381046;15343.46297879569;15550.766738499158;16025.198740062717;16329.591760206089;39829;40126.99682507961;40367;40462.393064103475;40746.59253407546;51268;52456.475435246684;53008;53100;54130;6971.268016858156;7218.27817218274;7335.559866408033;7397.778066657794;7485.139208990818;9743.707688769347;11303.71740706456;11766.294022358687;11768.235064720766;11982.221155624857;9384.249260059043;9839.901600984185;10003.34978226442;10351;10511.26421150512;9918.801623967625;9926.702198934043;9960.452175130438;10159.695209143738;10237.692869213935;10140.087392134743;10344.741381465523;10368.274220804471;10509.264351495322;10891.237613366988;6400.743970241138;6652.368025037614;6662.233843108104;6947.7220911162985;7641.847163056819;7592;9765.658201962897;10341.4312212826;10564.788704226026;10587.135526472835 12:48:47 INFO - PROCESS | 3975 | |3;mootools.html;1215.9939200303997;1311.4360824845317;1364.3382959264757;1386.9445222191114;1694.9745753813695;6652.401283884449;7395.556266623999;7702.576358300298;7709.113451953027;7938.483998540105;7778.144404115543;7841.1374748777735;7847.136814950365;7852.37181025518;7861.449698521106;2103.8001389868014;2111.5565731196466;2127.1278775701967;2129.233475948657;2135;2065.865718728285;2098.3390232076845;2112.376848829599;2117.6188286108495;2126.436494329004;2006.1373609347975;2036.9898150509227;2042.7140200371884;2043.7036629688687;2043.785402532735;2056.4139220322136;2058.3721964800657;2062.2678948973125;2067.080149333547;2070.6583413736716;2042.4383294594106;2055.660815965364;2081.6149012432747;2083.447886310129;2101.464126647695;7593.278638529331;7689.577073260968;7742.922570774276;7780.02749656293;7784.104827944802;4264.637505812009;4400.735955842628;4400.855777497842;4405.801738921724;4406.955930440687;2666.5733482642877;2752.3944732158893;2792.1204820481485;2811.7750579953554;2822.548392257229;946.2146418472652;946.2950102173925;957.6743907071559;967.6371360739722;970.8592254123183;10398.584056637725;10483.633072842415;10500.107490863285;10502.159827213805;10511.264211505275;3777.1501412182038;3799.2781371539318;3927.9607203928167;3941.4087886817206;3944.7633142011573;8180.222878826502;8223.876641850376;8249.340052795762;8249.711260105867;8252.381071419668;4329.9134017319475;4338.457692788402;4343.04453020335;4345.217860785089;4345.521992580813;3343.6823501767294;3452.879149229765;3872.4191371294532;4040.2121586290978;4044.2315959967514;3263.298390845932;3291.4240007998505;3378.527006219131;3401.0817079388426;3402.6937575618313;1012.8430093335545;1015.6546774096843;1021.6526381030485;1022.3661329975463;1042.6559235452282;589.5136512377306;713.7275591413324;722.404016686236;732.9010583571256;740.822202671355;4755.476897541268;4834.347363106006;4854.296127061556;4965.801367945314;5021.363784013422;376.4409851370767;380.08210172433814;380.22054787685136;381.90452386903274;858.3090612057354;3371.831408429569;3443;3466.2374277658873;3467.6358982306997;3633.3459977204357;9424.528773561295;9566.473843938651;9621.230301575857;9630.47032413224;9671.758206044764;9510.239180865514;9575.712728618153;9579.042095790364;9579.90420095804;9596.472194029257;6603.933960660332;6835.658217089126;6843.623600702009;7017.7894663161205;7019;5357.604695115792;5436.40199578054;5473.863153421196;5483.327585086587;5623.212750215055;7836.138024817152;8031.718889838828;8081.191880811872;8616.439931404439;8945.955270223607;8258.25675689191;8752;8959.19367256924;9032.187103160484;9053.909460905306;5729.3984131665575;5921.230240068763;6357.078223657636;6422.42198202164;6491;6874.243833178246;7060.082189315356;7111.715531378687;7290;7296.452766042674;6369.267534233437;6413.679316034273;6542.54202205841;6592.967035164794;6654.367835055662;6505.21937367519;6519.4132528072705;6552.57408268461;6563.278039415756;6573.80278591643;6007.158997740233;6111.419415155553;6118.908216376669;6126.356732543019;6128.908066378919;4377.693561450796;4451.621612163002;4527.275635898241;4551.772411379352;4689.577937985598;4464.263396539664;4577.633789296915;4636.976815115903;4643.233866412004;4704.811807527661 12:48:47 INFO - PROCESS | 3975 | |4;prototype.html;284.4282991187713;289.41104851626943;294.5846356637141;299.5596473184421;299.7691777331456;4875.5124487551275;4888.853334399965;4914.901701965963;4917.033186725312;4917.877053073666;4784.97607511962;4843.757812109398;4847.830325938593;4849.321095046687;4856.562909338168;3575.570931488225;3712.21813529506;3726.77639341639;3797.069717919114;3827.655511004009;3803.6006219346987;3805.6194380562;3830;3830.5020347354944;3838.5201849768778;3787.3182827091114;3794.2221844521946;3794.620537946197;3828.3300422426105;3830.8467661293516;3574.160072382988;3669.541307336583;3703.2038111805823;3783.2622638585485;3814.179951310468;3341.766076374655;3344.464885618314;3347.748918831073;3349.2631621043333;3363.445031569788;4688.929666055012;4704.858854234379;4710.9764451177525;4713;4757.857264282077;4082.4692789937417;4321.286987647034;4345.304751239818;4354.673399495019;4362.738235705868;4989.126902792033;4991.675541089818;4994.950050499485;4996.4254110777;5032.446430892599;3629.1471504196493;3650.5984341722387;3654.3738036573736;3654.8172591370326;3655.670989610921;5092.643514953955;5264.315638966948;5292.682439053669;5300.455781225033;5305.257263983045;3693.1505753676806;3698.278835627027;3708.517892673935;3716.554013518395;3725.1059745660846;3754.6808521276;3767.6420740029653;3770.3401904666575;3776.5656949450618;3795.8291876865055;4267.0825772459075;4275.444192254987;4281.373078230253;4306.935395969063;4314.1371725655;4723.858284251478;4938.629602779806;4986.526280003394;5006.6245031622775;5016.749162541858;1298.8376452943382;1315.046591221376;1320.4586119691073;1320.504810695989;1326.907116501855;812.4191203289677;846.5174850335372;846.7459762071477;859.2181115185151;861.2248975921596;4275.8075886585175;4336.544662810423;4415.470143582726;4468.798904049326;4473.418455600751;5213.582913366921;5227.790888364499;5238.607104467104;5239.842804715865;5246.396664383645;4497.5052744198765;4502.257127573979;4504.954950450454;4508.430194789004;4519.5480451954545;2603.9974609774995;2757.9724202758116;2760.144355249879;2779.708130646253;2781.2768680142904;4876.780544875489;4881.4142302923165;4884.535969082932;4890.633202509826;4929.7781599828095;5151.845444636667;5332.173513105475;5334.146536554133;5348.839534813962;5350.892982140335;4855.393075865517;4919.262110683426;4932.8520144395725;4934.506549345037;4935.580475659537;5248.711320877388;5250.18622113573;5259.526642602184;5265.552428043659;5287.4976877196605;4945.6043516517875;5117.334746482933;5163.432022477599;5188.143956247177;5198.8960220796125;4923.276853101529;4933.038057578738;4933.925991110208;4937.23472861707;4947.282644016669;3734.730191734823;3748.925021499609;3770.698344132518;3771.773693578394;3786.6592006718424;4993.775280112331;5305.575553955751;5318.97340513295;5321.7227865312825;5332.6000549958135;5103.830871879511;5119.974400127976;5135.3324067870935;5140;5140.46025167367;5028.899422011613;5063.772130254074;5091.59267258611;5136.871578210575;5138.97430512845;4813.4945830687275;4969.329140566122;5008.297178959026;5015.924761128659;5047.722375269395;4822.71063736177;4971.08613183934;5084.694918304914;5100.744962751922;5108.821191258288;4850.957044235506;4975.900481990412;4990.675606085593;5003.874903127451;5007.173816320266 12:48:47 INFO - PROCESS | 3975 | |5;yui.html;527.970961597112;538.8006437618081;551.6745120378976;552.5110277404498;626.4236902050114;11232.10143188545;12034.879651203486;12041.458134383953;12045.879541204586;12052.457639406237;11013;11041.889581104186;11093.500792464338;11906.236267028888;12240.632781016562;589.2781342854989;740.148828846825;760.6006846405654;762.6148794858589;764.2243123229927;608.6895683201577;727.3817255332951;757.6211894052974;762.7864198024535;765.9119201291867;802.3019972623825;804.8189157439588;804.9637766300531;805.1545876829306;805.6092794994423;618.628822706377;801.2548330053048;801.3108726495238;801.7875263055295;801.9117897031351;470.1654563150401;558.522463293886;578.5949835115437;582.4059459351438;584.1208980484387;12699.555515557004;12722.236665800081;12733.808992865115;12737.681557961032;12747.681307967283;12337.69155771104;12569.245845249314;12907.806382904264;13147.934260328733;13368.447204968945;11175.273607215506;11333.553111357589;11422.657320280405;11612.18714689972;11781.293122412682;3963.7027222958395;3971.285168669638;4014.237294913957;4056.391541268804;4075.816588253536;64885.67557162231;65418.3458165417;65476;65476.672616637086;65543;10005.699829005142;10279.94860025695;10305.227107966932;10744.247902646739;10749.355038697704;19348.613027739368;19366;19482.707759383622;19622.21511139567;19653.31213407524;5778.393268706809;5828.388019257917;6372.681365931685;6484.675766211671;6647.4349680276755;3507.596626387947;3551.786892786441;3575.964240357563;3593.5328407306883;3625.2024554597947;2588.1588483742858;2662.973370266311;2684.3825920038503;2687.704352521221;2704.418550011718;448.3610854532279;449.42024788023735;457.8260261100827;464.9418822647169;468;444.74427204357625;482.8816939849759;491.9286703427983;493.20347638563464;506.5365190850404;6929.133858267716;6983.882578787369;7196.64016799158;7401.962990185015;7415.369693575999;411.27410121136217;423.73728288460734;444.6842741653462;457.6247477068838;460.13035363163647;3464.705500032475;3470.16715988161;3514.4376899695926;3550.4496802995577;3655.853765849389;56606.716966414904;58641.41358586445;73668;76093;77199.61400192963;77520;77596.61201693956;78453.01657767917;81141.99999999882;82832;42160.57839421566;58684;67048.6647566759;67243.32756672468;69343;7739.922600774145;8074.475159114639;8265.628046738031;8327.708530201415;8382.16178382157;36153.819230903675;40579;40869.38695919504;40992.385114222714;41064.76805695834;60135.69932150311;60560.394396057236;60729.39270607237;61256;62383;10380.48097595102;10710.196735245043;10787.514561844579;10851.457427128455;11120.499577519193;9548.427094374081;9617.374870633386;9751.609935602497;10865.565377385132;11553.595624153115;9246.907530924873;9304.255659547356;9731.513424328898;9922.454265015205;10892.891071089505;11192;11473.254238474472;11563.6530904073;12052.251190116336;12331.260124392566;11406.258593191416;11638.53445862156;11650;11651;11862.644120676394;8769.736907892773;8911.643534258557;9120.954395227982;9184.770380740534;9353.25173986093;9180.6786762463;9592.280578956746;9612.855807162758;9630.32587718853;9644.469554174308 12:48:47 INFO - PROCESS | 3975 | __end_tp_report 12:48:47 INFO - PROCESS | 3975 | __start_cc_report 12:48:47 INFO - PROCESS | 3975 | _x_x_mozilla_cycle_collect,1629 12:48:47 INFO - PROCESS | 3975 | __end_cc_report 12:48:47 INFO - PROCESS | 3975 | __startTimestamp1474141727533__endTimestamp 12:48:47 INFO - PROCESS | 3975 | 12:48:47 INFO - PROCESS | 3975 | ------- Summary: start ------- 12:48:47 INFO - PROCESS | 3975 | Number of tests: 6 12:48:47 INFO - PROCESS | 3975 | 12:48:47 INFO - PROCESS | 3975 | [#0] dojo.html Cycles:165 Average:4667.54 Median:4787.59 stddev:481.96 (10.1%) stddev-sans-first:454.16 12:48:47 INFO - PROCESS | 3975 | Values: 2558.4 2588.9 2615.0 2615.5 2637.1 4576.4 4632.4 4740.7 4760.8 4782.3 4677.9 4701.6 4708.2 4718.5 4751.1 4311.7 4335.4 4354.6 4354.8 4426.7 4353.4 4383.2 4394.5 4420.9 4435.9 4207.2 4249.3 4341.0 4357.0 4377.3 4279.9 4300.3 4317.8 4350.8 4411.4 4308.6 4324.2 4383.0 4383.0 4394.5 4699.3 4707.2 4718.6 4735.1 4786.3 4903.6 4953.4 4994.4 5033.5 5037.7 5050.2 5078.3 5121.0 5139.5 5139.8 4735.7 4737.9 4745.1 4745.3 4804.6 5053.0 5166.6 5177.9 5181.1 5192.4 4877.2 4897.8 4920.3 4923.7 4938.2 4992.8 5003.4 5010.8 5076.3 5139.6 5000.0 5004.7 5031.9 5049.5 5100.1 4853.8 4901.9 4997.1 5010.0 5022.6 4373.5 4375.9 4387.0 4389.1 4445.4 3797.1 3807.4 3838.6 3852.9 3869.5 4261.0 4305.2 4346.5 4355.3 4390.0 5036.6 5038.3 5082.7 5097.9 5115.0 4350.9 4388.8 4394.9 4397.6 4414.7 4437.3 4479.5 4632.9 4638.3 4684.9 4684.9 4716.2 4733.4 4743.0 4807.0 5048.6 5053.3 5061.8 5062.2 5119.0 4613.3 4726.0 4735.6 4741.1 4792.9 4879.1 4964.6 4994.3 5013.8 5085.1 5082.7 5090.2 5092.9 5093.6 5148.6 4864.4 4953.7 4960.1 4960.8 4974.7 4818.2 4838.4 4847.2 4862.9 4891.0 4787.6 4799.3 4852.4 4862.7 4865.5 4857.5 4897.5 4903.6 4906.5 4964.2 4834.7 4836.2 4837.4 4844.3 4845.7 12:48:47 INFO - PROCESS | 3975 | 12:48:47 INFO - PROCESS | 3975 | [#1] ext.html Cycles:180 Average:44445.23 Median:17873.05 stddev:79971.24 (447.4%) stddev-sans-first:80127.71 12:48:47 INFO - PROCESS | 3975 | Values: 562.2 564.6 575.8 577.4 579.4 28039.4 28818.7 38766.6 39790.6 39873.8 39996.8 40421.8 40430.4 40434.8 40442.8 7554.2 7721.7 7725.2 7728.4 7730.9 7278.1 7486.6 7536.0 9851.7 10117.7 10205.6 10264.8 10278.7 10279.8 10281.9 10068.0 10117.7 10132.3 10137.0 10142.2 10044.7 10113.7 10116.3 10119.2 10120.9 37947.0 38272.0 38284.8 38287.8 38391.4 2743.8 2761.9 2832.4 3097.7 3104.2 28537.9 28843.0 28909.0 28947.1 29043.3 3207.4 3225.7 3262.8 3281.7 3332.8 245245.0 245261.0 245412.0 245869.0 246241.0 32483.4 33657.5 51741.5 52055.5 52097.0 31329.4 31589.1 31645.5 31681.4 31688.8 11432.9 11471.4 11493.5 11502.3 11518.1 8916.3 10257.4 10381.9 10382.8 10383.6 7216.5 7509.7 7749.7 7786.3 7871.1 3007.2 3056.4 3084.4 3085.6 3123.1 778.5 845.3 854.1 858.2 935.6 27574.9 27717.6 28576.7 29699.4 30010.7 901.4 914.8 925.6 936.0 962.6 14115.7 14743.3 15094.6 15854.1 16115.4 363156.0 364223.0 365117.0 365532.0 366296.0 48216.5 48770.5 48840.0 48926.0 48957.8 1037.8 1058.3 1127.2 1166.3 1170.8 25448.1 28615.5 29453.6 29875.7 30058.8 40802.0 40969.0 41279.8 41549.8 41602.8 288397.0 289741.0 289772.0 289805.0 290211.0 24323.3 25042.2 25217.4 25253.5 26434.3 40778.4 41015.0 41196.8 41362.2 42118.4 35484.3 35718.1 35826.3 35909.3 36202.8 34692.0 35626.0 35786.3 36075.3 36172.0 34068.3 34637.5 34882.8 35273.0 35304.8 30853.5 32622.8 32726.5 33141.7 33447.2 24300.3 24388.8 25115.4 25424.9 25559.2 12:48:47 INFO - PROCESS | 3975 | 12:48:47 INFO - PROCESS | 3975 | [#2] jquery.html Cycles:180 Average:25943.02 Median:4949.32 stddev:66087.64 (1335.3%) stddev-sans-first:66245.07 12:48:47 INFO - PROCESS | 3975 | Values: 334.6 353.6 357.6 360.0 384.9 10954.7 15656.6 16107.7 16526.3 17125.7 16198.7 16463.9 16590.3 16796.3 16876.3 4851.7 4945.5 4953.2 5003.8 5170.8 3666.5 4953.8 5029.0 5032.1 5037.3 3816.7 5589.9 5942.6 5973.4 5991.0 5934.6 6110.2 6114.3 6665.7 6987.3 6850.3 6855.4 6861.5 6864.0 6868.1 22177.4 23210.0 23411.3 23718.4 24076.2 1849.5 1859.9 1889.1 1926.0 2150.0 13566.9 13919.6 14159.9 14435.6 15482.1 1641.6 1695.4 1789.2 1802.3 1842.9 78738.0 78777.6 80503.0 81214.6 84006.6 16522.7 16803.4 16863.4 17048.7 17089.3 28649.9 28706.7 28767.0 28949.4 29140.7 8374.2 8658.0 8747.9 9015.1 9038.6 6988.4 7000.5 7094.1 7247.9 7252.2 8201.3 8293.7 8413.6 8519.8 8699.8 3150.2 3216.9 3239.3 3245.4 3323.9 729.2 742.9 782.3 832.4 840.6 14604.5 15140.7 15437.3 15717.4 15864.5 760.4 780.2 802.3 828.2 884.7 8460.9 8513.4 8913.2 9064.4 9148.7 396031.0 398301.0 398627.0 400113.0 405253.3 74875.6 76500.6 77325.6 77713.6 79726.0 855.6 880.4 882.6 951.0 952.5 15226.9 15343.5 15550.8 16025.2 16329.6 39829.0 40127.0 40367.0 40462.4 40746.6 51268.0 52456.5 53008.0 53100.0 54130.0 6971.3 7218.3 7335.6 7397.8 7485.1 9743.7 11303.7 11766.3 11768.2 11982.2 9384.2 9839.9 10003.3 10351.0 10511.3 9918.8 9926.7 9960.5 10159.7 10237.7 10140.1 10344.7 10368.3 10509.3 10891.2 6400.7 6652.4 6662.2 6947.7 7641.8 7592.0 9765.7 10341.4 10564.8 10587.1 12:48:47 INFO - PROCESS | 3975 | 12:48:47 INFO - PROCESS | 3975 | [#3] mootools.html Cycles:180 Average:4936.39 Median:4844.32 stddev:2828.39 (58.4%) stddev-sans-first:2822.51 12:48:47 INFO - PROCESS | 3975 | Values: 1216.0 1311.4 1364.3 1386.9 1695.0 6652.4 7395.6 7702.6 7709.1 7938.5 7778.1 7841.1 7847.1 7852.4 7861.4 2103.8 2111.6 2127.1 2129.2 2135.0 2065.9 2098.3 2112.4 2117.6 2126.4 2006.1 2037.0 2042.7 2043.7 2043.8 2056.4 2058.4 2062.3 2067.1 2070.7 2042.4 2055.7 2081.6 2083.4 2101.5 7593.3 7689.6 7742.9 7780.0 7784.1 4264.6 4400.7 4400.9 4405.8 4407.0 2666.6 2752.4 2792.1 2811.8 2822.5 946.2 946.3 957.7 967.6 970.9 10398.6 10483.6 10500.1 10502.2 10511.3 3777.2 3799.3 3928.0 3941.4 3944.8 8180.2 8223.9 8249.3 8249.7 8252.4 4329.9 4338.5 4343.0 4345.2 4345.5 3343.7 3452.9 3872.4 4040.2 4044.2 3263.3 3291.4 3378.5 3401.1 3402.7 1012.8 1015.7 1021.7 1022.4 1042.7 589.5 713.7 722.4 732.9 740.8 4755.5 4834.3 4854.3 4965.8 5021.4 376.4 380.1 380.2 381.9 858.3 3371.8 3443.0 3466.2 3467.6 3633.3 9424.5 9566.5 9621.2 9630.5 9671.8 9510.2 9575.7 9579.0 9579.9 9596.5 6603.9 6835.7 6843.6 7017.8 7019.0 5357.6 5436.4 5473.9 5483.3 5623.2 7836.1 8031.7 8081.2 8616.4 8946.0 8258.3 8752.0 8959.2 9032.2 9053.9 5729.4 5921.2 6357.1 6422.4 6491.0 6874.2 7060.1 7111.7 7290.0 7296.5 6369.3 6413.7 6542.5 6593.0 6654.4 6505.2 6519.4 6552.6 6563.3 6573.8 6007.2 6111.4 6118.9 6126.4 6128.9 4377.7 4451.6 4527.3 4551.8 4689.6 4464.3 4577.6 4637.0 4643.2 4704.8 12:48:47 INFO - PROCESS | 3975 | 12:48:47 INFO - PROCESS | 3975 | [#4] prototype.html Cycles:180 Average:4214.35 Median:4848.58 stddev:1222.45 (25.2%) stddev-sans-first:1189.75 12:48:47 INFO - PROCESS | 3975 | Values: 284.4 289.4 294.6 299.6 299.8 4875.5 4888.9 4914.9 4917.0 4917.9 4785.0 4843.8 4847.8 4849.3 4856.6 3575.6 3712.2 3726.8 3797.1 3827.7 3803.6 3805.6 3830.0 3830.5 3838.5 3787.3 3794.2 3794.6 3828.3 3830.8 3574.2 3669.5 3703.2 3783.3 3814.2 3341.8 3344.5 3347.7 3349.3 3363.4 4688.9 4704.9 4711.0 4713.0 4757.9 4082.5 4321.3 4345.3 4354.7 4362.7 4989.1 4991.7 4995.0 4996.4 5032.4 3629.1 3650.6 3654.4 3654.8 3655.7 5092.6 5264.3 5292.7 5300.5 5305.3 3693.2 3698.3 3708.5 3716.6 3725.1 3754.7 3767.6 3770.3 3776.6 3795.8 4267.1 4275.4 4281.4 4306.9 4314.1 4723.9 4938.6 4986.5 5006.6 5016.7 1298.8 1315.0 1320.5 1320.5 1326.9 812.4 846.5 846.7 859.2 861.2 4275.8 4336.5 4415.5 4468.8 4473.4 5213.6 5227.8 5238.6 5239.8 5246.4 4497.5 4502.3 4505.0 4508.4 4519.5 2604.0 2758.0 2760.1 2779.7 2781.3 4876.8 4881.4 4884.5 4890.6 4929.8 5151.8 5332.2 5334.1 5348.8 5350.9 4855.4 4919.3 4932.9 4934.5 4935.6 5248.7 5250.2 5259.5 5265.6 5287.5 4945.6 5117.3 5163.4 5188.1 5198.9 4923.3 4933.0 4933.9 4937.2 4947.3 3734.7 3748.9 3770.7 3771.8 3786.7 4993.8 5305.6 5319.0 5321.7 5332.6 5103.8 5120.0 5135.3 5140.0 5140.5 5028.9 5063.8 5091.6 5136.9 5139.0 4813.5 4969.3 5008.3 5015.9 5047.7 4822.7 4971.1 5084.7 5100.7 5108.8 4851.0 4975.9 4990.7 5003.9 5007.2 12:48:47 INFO - PROCESS | 3975 | 12:48:47 INFO - PROCESS | 3975 | [#5] yui.html Cycles:180 Average:16087.38 Median:476.52 stddev:22027.72 (4622.6%) stddev-sans-first:22058.53 12:48:47 INFO - PROCESS | 3975 | Values: 528.0 538.8 551.7 552.5 626.4 11232.1 12034.9 12041.5 12045.9 12052.5 11013.0 11041.9 11093.5 11906.2 12240.6 589.3 740.1 760.6 762.6 764.2 608.7 727.4 757.6 762.8 765.9 802.3 804.8 805.0 805.2 805.6 618.6 801.3 801.3 801.8 801.9 470.2 558.5 578.6 582.4 584.1 12699.6 12722.2 12733.8 12737.7 12747.7 12337.7 12569.2 12907.8 13147.9 13368.4 11175.3 11333.6 11422.7 11612.2 11781.3 3963.7 3971.3 4014.2 4056.4 4075.8 64885.7 65418.3 65476.0 65476.7 65543.0 10005.7 10279.9 10305.2 10744.2 10749.4 19348.6 19366.0 19482.7 19622.2 19653.3 5778.4 5828.4 6372.7 6484.7 6647.4 3507.6 3551.8 3576.0 3593.5 3625.2 2588.2 2663.0 2684.4 2687.7 2704.4 448.4 449.4 457.8 464.9 468.0 444.7 482.9 491.9 493.2 506.5 6929.1 6983.9 7196.6 7402.0 7415.4 411.3 423.7 444.7 457.6 460.1 3464.7 3470.2 3514.4 3550.4 3655.9 56606.7 58641.4 73668.0 76093.0 77199.6 77520.0 77596.6 78453.0 81142.0 82832.0 42160.6 58684.0 67048.7 67243.3 69343.0 7739.9 8074.5 8265.6 8327.7 8382.2 36153.8 40579.0 40869.4 40992.4 41064.8 60135.7 60560.4 60729.4 61256.0 62383.0 10380.5 10710.2 10787.5 10851.5 11120.5 9548.4 9617.4 9751.6 10865.6 11553.6 9246.9 9304.3 9731.5 9922.5 10892.9 11192.0 11473.3 11563.7 12052.3 12331.3 11406.3 11638.5 11650.0 11651.0 11862.6 8769.7 8911.6 9121.0 9184.8 9353.3 9180.7 9592.3 9612.9 9630.3 9644.5 12:48:47 INFO - PROCESS | 3975 | -------- Summary: end -------- 12:48:47 INFO - PROCESS | 3975 | 12:48:48 INFO - TEST-INFO | 3975: exit 0 12:48:48 INFO - TEST-OK | dromaeo_css | took 1121415ms 12:48:48 INFO - TEST-START | kraken 12:48:48 INFO - operating with platform_type : linux_ 12:48:48 INFO - Initialising browser for kraken test... 12:48:48 INFO - TEST-INFO | started process 4278 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjIfAv6/profile http://localhost:44782/getInfo.html) 12:48:53 INFO - PROCESS | 4278 | __metrics Screen width/height:1600/1200 12:48:53 INFO - PROCESS | 4278 | colorDepth:24 12:48:53 INFO - PROCESS | 4278 | Browser inner width/height: 1024/697 12:48:53 INFO - PROCESS | 4278 | __metrics 12:48:53 INFO - PROCESS | 4278 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:48:53 INFO - PROCESS | 4278 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:48:54 INFO - PROCESS | 4278 | console.error: PushService: 12:48:54 INFO - PROCESS | 4278 | stateChangeProcessEnqueue: Error transitioning state 12:48:54 INFO - PROCESS | 4278 | UnknownError 12:48:54 INFO - TEST-INFO | 4278: exit 0 12:48:54 INFO - Browser initialized. 12:48:54 INFO - Running cycle 1/1 for kraken test... 12:48:54 INFO - TEST-INFO | started process 4360 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjIfAv6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:49:07 INFO - PROCESS | 4360 | RSS: Main: 174686208 12:49:07 INFO - PROCESS | 4360 | 12:49:09 INFO - PROCESS | 4360 | Cycle 1(1): loaded http://localhost:44782/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44782/tests/kraken/driver.html?testName=audio-beat-detection) 12:49:10 INFO - PROCESS | 4360 | RSS: Main: 233086976 12:49:10 INFO - PROCESS | 4360 | 12:49:13 INFO - PROCESS | 4360 | Cycle 1(1): loaded http://localhost:44782/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44782/tests/kraken/driver.html?testName=audio-dft) 12:49:14 INFO - PROCESS | 4360 | RSS: Main: 217616384 12:49:14 INFO - PROCESS | 4360 | 12:49:17 INFO - PROCESS | 4360 | Cycle 1(1): loaded http://localhost:44782/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44782/tests/kraken/driver.html?testName=audio-fft) 12:49:18 INFO - PROCESS | 4360 | RSS: Main: 299831296 12:49:18 INFO - PROCESS | 4360 | 12:49:20 INFO - PROCESS | 4360 | Cycle 1(1): loaded http://localhost:44782/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44782/tests/kraken/driver.html?testName=audio-oscillator) 12:49:20 INFO - PROCESS | 4360 | RSS: Main: 209154048 12:49:20 INFO - PROCESS | 4360 | 12:49:23 INFO - PROCESS | 4360 | Cycle 1(1): loaded http://localhost:44782/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44782/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:49:23 INFO - PROCESS | 4360 | RSS: Main: 212819968 12:49:23 INFO - PROCESS | 4360 | 12:49:26 INFO - PROCESS | 4360 | Cycle 1(1): loaded http://localhost:44782/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44782/tests/kraken/driver.html?testName=imaging-darkroom) 12:49:27 INFO - PROCESS | 4360 | RSS: Main: 249630720 12:49:27 INFO - PROCESS | 4360 | 12:49:31 INFO - PROCESS | 4360 | Cycle 1(1): loaded http://localhost:44782/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44782/tests/kraken/driver.html?testName=imaging-desaturate) 12:49:31 INFO - PROCESS | 4360 | RSS: Main: 243879936 12:49:31 INFO - PROCESS | 4360 | 12:49:34 INFO - PROCESS | 4360 | Cycle 1(1): loaded http://localhost:44782/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44782/tests/kraken/driver.html?testName=json-parse-financial) 12:49:35 INFO - PROCESS | 4360 | RSS: Main: 246939648 12:49:35 INFO - PROCESS | 4360 | 12:49:36 INFO - PROCESS | 4360 | Cycle 1(1): loaded http://localhost:44782/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44782/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:49:37 INFO - PROCESS | 4360 | RSS: Main: 210407424 12:49:37 INFO - PROCESS | 4360 | 12:49:39 INFO - PROCESS | 4360 | Cycle 1(1): loaded http://localhost:44782/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44782/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:49:40 INFO - PROCESS | 4360 | RSS: Main: 218329088 12:49:40 INFO - PROCESS | 4360 | 12:49:42 INFO - PROCESS | 4360 | Cycle 1(1): loaded http://localhost:44782/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44782/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:49:42 INFO - PROCESS | 4360 | RSS: Main: 221986816 12:49:42 INFO - PROCESS | 4360 | 12:49:45 INFO - PROCESS | 4360 | Cycle 1(1): loaded http://localhost:44782/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44782/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:49:46 INFO - PROCESS | 4360 | RSS: Main: 223617024 12:49:46 INFO - PROCESS | 4360 | 12:49:49 INFO - PROCESS | 4360 | Cycle 1(1): loaded http://localhost:44782/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44782/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:49:49 INFO - PROCESS | 4360 | RSS: Main: 221175808 12:49:49 INFO - PROCESS | 4360 | 12:49:51 INFO - PROCESS | 4360 | Cycle 1(1): loaded http://localhost:44782/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44782/tests/kraken/driver.html?testName=ai-astar) 12:49:51 INFO - PROCESS | 4360 | RSS: Main: 272781312 12:49:51 INFO - PROCESS | 4360 | 12:49:51 INFO - PROCESS | 4360 | __start_tp_report 12:49:51 INFO - PROCESS | 4360 | _x_x_mozilla_page_load 12:49:51 INFO - PROCESS | 4360 | _x_x_mozilla_page_load_details 12:49:51 INFO - PROCESS | 4360 | |i|pagename|runs| 12:49:51 INFO - PROCESS | 4360 | |0;ai-astar;115;115;116;115;115;116;115;114;115;114 12:49:51 INFO - PROCESS | 4360 | |1;audio-beat-detection;158;126;129;166;127;127;125;128;170;128 12:49:51 INFO - PROCESS | 4360 | |2;audio-dft;163;164;162;163;161;160;162;161;163;163 12:49:51 INFO - PROCESS | 4360 | |3;audio-fft;76;78;76;108;77;78;77;77;108;79 12:49:51 INFO - PROCESS | 4360 | |4;audio-oscillator;85;83;131;84;84;85;106;84;83;111 12:49:51 INFO - PROCESS | 4360 | |5;imaging-gaussian-blur;118;118;117;117;117;118;118;118;117;117 12:49:51 INFO - PROCESS | 4360 | |6;imaging-darkroom;147;147;147;146;147;148;147;147;147;147 12:49:51 INFO - PROCESS | 4360 | |7;imaging-desaturate;95;95;95;95;95;95;94;95;95;95 12:49:51 INFO - PROCESS | 4360 | |8;json-parse-financial;65;63;64;63;64;66;65;65;73;63 12:49:51 INFO - PROCESS | 4360 | |9;json-stringify-tinderbox;66;66;66;66;66;67;65;66;65;66 12:49:51 INFO - PROCESS | 4360 | |10;stanford-crypto-aes;83;81;81;82;82;82;81;82;81;81 12:49:51 INFO - PROCESS | 4360 | |11;stanford-crypto-ccm;134;132;141;139;146;132;144;137;177;132 12:49:51 INFO - PROCESS | 4360 | |12;stanford-crypto-pbkdf2;175;170;170;170;170;175;173;172;174;169 12:49:51 INFO - PROCESS | 4360 | |13;stanford-crypto-sha256-iterative;68;72;68;69;73;68;68;67;68;66 12:49:51 INFO - PROCESS | 4360 | __end_tp_report 12:49:51 INFO - PROCESS | 4360 | __start_cc_report 12:49:51 INFO - PROCESS | 4360 | _x_x_mozilla_cycle_collect,1926 12:49:51 INFO - PROCESS | 4360 | __end_cc_report 12:49:51 INFO - PROCESS | 4360 | __startTimestamp1474141791875__endTimestamp 12:49:51 INFO - PROCESS | 4360 | 12:49:51 INFO - PROCESS | 4360 | ------- Summary: start ------- 12:49:51 INFO - PROCESS | 4360 | Number of tests: 14 12:49:51 INFO - PROCESS | 4360 | 12:49:51 INFO - PROCESS | 4360 | [#0] ai-astar Cycles:10 Average:115.00 Median:115.00 stddev:0.67 (0.6%) stddev-sans-first:0.71 12:49:51 INFO - PROCESS | 4360 | Values: 115.0 115.0 116.0 115.0 115.0 116.0 115.0 114.0 115.0 114.0 12:49:51 INFO - PROCESS | 4360 | 12:49:51 INFO - PROCESS | 4360 | [#1] audio-beat-detection Cycles:10 Average:138.40 Median:128.50 stddev:18.39 (14.3%) stddev-sans-first:18.08 12:49:51 INFO - PROCESS | 4360 | Values: 158.0 126.0 129.0 166.0 127.0 127.0 125.0 128.0 170.0 128.0 12:49:51 INFO - PROCESS | 4360 | 12:49:51 INFO - PROCESS | 4360 | [#2] audio-dft Cycles:10 Average:162.20 Median:163.00 stddev:1.23 (0.8%) stddev-sans-first:1.27 12:49:51 INFO - PROCESS | 4360 | Values: 163.0 164.0 162.0 163.0 161.0 160.0 162.0 161.0 163.0 163.0 12:49:51 INFO - PROCESS | 4360 | 12:49:51 INFO - PROCESS | 4360 | [#3] audio-fft Cycles:10 Average:83.40 Median:77.00 stddev:13.00 (16.9%) stddev-sans-first:13.51 12:49:51 INFO - PROCESS | 4360 | Values: 76.0 78.0 76.0 108.0 77.0 78.0 77.0 77.0 108.0 79.0 12:49:51 INFO - PROCESS | 4360 | 12:49:51 INFO - PROCESS | 4360 | [#4] audio-oscillator Cycles:10 Average:93.60 Median:84.00 stddev:16.68 (19.9%) stddev-sans-first:17.40 12:49:51 INFO - PROCESS | 4360 | Values: 85.0 83.0 131.0 84.0 84.0 85.0 106.0 84.0 83.0 111.0 12:49:51 INFO - PROCESS | 4360 | 12:49:51 INFO - PROCESS | 4360 | [#5] imaging-gaussian-blur Cycles:10 Average:117.50 Median:118.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 12:49:51 INFO - PROCESS | 4360 | Values: 118.0 118.0 117.0 117.0 117.0 118.0 118.0 118.0 117.0 117.0 12:49:51 INFO - PROCESS | 4360 | 12:49:51 INFO - PROCESS | 4360 | [#6] imaging-darkroom Cycles:10 Average:147.00 Median:147.00 stddev:0.47 (0.3%) stddev-sans-first:0.50 12:49:51 INFO - PROCESS | 4360 | Values: 147.0 147.0 147.0 146.0 147.0 148.0 147.0 147.0 147.0 147.0 12:49:51 INFO - PROCESS | 4360 | 12:49:51 INFO - PROCESS | 4360 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 12:49:51 INFO - PROCESS | 4360 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 94.0 95.0 95.0 95.0 12:49:51 INFO - PROCESS | 4360 | 12:49:51 INFO - PROCESS | 4360 | [#8] json-parse-financial Cycles:10 Average:65.10 Median:65.00 stddev:2.96 (4.6%) stddev-sans-first:3.14 12:49:51 INFO - PROCESS | 4360 | Values: 65.0 63.0 64.0 63.0 64.0 66.0 65.0 65.0 73.0 63.0 12:49:51 INFO - PROCESS | 4360 | 12:49:51 INFO - PROCESS | 4360 | [#9] json-stringify-tinderbox Cycles:10 Average:65.90 Median:66.00 stddev:0.57 (0.9%) stddev-sans-first:0.60 12:49:51 INFO - PROCESS | 4360 | Values: 66.0 66.0 66.0 66.0 66.0 67.0 65.0 66.0 65.0 66.0 12:49:51 INFO - PROCESS | 4360 | 12:49:51 INFO - PROCESS | 4360 | [#10] stanford-crypto-aes Cycles:10 Average:81.60 Median:82.00 stddev:0.70 (0.9%) stddev-sans-first:0.53 12:49:51 INFO - PROCESS | 4360 | Values: 83.0 81.0 81.0 82.0 82.0 82.0 81.0 82.0 81.0 81.0 12:49:51 INFO - PROCESS | 4360 | 12:49:51 INFO - PROCESS | 4360 | [#11] stanford-crypto-ccm Cycles:10 Average:141.40 Median:140.00 stddev:13.50 (9.6%) stddev-sans-first:14.05 12:49:51 INFO - PROCESS | 4360 | Values: 134.0 132.0 141.0 139.0 146.0 132.0 144.0 137.0 177.0 132.0 12:49:51 INFO - PROCESS | 4360 | 12:49:51 INFO - PROCESS | 4360 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.80 Median:172.50 stddev:2.30 (1.3%) stddev-sans-first:2.13 12:49:51 INFO - PROCESS | 4360 | Values: 175.0 170.0 170.0 170.0 170.0 175.0 173.0 172.0 174.0 169.0 12:49:51 INFO - PROCESS | 4360 | 12:49:51 INFO - PROCESS | 4360 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.70 Median:68.00 stddev:2.16 (3.2%) stddev-sans-first:2.28 12:49:51 INFO - PROCESS | 4360 | Values: 68.0 72.0 68.0 69.0 73.0 68.0 68.0 67.0 68.0 66.0 12:49:51 INFO - PROCESS | 4360 | -------- Summary: end -------- 12:49:51 INFO - PROCESS | 4360 | 12:49:53 INFO - TEST-INFO | 4360: exit 0 12:49:53 INFO - TEST-OK | kraken | took 64584ms 12:49:53 INFO - SUITE-END | took 1185s 12:49:53 INFO - Completed test suite (00:19:46) 12:49:53 INFO - javascript benchmark 12:49:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2558.4243545202326, 2588.8576128312934, 2615.0, 2615.5292047431467, 2637.1297471834287, 4576.405067341241, 4632.374629425027, 4740.715557066575, 4760.761961901904, 4782.258749893767, 4677.929831052528, 4701.576858082772, 4708.199606066968, 4718.504557021515, 4751.097291514609, 4311.698181127323, 4335.371371151181, 4354.629856462205, 4354.804033818486, 4426.667999900004, 4353.368761529577, 4383.232934236496, 4394.494633117184, 4420.9336859947125, 4435.933460998088, 4207.200631879933, 4249.298865687158, 4340.956590434087, 4357.041450880801, 4377.321515165155, 4279.91440171198, 4300.268954277781, 4317.76252306123, 4350.7607081610495, 4411.448568928884, 4308.633766129883, 4324.200022995725, 4382.978085109586, 4383.035732138925, 4394.516603173648, 4699.295105734133, 4707.223308154151, 4718.598919091882, 4735.076660051292, 4786.282057691375, 4903.583195428393, 4953.356063711731, 4994.400671919357, 5033.546980771748, 5037.672551284155, 5050.191969284933, 5078.289039534468, 5120.974395128001, 5139.486051394868, 5139.7687104080405, 4735.692180008288, 4737.928931066037, 4745.074710431432, 4745.335653008581, 4804.639652026112, 5053.0, 5166.638335316492, 5177.948220517746, 5181.119209734355, 5192.376914770176, 4877.170880950247, 4897.8285759998225, 4920.3111564381015, 4923.655344125948, 4938.160512712848, 4992.750362481861, 5003.424606170337, 5010.849674509771, 5076.289319495274, 5139.614528910347, 4999.975000124976, 5004.699718016931, 5031.949680503221, 5049.495050494995, 5100.1074811907765, 4853.757312134449, 4901.877453063702, 4997.125503036954, 5009.9729555441045, 5022.648414610942, 4373.518912919576, 4375.890602734893, 4387.0, 4389.144116897238, 4445.377647129404, 3797.0697179191275, 3807.3908174691915, 3838.596947320547, 3852.9229415411537, 3869.4582758413844, 4261.041265715189, 4305.225059489322, 4346.456692913386, 4355.346697995326, 4390.034192477649, 5036.64743467961, 5038.269450929668, 5082.669626474268, 5097.949020509747, 5114.974425127851, 4350.912981740411, 4388.802503887333, 4394.868153955386, 4397.626201772821, 4414.690971632019, 4437.29003359461, 4479.484859241229, 4632.930506042412, 4638.257878739386, 4684.906301873943, 4684.882877928079, 4716.221823399101, 4733.408323959505, 4742.976285118552, 4807.0, 5048.570871475966, 5053.29253904461, 5061.822836200715, 5062.190049592047, 5118.97440512795, 4613.331066995334, 4725.952740472689, 4735.644826638085, 4741.051789642154, 4792.856214313576, 4879.146149423907, 4964.578010868972, 4994.275830004702, 5013.774380152975, 5085.084684756632, 5082.669626474268, 5090.2364645303505, 5092.898142037213, 5093.617978651542, 5148.562372198405, 4864.416270047617, 4953.72754498506, 4960.107180707508, 4960.801567937242, 4974.651774375759, 4818.204996175592, 4838.371011768448, 4847.151748444078, 4862.902741945212, 4890.975545122252, 4787.593054590398, 4799.3041009052795, 4852.369192005017, 4862.659613826998, 4865.513448655106, 4857.53853383928, 4897.4857639947295, 4903.5586797188425, 4906.533879281463, 4964.180910149929, 4834.685745426536, 4836.202026665561, 4837.419509658864, 4844.346013288161, 4845.685030473008], "value": 4636.794144391155, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [562.1707980728426, 564.5878508688658, 575.792714622736, 577.4139248662608, 579.3916387792816, 28039.439211215762, 28818.711812881866, 38766.612333876685, 39790.602093979054, 39873.800630996804, 39996.80001599995, 40421.7978910105, 40430.39354409686, 40434.7978260109, 40442.797786011106, 7554.169041405455, 7721.72973945912, 7725.150233474314, 7728.381729461629, 7730.884036739453, 7278.126624805031, 7486.62566871657, 7535.9623201883915, 9851.704448866545, 10117.747056323577, 10205.642802501914, 10264.794704105914, 10278.691639250834, 10279.794404111914, 10281.897181028206, 10068.0, 10117.747056323577, 10132.290739648228, 10137.0, 10142.239332050125, 10044.748881277954, 10113.69658910234, 10116.342437741523, 10119.190464762874, 10120.898791012069, 37947.0, 38272.0, 38284.80857595722, 38287.808560957295, 38391.42412863809, 2743.8353698778137, 2761.8619069046667, 2832.391035927265, 3097.7366923811505, 3104.1929098434343, 28537.857310713312, 28843.0, 28909.0, 28947.131586052452, 29043.273918152005, 3207.3571793510796, 3225.70968612826, 3262.755293353008, 3281.6718328166994, 3332.8333583320737, 245245.0, 245261.0, 245412.0, 245869.0, 246241.0, 32483.350332993206, 33657.49513757296, 51741.48258517442, 52055.47944520582, 52097.0, 31329.373412532077, 31589.052328430185, 31645.52531712026, 31681.36637267242, 31688.841555792074, 11432.94283528577, 11471.369074700804, 11493.540258389738, 11502.274658801247, 11518.136139789549, 8916.286697064219, 10257.384556926609, 10381.896181038244, 10382.844257336146, 10383.636572719919, 7216.494845360774, 7509.662065207079, 7749.690012399441, 7786.299233069051, 7871.0948240951875, 3007.173027417478, 3056.388722255513, 3084.352286019916, 3085.5525948737754, 3123.094302652251, 778.5056489129444, 845.3321875718236, 854.1159899503983, 858.2490320969152, 935.6070450410843, 27574.862125689244, 27717.58423623647, 28576.714232857405, 29699.40601187964, 30010.699893001227, 901.3915606965272, 914.7941713114496, 925.6112432778115, 936.0452338614712, 962.552413127899, 14115.71768564623, 14743.262836858328, 15094.622634434227, 15854.128022958848, 16115.355385784438, 363156.0, 364223.0, 365117.0, 365532.0, 366296.0, 48216.5178348212, 48770.512294877306, 48840.0, 48926.0, 48957.75521122372, 1037.8339465685453, 1058.3068090400955, 1127.2053202492386, 1166.2535976974743, 1170.8302296167008, 25448.109316173846, 28615.520940674873, 29453.558196627495, 29875.701242987292, 30058.83793822305, 40802.0, 40969.0, 41279.7936010318, 41549.79225103976, 41602.791986041084, 288397.0, 289741.0, 289771.98306245054, 289805.0, 290211.0, 24323.27030189097, 25042.248732538053, 25217.369565761004, 25253.49493010093, 26434.339141521617, 40778.388324174564, 41015.0, 41196.79401602973, 41362.172756545304, 42118.36822447727, 35484.29031419409, 35718.107047324025, 35826.28347433089, 35909.28181436409, 36202.81898590596, 34692.0, 35626.0, 35786.28427431489, 36075.27849443049, 36172.0, 34068.318633626695, 34637.48043779396, 34882.825585871906, 35273.0, 35304.82347588348, 30853.537196942514, 32622.83688581542, 32726.50910236301, 33141.680082394, 33447.16382090467, 24300.27099187027, 24388.75611243865, 25115.372115696522, 25424.872875635505, 25559.23322300334], "value": 16343.006574922223, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [334.5984818218136, 353.63398882156974, 357.61020487668435, 359.9514065601143, 384.90185002824273, 10954.726131846697, 15656.608584785372, 16107.677846443079, 16526.25631846567, 17125.657486850254, 16198.676026479492, 16463.91768041158, 16590.336386544524, 16796.32814687411, 16876.324947002136, 4851.708897466146, 4945.455999840015, 4953.182724850404, 5003.799848006075, 5170.793168273283, 3666.50502182206, 4953.777080031389, 5029.0, 5032.11937910866, 5037.3451451311275, 3816.656500914917, 5589.8602534936745, 5942.5840191186635, 5973.432523910222, 5991.0, 5934.643921364732, 6110.175126357929, 6114.327423983381, 6665.700043498055, 6987.266337034589, 6850.280720524373, 6855.383015528577, 6861.451083913275, 6864.0, 6868.107146071029, 22177.44556386087, 23210.0, 23411.297661070195, 23718.40703982397, 24076.157334493208, 1849.4636555398918, 1859.8605104617072, 1889.1404410992966, 1926.0466069295649, 2149.9895049326947, 13566.864331356659, 13919.582412527641, 14159.85840141606, 14435.566932992027, 15482.148481833494, 1641.606014556498, 1695.440504633468, 1789.2216885654652, 1802.3331367394017, 1842.9262829486804, 78738.0, 78777.60611196965, 80503.0, 81214.59392702999, 84006.57996709978, 16522.669546609002, 16803.41188058412, 16863.409780657617, 17048.659026819394, 17089.31642734277, 28649.856750716113, 28706.71293287082, 28767.0, 28949.42101158007, 29140.708592914223, 8374.246317831425, 8658.0, 8747.900503879024, 9015.14356136166, 9038.638454461749, 6988.371046605728, 7000.509964302551, 7094.11323584552, 7247.855042899112, 7252.202257751748, 8201.343892488587, 8293.709720159766, 8413.611754060512, 8519.787005324792, 8699.826003480022, 3150.212446888278, 3216.903492895217, 3239.3197428539797, 3245.448273793461, 3323.93352132956, 729.2088084428349, 742.9331360177501, 782.2803021220491, 832.4256263178509, 840.5755093677653, 14604.488842890449, 15140.697186056437, 15437.30532126057, 15717.371305147895, 15864.509807842256, 760.4106817216701, 780.2470615855726, 802.293981296467, 828.1966492502263, 884.6903583745637, 8460.8730869037, 8513.40406171562, 8913.153250441197, 9064.365494415459, 9148.719179314901, 396031.0, 398301.0, 398627.0, 400112.9881362415, 405253.2645429642, 74875.62562187154, 76500.61749691216, 77325.61337193391, 77713.61143194248, 79726.0, 855.6149732620221, 880.3793325705362, 882.5614248774584, 950.9667161649309, 952.4618590496348, 15226.923865381046, 15343.46297879569, 15550.766738499158, 16025.198740062717, 16329.591760206089, 39829.0, 40126.99682507961, 40367.0, 40462.393064103475, 40746.59253407546, 51268.0, 52456.475435246684, 53008.0, 53100.0, 54130.0, 6971.268016858156, 7218.27817218274, 7335.559866408033, 7397.778066657794, 7485.139208990818, 9743.707688769347, 11303.71740706456, 11766.294022358687, 11768.235064720766, 11982.221155624857, 9384.249260059043, 9839.901600984185, 10003.34978226442, 10351.0, 10511.26421150512, 9918.801623967625, 9926.702198934043, 9960.452175130438, 10159.695209143738, 10237.692869213935, 10140.087392134743, 10344.741381465523, 10368.274220804471, 10509.264351495322, 10891.237613366988, 6400.743970241138, 6652.368025037614, 6662.233843108104, 6947.7220911162985, 7641.847163056819, 7592.0, 9765.658201962897, 10341.4312212826, 10564.788704226026, 10587.135526472835], "value": 8946.39444300899, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1215.9939200303997, 1311.4360824845317, 1364.3382959264757, 1386.9445222191114, 1694.9745753813695, 6652.401283884449, 7395.556266623999, 7702.576358300298, 7709.113451953027, 7938.483998540105, 7778.144404115543, 7841.1374748777735, 7847.136814950365, 7852.37181025518, 7861.449698521106, 2103.8001389868014, 2111.5565731196466, 2127.1278775701967, 2129.233475948657, 2135.0, 2065.865718728285, 2098.3390232076845, 2112.376848829599, 2117.6188286108495, 2126.436494329004, 2006.1373609347975, 2036.9898150509227, 2042.7140200371884, 2043.7036629688687, 2043.785402532735, 2056.4139220322136, 2058.3721964800657, 2062.2678948973125, 2067.080149333547, 2070.6583413736716, 2042.4383294594106, 2055.660815965364, 2081.6149012432747, 2083.447886310129, 2101.464126647695, 7593.278638529331, 7689.577073260968, 7742.922570774276, 7780.02749656293, 7784.104827944802, 4264.637505812009, 4400.735955842628, 4400.855777497842, 4405.801738921724, 4406.955930440687, 2666.5733482642877, 2752.3944732158893, 2792.1204820481485, 2811.7750579953554, 2822.548392257229, 946.2146418472652, 946.2950102173925, 957.6743907071559, 967.6371360739722, 970.8592254123183, 10398.584056637725, 10483.633072842415, 10500.107490863285, 10502.159827213805, 10511.264211505275, 3777.1501412182038, 3799.2781371539318, 3927.9607203928167, 3941.4087886817206, 3944.7633142011573, 8180.222878826502, 8223.876641850376, 8249.340052795762, 8249.711260105867, 8252.381071419668, 4329.9134017319475, 4338.457692788402, 4343.04453020335, 4345.217860785089, 4345.521992580813, 3343.6823501767294, 3452.879149229765, 3872.4191371294532, 4040.2121586290978, 4044.2315959967514, 3263.298390845932, 3291.4240007998505, 3378.527006219131, 3401.0817079388426, 3402.6937575618313, 1012.8430093335545, 1015.6546774096843, 1021.6526381030485, 1022.3661329975463, 1042.6559235452282, 589.5136512377306, 713.7275591413324, 722.404016686236, 732.9010583571256, 740.822202671355, 4755.476897541268, 4834.347363106006, 4854.296127061556, 4965.801367945314, 5021.363784013422, 376.4409851370767, 380.08210172433814, 380.22054787685136, 381.90452386903274, 858.3090612057354, 3371.831408429569, 3443.0, 3466.2374277658873, 3467.6358982306997, 3633.3459977204357, 9424.528773561295, 9566.473843938651, 9621.230301575857, 9630.47032413224, 9671.758206044764, 9510.239180865514, 9575.712728618153, 9579.042095790364, 9579.90420095804, 9596.472194029257, 6603.933960660332, 6835.658217089126, 6843.623600702009, 7017.7894663161205, 7019.0, 5357.604695115792, 5436.40199578054, 5473.863153421196, 5483.327585086587, 5623.212750215055, 7836.138024817152, 8031.718889838828, 8081.191880811872, 8616.439931404439, 8945.955270223607, 8258.25675689191, 8752.0, 8959.19367256924, 9032.187103160484, 9053.909460905306, 5729.3984131665575, 5921.230240068763, 6357.078223657636, 6422.42198202164, 6491.0, 6874.243833178246, 7060.082189315356, 7111.715531378687, 7290.0, 7296.452766042674, 6369.267534233437, 6413.679316034273, 6542.54202205841, 6592.967035164794, 6654.367835055662, 6505.21937367519, 6519.4132528072705, 6552.57408268461, 6563.278039415756, 6573.80278591643, 6007.158997740233, 6111.419415155553, 6118.908216376669, 6126.356732543019, 6128.908066378919, 4377.693561450796, 4451.621612163002, 4527.275635898241, 4551.772411379352, 4689.577937985598, 4464.263396539664, 4577.633789296915, 4636.976815115903, 4643.233866412004, 4704.811807527661], "value": 3894.7855914796037, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [284.4282991187713, 289.41104851626943, 294.5846356637141, 299.5596473184421, 299.7691777331456, 4875.5124487551275, 4888.853334399965, 4914.901701965963, 4917.033186725312, 4917.877053073666, 4784.97607511962, 4843.757812109398, 4847.830325938593, 4849.321095046687, 4856.562909338168, 3575.570931488225, 3712.21813529506, 3726.77639341639, 3797.069717919114, 3827.655511004009, 3803.6006219346987, 3805.6194380562, 3830.0, 3830.5020347354944, 3838.5201849768778, 3787.3182827091114, 3794.2221844521946, 3794.620537946197, 3828.3300422426105, 3830.8467661293516, 3574.160072382988, 3669.541307336583, 3703.2038111805823, 3783.2622638585485, 3814.179951310468, 3341.766076374655, 3344.464885618314, 3347.748918831073, 3349.2631621043333, 3363.445031569788, 4688.929666055012, 4704.858854234379, 4710.9764451177525, 4713.0, 4757.857264282077, 4082.4692789937417, 4321.286987647034, 4345.304751239818, 4354.673399495019, 4362.738235705868, 4989.126902792033, 4991.675541089818, 4994.950050499485, 4996.4254110777, 5032.446430892599, 3629.1471504196493, 3650.5984341722387, 3654.3738036573736, 3654.8172591370326, 3655.670989610921, 5092.643514953955, 5264.315638966948, 5292.682439053669, 5300.455781225033, 5305.257263983045, 3693.1505753676806, 3698.278835627027, 3708.517892673935, 3716.554013518395, 3725.1059745660846, 3754.6808521276, 3767.6420740029653, 3770.3401904666575, 3776.5656949450618, 3795.8291876865055, 4267.0825772459075, 4275.444192254987, 4281.373078230253, 4306.935395969063, 4314.1371725655, 4723.858284251478, 4938.629602779806, 4986.526280003394, 5006.6245031622775, 5016.749162541858, 1298.8376452943382, 1315.046591221376, 1320.4586119691073, 1320.504810695989, 1326.907116501855, 812.4191203289677, 846.5174850335372, 846.7459762071477, 859.2181115185151, 861.2248975921596, 4275.8075886585175, 4336.544662810423, 4415.470143582726, 4468.798904049326, 4473.418455600751, 5213.582913366921, 5227.790888364499, 5238.607104467104, 5239.842804715865, 5246.396664383645, 4497.5052744198765, 4502.257127573979, 4504.954950450454, 4508.430194789004, 4519.5480451954545, 2603.9974609774995, 2757.9724202758116, 2760.144355249879, 2779.708130646253, 2781.2768680142904, 4876.780544875489, 4881.4142302923165, 4884.535969082932, 4890.633202509826, 4929.7781599828095, 5151.845444636667, 5332.173513105475, 5334.146536554133, 5348.839534813962, 5350.892982140335, 4855.393075865517, 4919.262110683426, 4932.8520144395725, 4934.506549345037, 4935.580475659537, 5248.711320877388, 5250.18622113573, 5259.526642602184, 5265.552428043659, 5287.4976877196605, 4945.6043516517875, 5117.334746482933, 5163.432022477599, 5188.143956247177, 5198.8960220796125, 4923.276853101529, 4933.038057578738, 4933.925991110208, 4937.23472861707, 4947.282644016669, 3734.730191734823, 3748.925021499609, 3770.698344132518, 3771.773693578394, 3786.6592006718424, 4993.775280112331, 5305.575553955751, 5318.97340513295, 5321.7227865312825, 5332.6000549958135, 5103.830871879511, 5119.974400127976, 5135.3324067870935, 5140.0, 5140.46025167367, 5028.899422011613, 5063.772130254074, 5091.59267258611, 5136.871578210575, 5138.97430512845, 4813.4945830687275, 4969.329140566122, 5008.297178959026, 5015.924761128659, 5047.722375269395, 4822.71063736177, 4971.08613183934, 5084.694918304914, 5100.744962751922, 5108.821191258288, 4850.957044235506, 4975.900481990412, 4990.675606085593, 5003.874903127451, 5007.173816320266], "value": 3822.9355846037593, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [527.970961597112, 538.8006437618081, 551.6745120378976, 552.5110277404498, 626.4236902050114, 11232.10143188545, 12034.879651203486, 12041.458134383953, 12045.879541204586, 12052.457639406237, 11013.0, 11041.889581104186, 11093.500792464338, 11906.236267028888, 12240.632781016562, 589.2781342854989, 740.148828846825, 760.6006846405654, 762.6148794858589, 764.2243123229927, 608.6895683201577, 727.3817255332951, 757.6211894052974, 762.7864198024535, 765.9119201291867, 802.3019972623825, 804.8189157439588, 804.9637766300531, 805.1545876829306, 805.6092794994423, 618.628822706377, 801.2548330053048, 801.3108726495238, 801.7875263055295, 801.9117897031351, 470.1654563150401, 558.522463293886, 578.5949835115437, 582.4059459351438, 584.1208980484387, 12699.555515557004, 12722.236665800081, 12733.808992865115, 12737.681557961032, 12747.681307967283, 12337.69155771104, 12569.245845249314, 12907.806382904264, 13147.934260328733, 13368.447204968945, 11175.273607215506, 11333.553111357589, 11422.657320280405, 11612.18714689972, 11781.293122412682, 3963.7027222958395, 3971.285168669638, 4014.237294913957, 4056.391541268804, 4075.816588253536, 64885.67557162231, 65418.3458165417, 65476.0, 65476.672616637086, 65543.0, 10005.699829005142, 10279.94860025695, 10305.227107966932, 10744.247902646739, 10749.355038697704, 19348.613027739368, 19366.0, 19482.707759383622, 19622.21511139567, 19653.31213407524, 5778.393268706809, 5828.388019257917, 6372.681365931685, 6484.675766211671, 6647.4349680276755, 3507.596626387947, 3551.786892786441, 3575.964240357563, 3593.5328407306883, 3625.2024554597947, 2588.1588483742858, 2662.973370266311, 2684.3825920038503, 2687.704352521221, 2704.418550011718, 448.3610854532279, 449.42024788023735, 457.8260261100827, 464.9418822647169, 468.0, 444.74427204357625, 482.8816939849759, 491.9286703427983, 493.20347638563464, 506.5365190850404, 6929.133858267716, 6983.882578787369, 7196.64016799158, 7401.962990185015, 7415.369693575999, 411.27410121136217, 423.73728288460734, 444.6842741653462, 457.6247477068838, 460.13035363163647, 3464.705500032475, 3470.16715988161, 3514.4376899695926, 3550.4496802995577, 3655.853765849389, 56606.716966414904, 58641.41358586445, 73668.0, 76093.0, 77199.61400192963, 77520.0, 77596.61201693956, 78453.01657767917, 81141.99999999882, 82832.0, 42160.57839421566, 58684.0, 67048.6647566759, 67243.32756672468, 69343.0, 7739.922600774145, 8074.475159114639, 8265.628046738031, 8327.708530201415, 8382.16178382157, 36153.819230903675, 40579.0, 40869.38695919504, 40992.385114222714, 41064.76805695834, 60135.69932150311, 60560.394396057236, 60729.39270607237, 61256.0, 62383.0, 10380.48097595102, 10710.196735245043, 10787.514561844579, 10851.457427128455, 11120.499577519193, 9548.427094374081, 9617.374870633386, 9751.609935602497, 10865.565377385132, 11553.595624153115, 9246.907530924873, 9304.255659547356, 9731.513424328898, 9922.454265015205, 10892.891071089505, 11192.0, 11473.254238474472, 11563.6530904073, 12052.251190116336, 12331.260124392566, 11406.258593191416, 11638.53445862156, 11650.0, 11651.0, 11862.644120676394, 8769.736907892773, 8911.643534258557, 9120.954395227982, 9184.770380740534, 9353.25173986093, 9180.6786762463, 9592.280578956746, 9612.855807162758, 9630.32587718853, 9644.469554174308], "value": 6084.295638081718, "unit": "score"}], "name": "dromaeo_css", "value": 6281.400892217982, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 115.0, 116.0, 115.0, 115.0, 116.0, 115.0, 114.0, 115.0, 114.0], "value": 115.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 126.0, 129.0, 166.0, 127.0, 127.0, 125.0, 128.0, 170.0, 128.0], "value": 138.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 164.0, 162.0, 163.0, 161.0, 160.0, 162.0, 161.0, 163.0, 163.0], "value": 162.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 78.0, 76.0, 108.0, 77.0, 78.0, 77.0, 77.0, 108.0, 79.0], "value": 83.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 131.0, 84.0, 84.0, 85.0, 106.0, 84.0, 83.0, 111.0], "value": 93.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 117.0, 117.0, 117.0, 118.0, 118.0, 118.0, 117.0, 117.0], "value": 117.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 147.0, 146.0, 147.0, 148.0, 147.0, 147.0, 147.0, 147.0], "value": 147.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 63.0, 64.0, 63.0, 64.0, 66.0, 65.0, 65.0, 73.0, 63.0], "value": 65.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 66.0, 66.0, 67.0, 65.0, 66.0, 65.0, 66.0], "value": 65.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 81.0, 81.0, 82.0, 82.0, 82.0, 81.0, 82.0, 81.0, 81.0], "value": 81.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 132.0, 141.0, 139.0, 146.0, 132.0, 144.0, 137.0, 177.0, 132.0], "value": 141.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 170.0, 170.0, 170.0, 170.0, 175.0, 173.0, 172.0, 174.0, 169.0], "value": 171.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 72.0, 68.0, 69.0, 73.0, 68.0, 68.0, 67.0, 68.0, 66.0], "value": 68.7, "unit": "score"}], "name": "kraken", "value": 1546.5, "alertThreshold": 2.0}]} 12:49:53 INFO - Return code: 0 12:49:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:49:53 INFO - # TBPL SUCCESS # 12:49:53 INFO - Running post-action listener: _resource_record_post_action 12:49:53 INFO - [mozharness: 2016-09-17 19:49:53.667500Z] Finished run-tests step (success) 12:49:53 INFO - Running post-run listener: _resource_record_post_run 12:49:53 INFO - Total resource usage - Wall time: 1203s; CPU: 13.0%; Read bytes: 4096; Write bytes: 389660672; Read time: 16; Write time: 289868 12:49:53 INFO - TinderboxPrint: CPU usage
13.1% 12:49:53 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 12:49:53 INFO - TinderboxPrint: I/O write bytes / time
389,660,672 / 289,868 12:49:53 INFO - TinderboxPrint: CPU idle
8,344.5 (86.9%) 12:49:53 INFO - TinderboxPrint: CPU user
1,202.5 (12.5%) 12:49:53 INFO - TinderboxPrint: Swap in / out
0 / 0 12:49:53 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 98942976; Read time: 0; Write time: 54428 12:49:53 INFO - run-tests - Wall time: 1187s; CPU: 13.0%; Read bytes: 0; Write bytes: 290717696; Read time: 0; Write time: 235440 12:49:54 INFO - Running post-run listener: _upload_blobber_files 12:49:54 INFO - Blob upload gear active. 12:49:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:49:54 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 12:49:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:49:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:49:54 INFO - (blobuploader) - INFO - Open directory for files ... 12:49:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:49:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:49:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:49:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:49:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:49:55 INFO - (blobuploader) - INFO - Done attempting. 12:49:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:49:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:49:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:49:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:49:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:49:58 INFO - (blobuploader) - INFO - Done attempting. 12:49:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:49:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:49:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:49:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:49:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:49:59 INFO - (blobuploader) - INFO - Done attempting. 12:49:59 INFO - (blobuploader) - INFO - Iteration through files over. 12:49:59 INFO - Return code: 0 12:49:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:49:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:49:59 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d5741f7a957bdc3bd3cb8878da763f18319c1157a51db42316bd0f576807e10287752d1afa5ad724cde965f8e680806a9784ba962ec2a60565afb8e7acc1e944", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8299025c0bb5f34800ebf3aa93b7a5562c988a04ce8d23b5dbd726d07f00c3fee086ebfe14e8bdfee6c8941897030dd7d872d1a99f4234dc1aab43307325f790", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/df0ac74a2a868b1905348908f314820f7f8f306ba1c1a872a1b1e8ade2e209d04e8565ecedf4e272dce5e983087380a803160dad3644d3bf35281e92d2eb3bad"} 12:49:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:49:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:49:59 INFO - Contents: 12:49:59 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d5741f7a957bdc3bd3cb8878da763f18319c1157a51db42316bd0f576807e10287752d1afa5ad724cde965f8e680806a9784ba962ec2a60565afb8e7acc1e944", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8299025c0bb5f34800ebf3aa93b7a5562c988a04ce8d23b5dbd726d07f00c3fee086ebfe14e8bdfee6c8941897030dd7d872d1a99f4234dc1aab43307325f790", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/df0ac74a2a868b1905348908f314820f7f8f306ba1c1a872a1b1e8ade2e209d04e8565ecedf4e272dce5e983087380a803160dad3644d3bf35281e92d2eb3bad"} 12:49:59 INFO - Running post-run listener: copy_logs_to_upload_dir 12:49:59 INFO - Copying logs to upload dir... 12:49:59 INFO - mkdir: /builds/slave/test/build/upload/logs 12:49:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1250.405368 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-09-17 12:49:59.700395) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-17 12:49:59.704243) ========= 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=a0d2d2de893abaa8702a9954000001a7-1474140206.36685-2348691 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d5741f7a957bdc3bd3cb8878da763f18319c1157a51db42316bd0f576807e10287752d1afa5ad724cde965f8e680806a9784ba962ec2a60565afb8e7acc1e944", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8299025c0bb5f34800ebf3aa93b7a5562c988a04ce8d23b5dbd726d07f00c3fee086ebfe14e8bdfee6c8941897030dd7d872d1a99f4234dc1aab43307325f790", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/df0ac74a2a868b1905348908f314820f7f8f306ba1c1a872a1b1e8ade2e209d04e8565ecedf4e272dce5e983087380a803160dad3644d3bf35281e92d2eb3bad"} build_url:https://queue.taskcluster.net/v1/task/PeWQRu36QMSCcjb2rVnZbQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011180 build_url: 'https://queue.taskcluster.net/v1/task/PeWQRu36QMSCcjb2rVnZbQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d5741f7a957bdc3bd3cb8878da763f18319c1157a51db42316bd0f576807e10287752d1afa5ad724cde965f8e680806a9784ba962ec2a60565afb8e7acc1e944", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8299025c0bb5f34800ebf3aa93b7a5562c988a04ce8d23b5dbd726d07f00c3fee086ebfe14e8bdfee6c8941897030dd7d872d1a99f4234dc1aab43307325f790", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/df0ac74a2a868b1905348908f314820f7f8f306ba1c1a872a1b1e8ade2e209d04e8565ecedf4e272dce5e983087380a803160dad3644d3bf35281e92d2eb3bad"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-17 12:49:59.730594) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 12:49:59.730963) ========= 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=a0d2d2de893abaa8702a9954000001a7-1474140206.36685-2348691 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004448 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 12:49:59.813895) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-17 12:49:59.814266) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-17 12:49:59.814603) ========= ========= Total master_lag: 0.38 =========