builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-034 starttime: 1474066270.81 results: success (0) buildid: 20160916143318 builduid: 5e1d64455ff1425695873febf4a7bbbc revision: b401cb17167b34c362eb819259effbb3c0979f59 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-16 15:51:10.809171) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-16 15:51:10.809678) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-16 15:51:10.810022) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-16 15:51:10.872484) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 15:51:10.872827) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=d6bbd613908ccac0c6c9cb47000001f7-1474065853.992838-1013234262 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-16 15:51:10-- 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% 47.0M=0s 2016-09-16 15:51:11 (47.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.165733 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 15:51:11.094704) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 15:51:11.095388) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=d6bbd613908ccac0c6c9cb47000001f7-1474065853.992838-1013234262 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017790 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 15:51:11.156914) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 15:51:11.157274) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b401cb17167b34c362eb819259effbb3c0979f59 --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 b401cb17167b34c362eb819259effbb3c0979f59 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=d6bbd613908ccac0c6c9cb47000001f7-1474065853.992838-1013234262 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-16 15:51:11,242 truncating revision to first 12 chars 2016-09-16 15:51:11,242 Setting DEBUG logging. 2016-09-16 15:51:11,242 attempt 1/10 2016-09-16 15:51:11,242 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b401cb17167b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-16 15:51:11,548 unpacking tar archive at: mozilla-central-b401cb17167b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.653009 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 15:51:11.833754) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-16 15:51:11.834127) ========= script_repo_revision: b401cb17167b34c362eb819259effbb3c0979f59 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-16 15:51:11.834621) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-16 15:51:11.834972) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-16 15:51:11.847911) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2016-09-16 15:51:11.848232) ========= /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=d6bbd613908ccac0c6c9cb47000001f7-1474065853.992838-1013234262 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:51:12 INFO - MultiFileLogger online at 20160916 15:51:12 in /builds/slave/test 15:51:12 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 15:51:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:51:12 INFO - {'append_to_log': False, 15:51:12 INFO - 'base_work_dir': '/builds/slave/test', 15:51:12 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 15:51:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:51:12 INFO - 'branch': 'Firefox-Non-PGO', 15:51:12 INFO - 'buildbot_json_path': 'buildprops.json', 15:51:12 INFO - 'config_files': ('talos/linux_config.py',), 15:51:12 INFO - 'default_actions': ('clobber', 15:51:12 INFO - 'read-buildbot-config', 15:51:12 INFO - 'download-and-extract', 15:51:12 INFO - 'populate-webroot', 15:51:12 INFO - 'create-virtualenv', 15:51:12 INFO - 'install', 15:51:12 INFO - 'run-tests'), 15:51:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:51:12 INFO - 'download_minidump_stackwalk': True, 15:51:12 INFO - 'download_symbols': 'ondemand', 15:51:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:51:12 INFO - 'tooltool.py': '/tools/tooltool.py', 15:51:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:51:12 INFO - '/tools/misc-python/virtualenv.py')}, 15:51:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:51:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:51:12 INFO - 'installer_path': 'installer.exe', 15:51:12 INFO - 'log_level': 'info', 15:51:12 INFO - 'log_name': 'talos', 15:51:12 INFO - 'log_to_console': True, 15:51:12 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:51:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:51:12 INFO - 'opt_config_files': (), 15:51:12 INFO - 'pip_index': False, 15:51:12 INFO - 'sps_profile': False, 15:51:12 INFO - 'sps_profile_interval': 0, 15:51:12 INFO - 'suite': 'dromaeojs', 15:51:12 INFO - 'system_bits': '32', 15:51:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:51:12 INFO - 'title': 'talos-linux64-ix-034', 15:51:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:51:12 INFO - 'use_talos_json': True, 15:51:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:51:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:51:12 INFO - 'work_dir': 'build'} 15:51:12 INFO - [mozharness: 2016-09-16 22:51:12.124610Z] Running clobber step. 15:51:12 INFO - Running pre-action listener: _resource_record_pre_action 15:51:12 INFO - Running main action method: clobber 15:51:12 INFO - rmtree: /builds/slave/test/build 15:51:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:51:12 INFO - Running post-action listener: _resource_record_post_action 15:51:12 INFO - [mozharness: 2016-09-16 22:51:12.551787Z] Finished clobber step (success) 15:51:12 INFO - [mozharness: 2016-09-16 22:51:12.551888Z] Running read-buildbot-config step. 15:51:12 INFO - Running pre-action listener: _resource_record_pre_action 15:51:12 INFO - Running main action method: read_buildbot_config 15:51:12 INFO - Using buildbot properties: 15:51:12 INFO - { 15:51:12 INFO - "project": "", 15:51:12 INFO - "product": "firefox", 15:51:12 INFO - "script_repo_revision": "production", 15:51:12 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 15:51:12 INFO - "repository": "", 15:51:12 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 15:51:12 INFO - "buildid": "20160916143318", 15:51:12 INFO - "pgo_build": "False", 15:51:12 INFO - "basedir": "/builds/slave/test", 15:51:12 INFO - "buildnumber": 742, 15:51:12 INFO - "slavename": "talos-linux64-ix-034", 15:51:12 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 15:51:12 INFO - "platform": "ubuntu64_hw", 15:51:12 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 15:51:12 INFO - "branch": "mozilla-central", 15:51:12 INFO - "repo_path": "mozilla-central", 15:51:12 INFO - "revision": "b401cb17167b34c362eb819259effbb3c0979f59", 15:51:12 INFO - "stage_platform": "linux64", 15:51:12 INFO - "builduid": "5e1d64455ff1425695873febf4a7bbbc", 15:51:12 INFO - "slavebuilddir": "test" 15:51:12 INFO - } 15:51:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/A3vR-t0sQlecNA8-Bm1aJw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 15:51:12 INFO - Running post-action listener: _resource_record_post_action 15:51:12 INFO - [mozharness: 2016-09-16 22:51:12.554366Z] Finished read-buildbot-config step (success) 15:51:12 INFO - [mozharness: 2016-09-16 22:51:12.554455Z] Running download-and-extract step. 15:51:12 INFO - Running pre-action listener: _resource_record_pre_action 15:51:12 INFO - Running main action method: download_and_extract 15:51:12 INFO - mkdir: /builds/slave/test/build/tests 15:51:12 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')]} 15:51:12 INFO - trying https://queue.taskcluster.net/v1/task/A3vR-t0sQlecNA8-Bm1aJw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 15:51:12 INFO - Downloading https://queue.taskcluster.net/v1/task/A3vR-t0sQlecNA8-Bm1aJw/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 15:51:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A3vR-t0sQlecNA8-Bm1aJw/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 15:51:13 INFO - Downloaded 1361 bytes. 15:51:13 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 15:51:13 INFO - Using the following test package requirements: 15:51:13 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 15:51:13 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:51:13 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 15:51:13 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:51:13 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 15:51:13 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:51:13 INFO - u'jsshell-linux-x86_64.zip'], 15:51:13 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:51:13 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 15:51:13 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 15:51:13 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:51:13 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 15:51:13 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:51:13 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 15:51:13 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:51:13 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 15:51:13 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:51:13 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 15:51:13 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:51:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/A3vR-t0sQlecNA8-Bm1aJw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 15:51:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A3vR-t0sQlecNA8-Bm1aJw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:51:13 INFO - Fetch https://queue.taskcluster.net/v1/task/A3vR-t0sQlecNA8-Bm1aJw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 15:51:13 INFO - Expected file size: 20365179 15:51:17 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 15:51:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/A3vR-t0sQlecNA8-Bm1aJw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 15:51:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A3vR-t0sQlecNA8-Bm1aJw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:51:17 INFO - Fetch https://queue.taskcluster.net/v1/task/A3vR-t0sQlecNA8-Bm1aJw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 15:51:18 INFO - Expected file size: 20365179 15:51:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/A3vR-t0sQlecNA8-Bm1aJw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 15:51:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A3vR-t0sQlecNA8-Bm1aJw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 15:51:23 INFO - Fetch https://queue.taskcluster.net/v1/task/A3vR-t0sQlecNA8-Bm1aJw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip into memory 15:51:24 INFO - Expected file size: 14414048 15:51:24 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')]} 15:51:24 INFO - trying https://queue.taskcluster.net/v1/task/A3vR-t0sQlecNA8-Bm1aJw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 15:51:24 INFO - Downloading https://queue.taskcluster.net/v1/task/A3vR-t0sQlecNA8-Bm1aJw/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 15:51:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A3vR-t0sQlecNA8-Bm1aJw/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 15:51:27 INFO - Downloaded 55593046 bytes. 15:51:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/A3vR-t0sQlecNA8-Bm1aJw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 15:51:27 INFO - mkdir: /builds/slave/test/properties 15:51:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:51:27 INFO - Writing to file /builds/slave/test/properties/build_url 15:51:27 INFO - Contents: 15:51:27 INFO - build_url:https://queue.taskcluster.net/v1/task/A3vR-t0sQlecNA8-Bm1aJw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 15:51:27 INFO - Running post-action listener: _resource_record_post_action 15:51:27 INFO - Running post-action listener: set_extra_try_arguments 15:51:27 INFO - [mozharness: 2016-09-16 22:51:27.791671Z] Finished download-and-extract step (success) 15:51:27 INFO - [mozharness: 2016-09-16 22:51:27.791898Z] Running populate-webroot step. 15:51:27 INFO - Running pre-action listener: _resource_record_pre_action 15:51:27 INFO - Running main action method: populate_webroot 15:51:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:51:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:51:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:51:27 INFO - '--tppagecycles', 15:51:27 INFO - '7'], 15:51:27 INFO - 'tests': ['tsvgm']}}, 15:51:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:51:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:51:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:51:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:51:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:51:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:51:27 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:51:27 INFO - 'talos_options': ['--disable-e10s'], 15:51:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:51:27 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:51:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:51:27 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:51:27 INFO - 'talos_options': ['--disable-e10s'], 15:51:27 INFO - 'tests': ['damp', 'tps']}, 15:51:27 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:51:27 INFO - 'tests': ['damp', 'tps']}, 15:51:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:51:27 INFO - 'tests': ['dromaeo_dom']}, 15:51:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:51:27 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:51:27 INFO - 'tests': ['basic_compositor_video']}, 15:51:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:51:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:51:27 INFO - 'tests': ['a11yr', 15:51:27 INFO - 'ts_paint', 15:51:27 INFO - 'tpaint', 15:51:27 INFO - 'sessionrestore', 15:51:27 INFO - 'sessionrestore_no_auto_restore', 15:51:27 INFO - 'tabpaint']}, 15:51:27 INFO - 'other-e10s': {'tests': ['a11yr', 15:51:27 INFO - 'ts_paint', 15:51:27 INFO - 'tpaint', 15:51:27 INFO - 'sessionrestore', 15:51:27 INFO - 'sessionrestore_no_auto_restore', 15:51:27 INFO - 'tabpaint']}, 15:51:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:51:27 INFO - 'ts_paint', 15:51:27 INFO - 'tpaint', 15:51:27 INFO - 'sessionrestore', 15:51:27 INFO - 'sessionrestore_no_auto_restore', 15:51:27 INFO - 'tabpaint']}, 15:51:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:51:27 INFO - 'ts_paint', 15:51:27 INFO - 'tpaint', 15:51:27 INFO - 'sessionrestore', 15:51:27 INFO - 'sessionrestore_no_auto_restore', 15:51:27 INFO - 'tabpaint']}, 15:51:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:51:27 INFO - 'tests': ['a11yr', 15:51:27 INFO - 'ts_paint', 15:51:27 INFO - 'tpaint', 15:51:27 INFO - 'sessionrestore', 15:51:27 INFO - 'sessionrestore_no_auto_restore', 15:51:27 INFO - 'tabpaint']}, 15:51:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:51:27 INFO - 'tests': ['a11yr', 15:51:27 INFO - 'ts_paint', 15:51:27 INFO - 'tpaint', 15:51:27 INFO - 'sessionrestore', 15:51:27 INFO - 'sessionrestore_no_auto_restore', 15:51:27 INFO - 'tabpaint']}, 15:51:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:51:27 INFO - 'tests': ['tsvgx', 15:51:27 INFO - 'tsvgr_opacity', 15:51:27 INFO - 'tart', 15:51:27 INFO - 'tscrollx', 15:51:27 INFO - 'cart']}, 15:51:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:51:27 INFO - 'tsvgr_opacity', 15:51:27 INFO - 'tart', 15:51:27 INFO - 'tscrollx', 15:51:27 INFO - 'cart']}, 15:51:27 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:51:27 INFO - 'talos_options': ['--disable-e10s'], 15:51:27 INFO - 'tests': ['tp5o']}, 15:51:27 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:51:27 INFO - 'tests': ['tp5o']}, 15:51:27 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:51:27 INFO - 'talos_options': ['--disable-e10s', 15:51:27 INFO - '--xperf_path', 15:51:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:51:27 INFO - 'tests': ['tp5n']}, 15:51:27 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:51:27 INFO - 'talos_options': ['--xperf_path', 15:51:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:51:27 INFO - 'tests': ['tp5n']}}, 15:51:27 INFO - 'talos.zip': {'path': '', 15:51:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:51:27 INFO - Running post-action listener: _resource_record_post_action 15:51:27 INFO - [mozharness: 2016-09-16 22:51:27.813421Z] Finished populate-webroot step (success) 15:51:27 INFO - [mozharness: 2016-09-16 22:51:27.813510Z] Running create-virtualenv step. 15:51:27 INFO - Running pre-action listener: _resource_record_pre_action 15:51:27 INFO - Running main action method: create_virtualenv 15:51:27 INFO - Creating virtualenv /builds/slave/test/build/venv 15:51:27 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 15:51:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:51:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:51:27 INFO - Using real prefix '/usr' 15:51:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:51:28 INFO - Installing distribute.............................................................................................................................................................................................done. 15:51:30 INFO - Installing pip.................done. 15:51:30 INFO - Return code: 0 15:51:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:51:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7d027c4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a40ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a3df60>, '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 0x2a3ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a3f1d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474065853.992838-1013234262', '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 15:51:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:51:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:51:30 INFO - Using env: {'DISPLAY': ':0', 15:51:30 INFO - 'HOME': '/home/cltbld', 15:51:30 INFO - 'LANG': 'en_US.UTF-8', 15:51:30 INFO - 'LANGUAGE': 'en_US:en', 15:51:30 INFO - 'LOGNAME': 'cltbld', 15:51:30 INFO - 'MAIL': '/var/mail/cltbld', 15:51:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:30 INFO - 'MOZ_NO_REMOTE': '1', 15:51:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:51:30 INFO - 'NO_EM_RESTART': '1', 15:51:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:51:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:30 INFO - 'PWD': '/builds/slave/test', 15:51:30 INFO - 'SHELL': '/bin/bash', 15:51:30 INFO - 'SHLVL': '1', 15:51:30 INFO - 'TERM': 'linux', 15:51:30 INFO - 'TMOUT': '86400', 15:51:30 INFO - 'USER': 'cltbld', 15:51:30 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474065853.992838-1013234262', 15:51:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:30 INFO - '_': '/tools/buildbot/bin/python'} 15:51:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:30 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 15:51:30 INFO - Cleaning up... 15:51:30 INFO - Return code: 0 15:51:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:51:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f7d027c4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a40ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a3df60>, '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 0x2a3ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a3f1d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474065853.992838-1013234262', '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 15:51:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:51:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:51:30 INFO - Using env: {'DISPLAY': ':0', 15:51:30 INFO - 'HOME': '/home/cltbld', 15:51:30 INFO - 'LANG': 'en_US.UTF-8', 15:51:30 INFO - 'LANGUAGE': 'en_US:en', 15:51:30 INFO - 'LOGNAME': 'cltbld', 15:51:30 INFO - 'MAIL': '/var/mail/cltbld', 15:51:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:30 INFO - 'MOZ_NO_REMOTE': '1', 15:51:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:51:30 INFO - 'NO_EM_RESTART': '1', 15:51:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:51:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:30 INFO - 'PWD': '/builds/slave/test', 15:51:30 INFO - 'SHELL': '/bin/bash', 15:51:30 INFO - 'SHLVL': '1', 15:51:30 INFO - 'TERM': 'linux', 15:51:30 INFO - 'TMOUT': '86400', 15:51:30 INFO - 'USER': 'cltbld', 15:51:30 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474065853.992838-1013234262', 15:51:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:30 INFO - '_': '/tools/buildbot/bin/python'} 15:51:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:31 INFO - Downloading/unpacking psutil>=3.1.1 15:51: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 15:51: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 15:51: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 15:51: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 15:51: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 15:51: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 15:51:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:51:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:51:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:51:33 INFO - Installing collected packages: psutil 15:51:33 INFO - Running setup.py install for psutil 15:51:33 INFO - building 'psutil._psutil_linux' extension 15:51: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 15:51: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 15:51:34 INFO - building 'psutil._psutil_posix' extension 15:51: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 15:51: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 15:51:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:51:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:51:34 INFO - Successfully installed psutil 15:51:34 INFO - Cleaning up... 15:51:34 INFO - Return code: 0 15:51:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:51: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')]} 15:51:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51: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')]} 15:51:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51: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 0x7f7d027c4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a40ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a3df60>, '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 0x2a3ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a3f1d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474065853.992838-1013234262', '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 15:51: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 15:51: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 15:51:34 INFO - Using env: {'DISPLAY': ':0', 15:51:34 INFO - 'HOME': '/home/cltbld', 15:51:34 INFO - 'LANG': 'en_US.UTF-8', 15:51:34 INFO - 'LANGUAGE': 'en_US:en', 15:51:34 INFO - 'LOGNAME': 'cltbld', 15:51:34 INFO - 'MAIL': '/var/mail/cltbld', 15:51:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:34 INFO - 'MOZ_NO_REMOTE': '1', 15:51:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:51:34 INFO - 'NO_EM_RESTART': '1', 15:51:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:51:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:34 INFO - 'PWD': '/builds/slave/test', 15:51:34 INFO - 'SHELL': '/bin/bash', 15:51:34 INFO - 'SHLVL': '1', 15:51:34 INFO - 'TERM': 'linux', 15:51:34 INFO - 'TMOUT': '86400', 15:51:34 INFO - 'USER': 'cltbld', 15:51:34 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474065853.992838-1013234262', 15:51:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:34 INFO - '_': '/tools/buildbot/bin/python'} 15:51:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:51: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 15:51: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 15:51: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 15:51: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 15:51: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 15:51: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 15:51:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:51:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:51:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:51:36 INFO - Installing collected packages: mozsystemmonitor 15:51:36 INFO - Running setup.py install for mozsystemmonitor 15:51:37 INFO - Successfully installed mozsystemmonitor 15:51:37 INFO - Cleaning up... 15:51:37 INFO - Return code: 0 15:51:37 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:51:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:37 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 0x7f7d027c4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a40ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a3df60>, '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 0x2a3ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a3f1d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474065853.992838-1013234262', '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 15:51:37 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 15:51:37 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 15:51:37 INFO - Using env: {'DISPLAY': ':0', 15:51:37 INFO - 'HOME': '/home/cltbld', 15:51:37 INFO - 'LANG': 'en_US.UTF-8', 15:51:37 INFO - 'LANGUAGE': 'en_US:en', 15:51:37 INFO - 'LOGNAME': 'cltbld', 15:51:37 INFO - 'MAIL': '/var/mail/cltbld', 15:51:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:37 INFO - 'MOZ_NO_REMOTE': '1', 15:51:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:51:37 INFO - 'NO_EM_RESTART': '1', 15:51:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:51:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:37 INFO - 'PWD': '/builds/slave/test', 15:51:37 INFO - 'SHELL': '/bin/bash', 15:51:37 INFO - 'SHLVL': '1', 15:51:37 INFO - 'TERM': 'linux', 15:51:37 INFO - 'TMOUT': '86400', 15:51:37 INFO - 'USER': 'cltbld', 15:51:37 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474065853.992838-1013234262', 15:51:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:37 INFO - '_': '/tools/buildbot/bin/python'} 15:51:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:37 INFO - Downloading/unpacking jsonschema==2.5.1 15:51:37 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 15:51:37 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 15:51:37 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 15:51:37 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 15:51:37 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 15:51:37 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 15:51:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:51:39 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:51: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 15:51: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 15:51: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 15:51: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 15:51: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 15:51: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 15:51:39 INFO - Downloading functools32-3.2.3-2.tar.gz 15:51:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:51:39 INFO - warning: no files found matching '*.txt' 15:51:39 INFO - no previously-included directories found matching 'build' 15:51:39 INFO - no previously-included directories found matching 'dist' 15:51:39 INFO - no previously-included directories found matching '.git*' 15:51:39 INFO - Installing collected packages: jsonschema, functools32 15:51:39 INFO - Running setup.py install for functools32 15:51:39 INFO - warning: no files found matching '*.txt' 15:51:39 INFO - no previously-included directories found matching 'build' 15:51:39 INFO - no previously-included directories found matching 'dist' 15:51:39 INFO - no previously-included directories found matching '.git*' 15:51:39 INFO - Successfully installed jsonschema functools32 15:51:39 INFO - Cleaning up... 15:51:39 INFO - Return code: 0 15:51:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:51: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')]} 15:51:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51: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')]} 15:51:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51: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 0x7f7d027c4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a40ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a3df60>, '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 0x2a3ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a3f1d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474065853.992838-1013234262', '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 15:51: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 15:51: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 15:51:39 INFO - Using env: {'DISPLAY': ':0', 15:51:39 INFO - 'HOME': '/home/cltbld', 15:51:39 INFO - 'LANG': 'en_US.UTF-8', 15:51:39 INFO - 'LANGUAGE': 'en_US:en', 15:51:39 INFO - 'LOGNAME': 'cltbld', 15:51:39 INFO - 'MAIL': '/var/mail/cltbld', 15:51:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:39 INFO - 'MOZ_NO_REMOTE': '1', 15:51:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:51:39 INFO - 'NO_EM_RESTART': '1', 15:51:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:51:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:39 INFO - 'PWD': '/builds/slave/test', 15:51:39 INFO - 'SHELL': '/bin/bash', 15:51:39 INFO - 'SHLVL': '1', 15:51:39 INFO - 'TERM': 'linux', 15:51:39 INFO - 'TMOUT': '86400', 15:51:39 INFO - 'USER': 'cltbld', 15:51:39 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474065853.992838-1013234262', 15:51:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:39 INFO - '_': '/tools/buildbot/bin/python'} 15:51:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:39 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:51:39 INFO - Cleaning up... 15:51:39 INFO - Return code: 0 15:51:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:51: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')]} 15:51:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51: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')]} 15:51:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51: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 0x7f7d027c4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a40ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a3df60>, '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 0x2a3ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a3f1d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474065853.992838-1013234262', '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 15:51: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 15:51: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 15:51:39 INFO - Using env: {'DISPLAY': ':0', 15:51:39 INFO - 'HOME': '/home/cltbld', 15:51:39 INFO - 'LANG': 'en_US.UTF-8', 15:51:39 INFO - 'LANGUAGE': 'en_US:en', 15:51:39 INFO - 'LOGNAME': 'cltbld', 15:51:39 INFO - 'MAIL': '/var/mail/cltbld', 15:51:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:39 INFO - 'MOZ_NO_REMOTE': '1', 15:51:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:51:39 INFO - 'NO_EM_RESTART': '1', 15:51:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:51:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:39 INFO - 'PWD': '/builds/slave/test', 15:51:39 INFO - 'SHELL': '/bin/bash', 15:51:39 INFO - 'SHLVL': '1', 15:51:39 INFO - 'TERM': 'linux', 15:51:39 INFO - 'TMOUT': '86400', 15:51:39 INFO - 'USER': 'cltbld', 15:51:39 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474065853.992838-1013234262', 15:51:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:39 INFO - '_': '/tools/buildbot/bin/python'} 15:51:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:40 INFO - Downloading/unpacking blobuploader==1.2.4 15:51:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:41 INFO - Downloading blobuploader-1.2.4.tar.gz 15:51:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:51:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:51: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 15:51: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 15:51: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 15:51: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 15:51: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 15:51: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 15:51:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:51:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:51: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 15:51: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 15:51: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 15:51: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 15:51: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 15:51: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 15:51:42 INFO - Downloading docopt-0.6.1.tar.gz 15:51:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:51:42 INFO - Installing collected packages: blobuploader, requests, docopt 15:51:42 INFO - Running setup.py install for blobuploader 15:51:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:51:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:51:42 INFO - Running setup.py install for requests 15:51:43 INFO - Running setup.py install for docopt 15:51:43 INFO - Successfully installed blobuploader requests docopt 15:51:43 INFO - Cleaning up... 15:51:43 INFO - Return code: 0 15:51:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:51: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')]} 15:51:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51: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')]} 15:51:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51: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 0x7f7d027c4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a40ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a3df60>, '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 0x2a3ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a3f1d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474065853.992838-1013234262', '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 15:51: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 15:51: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 15:51:43 INFO - Using env: {'DISPLAY': ':0', 15:51:43 INFO - 'HOME': '/home/cltbld', 15:51:43 INFO - 'LANG': 'en_US.UTF-8', 15:51:43 INFO - 'LANGUAGE': 'en_US:en', 15:51:43 INFO - 'LOGNAME': 'cltbld', 15:51:43 INFO - 'MAIL': '/var/mail/cltbld', 15:51:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:43 INFO - 'MOZ_NO_REMOTE': '1', 15:51:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:51:43 INFO - 'NO_EM_RESTART': '1', 15:51:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:51:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:43 INFO - 'PWD': '/builds/slave/test', 15:51:43 INFO - 'SHELL': '/bin/bash', 15:51:43 INFO - 'SHLVL': '1', 15:51:43 INFO - 'TERM': 'linux', 15:51:43 INFO - 'TMOUT': '86400', 15:51:43 INFO - 'USER': 'cltbld', 15:51:43 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474065853.992838-1013234262', 15:51:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:43 INFO - '_': '/tools/buildbot/bin/python'} 15:51:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:51:43 INFO - Running setup.py (path:/tmp/pip-IAWjWB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:51:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:51:43 INFO - Running setup.py (path:/tmp/pip-GX68yU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:51:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:51:43 INFO - Running setup.py (path:/tmp/pip-oJlaMi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:51:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:51:43 INFO - Running setup.py (path:/tmp/pip-WLaSbf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:51:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:51:43 INFO - Running setup.py (path:/tmp/pip-Tx2EXc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:51:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:51:43 INFO - Running setup.py (path:/tmp/pip-BPWKX0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:51:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:51:43 INFO - Running setup.py (path:/tmp/pip-T85YZM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:51:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:51:44 INFO - Running setup.py (path:/tmp/pip-Qgg9iv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:51:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:51:44 INFO - Running setup.py (path:/tmp/pip-wLBUUB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:51:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:51:44 INFO - Running setup.py (path:/tmp/pip-Qd7dHA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:51:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:51:44 INFO - Running setup.py (path:/tmp/pip-yp8JLP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:51:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:51:44 INFO - Running setup.py (path:/tmp/pip-YOpobR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:51:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:51:44 INFO - Running setup.py (path:/tmp/pip-8U1wSp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:51:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:51:44 INFO - Running setup.py (path:/tmp/pip-EMjFRR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:51:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:51:44 INFO - Running setup.py (path:/tmp/pip-eukEsh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:51:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:51:44 INFO - Running setup.py (path:/tmp/pip-H2MKny-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:51:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:51:44 INFO - Running setup.py (path:/tmp/pip-nbmG1F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:51:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:51:44 INFO - Running setup.py install for manifestparser 15:51:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:51:44 INFO - Running setup.py install for mozcrash 15:51:44 INFO - Running setup.py install for mozdebug 15:51:45 INFO - Running setup.py install for mozdevice 15:51:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:51:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:51:45 INFO - Running setup.py install for mozfile 15:51:45 INFO - Running setup.py install for mozhttpd 15:51:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:51:45 INFO - Running setup.py install for mozinfo 15:51:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:51:45 INFO - Running setup.py install for mozInstall 15:51:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:51:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:51:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:51:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:51:45 INFO - Running setup.py install for mozleak 15:51:45 INFO - Running setup.py install for mozlog 15:51:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:51:45 INFO - Running setup.py install for moznetwork 15:51:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:51:45 INFO - Running setup.py install for mozprocess 15:51:46 INFO - Running setup.py install for mozprofile 15:51:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:51:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:51:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:51:46 INFO - Running setup.py install for mozrunner 15:51:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:51:46 INFO - Running setup.py install for mozscreenshot 15:51:46 INFO - Running setup.py install for moztest 15:51:46 INFO - Running setup.py install for mozversion 15:51:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:51:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:51:46 INFO - Cleaning up... 15:51:46 INFO - Return code: 0 15:51:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:51: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')]} 15:51:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51: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')]} 15:51:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51: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 0x7f7d027c4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a40ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a3df60>, '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 0x2a3ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a3f1d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474065853.992838-1013234262', '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 15:51: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 15:51: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 15:51:46 INFO - Using env: {'DISPLAY': ':0', 15:51:46 INFO - 'HOME': '/home/cltbld', 15:51:46 INFO - 'LANG': 'en_US.UTF-8', 15:51:46 INFO - 'LANGUAGE': 'en_US:en', 15:51:46 INFO - 'LOGNAME': 'cltbld', 15:51:46 INFO - 'MAIL': '/var/mail/cltbld', 15:51:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:46 INFO - 'MOZ_NO_REMOTE': '1', 15:51:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:51:46 INFO - 'NO_EM_RESTART': '1', 15:51:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:51:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:46 INFO - 'PWD': '/builds/slave/test', 15:51:46 INFO - 'SHELL': '/bin/bash', 15:51:46 INFO - 'SHLVL': '1', 15:51:46 INFO - 'TERM': 'linux', 15:51:46 INFO - 'TMOUT': '86400', 15:51:46 INFO - 'USER': 'cltbld', 15:51:46 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474065853.992838-1013234262', 15:51:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:46 INFO - '_': '/tools/buildbot/bin/python'} 15:51:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:51:46 INFO - Running setup.py (path:/tmp/pip-mELdOm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:51: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)) 15:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:51:46 INFO - Running setup.py (path:/tmp/pip-6UVnNx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:51: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)) 15:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:51:46 INFO - Running setup.py (path:/tmp/pip-5SzD51-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:51:47 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)) 15:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:51:47 INFO - Running setup.py (path:/tmp/pip-BvNnnt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:51:47 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)) 15:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:51:47 INFO - Running setup.py (path:/tmp/pip-3OwFcy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:51:47 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)) 15:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:51:47 INFO - Running setup.py (path:/tmp/pip-SQmpCx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:51:47 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)) 15:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:51:47 INFO - Running setup.py (path:/tmp/pip-lBnYkD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:51:47 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)) 15:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:51:47 INFO - Running setup.py (path:/tmp/pip-jzSDJ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:51: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)) 15:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:51:47 INFO - Running setup.py (path:/tmp/pip-aPXZZP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:51: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)) 15:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:51:47 INFO - Running setup.py (path:/tmp/pip-iR6vJw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:51: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)) 15:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:51:47 INFO - Running setup.py (path:/tmp/pip-1cN2_a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:51: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)) 15:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:51:47 INFO - Running setup.py (path:/tmp/pip-xMRnBY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:51: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)) 15:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:51:47 INFO - Running setup.py (path:/tmp/pip-ZwQ8W4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:51: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)) 15:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:51:47 INFO - Running setup.py (path:/tmp/pip-fX1X1S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:51: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)) 15:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:51:47 INFO - Running setup.py (path:/tmp/pip-FnpEv5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:51: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)) 15:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:51:47 INFO - Running setup.py (path:/tmp/pip-Vt4oOk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:51: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)) 15:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:51:47 INFO - Running setup.py (path:/tmp/pip-v1moPT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:51:48 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)) 15:51:48 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)) 15:51:48 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)) 15:51:48 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)) 15:51:48 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)) 15:51:48 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)) 15:51:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:51:48 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 15:51:48 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 15:51:48 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 15:51:48 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 15:51:48 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 15:51:48 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 15:51:49 INFO - Downloading blessings-1.6.tar.gz 15:51:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:51:50 INFO - Installing collected packages: blessings 15:51:50 INFO - Running setup.py install for blessings 15:51:50 INFO - Successfully installed blessings 15:51:50 INFO - Cleaning up... 15:51:50 INFO - Return code: 0 15:51:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:51:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:51:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:51:50 INFO - Reading from file tmpfile_stdout 15:51:50 INFO - Current package versions: 15:51:50 INFO - argparse == 1.2.1 15:51:50 INFO - blessings == 1.6 15:51:50 INFO - blobuploader == 1.2.4 15:51:50 INFO - docopt == 0.6.1 15:51:50 INFO - functools32 == 3.2.3-2 15:51:50 INFO - jsonschema == 2.5.1 15:51:50 INFO - manifestparser == 1.1 15:51:50 INFO - mozInstall == 1.12 15:51:50 INFO - mozcrash == 0.17 15:51:50 INFO - mozdebug == 0.1 15:51:50 INFO - mozdevice == 0.48 15:51:50 INFO - mozfile == 1.2 15:51:50 INFO - mozhttpd == 0.7 15:51:50 INFO - mozinfo == 0.9 15:51:50 INFO - mozleak == 0.1 15:51:50 INFO - mozlog == 3.4 15:51:50 INFO - moznetwork == 0.27 15:51:50 INFO - mozprocess == 0.23 15:51:50 INFO - mozprofile == 0.28 15:51:50 INFO - mozrunner == 6.12 15:51:50 INFO - mozscreenshot == 0.1 15:51:50 INFO - mozsystemmonitor == 0.3 15:51:50 INFO - moztest == 0.7 15:51:50 INFO - mozversion == 1.4 15:51:50 INFO - psutil == 3.1.1 15:51:50 INFO - requests == 1.2.3 15:51:50 INFO - wsgiref == 0.1.2 15:51:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:51: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')]} 15:51:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51: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')]} 15:51:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51: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 0x7f7d027c4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a40ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a3df60>, '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 0x2a3ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a3f1d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474065853.992838-1013234262', '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 15:51: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 15:51: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 15:51:50 INFO - Using env: {'DISPLAY': ':0', 15:51:50 INFO - 'HOME': '/home/cltbld', 15:51:50 INFO - 'LANG': 'en_US.UTF-8', 15:51:50 INFO - 'LANGUAGE': 'en_US:en', 15:51:50 INFO - 'LOGNAME': 'cltbld', 15:51:50 INFO - 'MAIL': '/var/mail/cltbld', 15:51:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:50 INFO - 'MOZ_NO_REMOTE': '1', 15:51:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:51:50 INFO - 'NO_EM_RESTART': '1', 15:51:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:51:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:50 INFO - 'PWD': '/builds/slave/test', 15:51:50 INFO - 'SHELL': '/bin/bash', 15:51:50 INFO - 'SHLVL': '1', 15:51:50 INFO - 'TERM': 'linux', 15:51:50 INFO - 'TMOUT': '86400', 15:51:50 INFO - 'USER': 'cltbld', 15:51:50 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474065853.992838-1013234262', 15:51:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:50 INFO - '_': '/tools/buildbot/bin/python'} 15:51:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51: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)) 15:51: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)) 15:51: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)) 15:51: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)) 15:51: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)) 15:51: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)) 15:51: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)) 15:51: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)) 15:51: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)) 15:51: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)) 15:51: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)) 15:51:50 INFO - Cleaning up... 15:51:50 INFO - Return code: 0 15:51:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:51: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')]} 15:51:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51: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')]} 15:51:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51: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 0x7f7d027c4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a40ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a3df60>, '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 0x2a3ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a3f1d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474065853.992838-1013234262', '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 15:51: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 15:51: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 15:51:50 INFO - Using env: {'DISPLAY': ':0', 15:51:50 INFO - 'HOME': '/home/cltbld', 15:51:50 INFO - 'LANG': 'en_US.UTF-8', 15:51:50 INFO - 'LANGUAGE': 'en_US:en', 15:51:50 INFO - 'LOGNAME': 'cltbld', 15:51:50 INFO - 'MAIL': '/var/mail/cltbld', 15:51:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:50 INFO - 'MOZ_NO_REMOTE': '1', 15:51:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:51:50 INFO - 'NO_EM_RESTART': '1', 15:51:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:51:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:50 INFO - 'PWD': '/builds/slave/test', 15:51:50 INFO - 'SHELL': '/bin/bash', 15:51:50 INFO - 'SHLVL': '1', 15:51:50 INFO - 'TERM': 'linux', 15:51:50 INFO - 'TMOUT': '86400', 15:51:50 INFO - 'USER': 'cltbld', 15:51:50 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474065853.992838-1013234262', 15:51:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:50 INFO - '_': '/tools/buildbot/bin/python'} 15:51:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:51:50 INFO - Cleaning up... 15:51:50 INFO - Return code: 0 15:51:50 INFO - Running post-action listener: _resource_record_post_action 15:51:50 INFO - Running post-action listener: _start_resource_monitoring 15:51:50 INFO - Starting resource monitoring. 15:51:50 INFO - [mozharness: 2016-09-16 22:51:50.884396Z] Finished create-virtualenv step (success) 15:51:50 INFO - [mozharness: 2016-09-16 22:51:50.885457Z] Running install step. 15:51:50 INFO - Running pre-action listener: _resource_record_pre_action 15:51:50 INFO - Running main action method: install 15:51:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:51:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:51:51 INFO - Reading from file tmpfile_stdout 15:51:51 INFO - Detecting whether we're running mozinstall >=1.0... 15:51:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:51:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:51:51 INFO - Reading from file tmpfile_stdout 15:51:51 INFO - Output received: 15:51:51 INFO - Usage: mozinstall [options] installer 15:51:51 INFO - Options: 15:51:51 INFO - -h, --help show this help message and exit 15:51:51 INFO - -d DEST, --destination=DEST 15:51:51 INFO - Directory to install application into. [default: 15:51:51 INFO - "/builds/slave/test"] 15:51:51 INFO - --app=APP Application being installed. [default: firefox] 15:51:51 INFO - mkdir: /builds/slave/test/build/application 15:51:51 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'] 15:51:51 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 15:52:07 INFO - Reading from file tmpfile_stdout 15:52:07 INFO - Output received: 15:52:07 INFO - /builds/slave/test/build/application/firefox/firefox 15:52:07 INFO - Running post-action listener: _resource_record_post_action 15:52:07 INFO - [mozharness: 2016-09-16 22:52:07.230468Z] Finished install step (success) 15:52:07 INFO - [mozharness: 2016-09-16 22:52:07.230717Z] Running run-tests step. 15:52:07 INFO - Running pre-action listener: _resource_record_pre_action 15:52:07 INFO - Running main action method: run_tests 15:52:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:52:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:52:07 INFO - Python 2.7.3 15:52:07 INFO - Return code: 0 15:52:07 INFO - grabbing minidump binary from tooltool 15:52:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:52:07 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 0x2a3df60>, '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 0x2a3ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a3f1d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:52:07 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 15:52:07 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 15:52:07 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 15:52:07 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:52:07 INFO - Return code: 0 15:52:07 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:52:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:52:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:52:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:52:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:52:07 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/A3vR-t0sQlecNA8-Bm1aJw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 15:52:07 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/A3vR-t0sQlecNA8-Bm1aJw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-034 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 15:52:07 INFO - Using env: {'DISPLAY': ':0', 15:52:07 INFO - 'HOME': '/home/cltbld', 15:52:07 INFO - 'LANG': 'en_US.UTF-8', 15:52:07 INFO - 'LANGUAGE': 'en_US:en', 15:52:07 INFO - 'LOGNAME': 'cltbld', 15:52:07 INFO - 'MAIL': '/var/mail/cltbld', 15:52:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:52:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:52:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:52:07 INFO - 'MOZ_NO_REMOTE': '1', 15:52:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:52:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:52:07 INFO - 'NO_EM_RESTART': '1', 15:52:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:52:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:52:07 INFO - 'PWD': '/builds/slave/test', 15:52:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:52:07 INFO - 'SHELL': '/bin/bash', 15:52:07 INFO - 'SHLVL': '1', 15:52:07 INFO - 'TERM': 'linux', 15:52:07 INFO - 'TMOUT': '86400', 15:52:07 INFO - 'USER': 'cltbld', 15:52:07 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1474065853.992838-1013234262', 15:52:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:52:07 INFO - '_': '/tools/buildbot/bin/python'} 15:52:07 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/A3vR-t0sQlecNA8-Bm1aJw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 15:52:07 INFO - mozversion application_buildid: 20160916143318 15:52:07 INFO - mozversion application_changeset: b401cb17167b34c362eb819259effbb3c0979f59 15:52:07 INFO - mozversion application_display_name: Nightly 15:52:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:52:07 INFO - mozversion application_name: Firefox 15:52:07 INFO - mozversion application_remotingname: firefox 15:52:07 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:52:07 INFO - mozversion application_vendor: Mozilla 15:52:07 INFO - mozversion application_version: 51.0a1 15:52:07 INFO - mozversion platform_buildid: 20160916143318 15:52:07 INFO - mozversion platform_changeset: b401cb17167b34c362eb819259effbb3c0979f59 15:52:07 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:52:07 INFO - mozversion platform_version: 51.0a1 15:52:07 INFO - using testdate: 1474066327 15:52:07 INFO - actual date: 1474066327 15:52:07 INFO - starting webserver on 'localhost:54807' 15:52:07 INFO - SUITE-START | Running 2 tests 15:52:07 INFO - TEST-START | dromaeo_css 15:52:07 INFO - operating with platform_type : linux_ 15:52:07 INFO - Initialising browser for dromaeo_css test... 15:52:07 INFO - TEST-INFO | started process 3305 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsuArGo/profile http://localhost:54807/getInfo.html) 15:52:14 INFO - PROCESS | 3305 | __metrics Screen width/height:1600/1200 15:52:14 INFO - PROCESS | 3305 | colorDepth:24 15:52:14 INFO - PROCESS | 3305 | Browser inner width/height: 1024/697 15:52:14 INFO - PROCESS | 3305 | __metrics 15:52:15 INFO - PROCESS | 3305 | 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" 15:52:15 INFO - PROCESS | 3305 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:52:15 INFO - PROCESS | 3305 | console.error: PushService: 15:52:15 INFO - PROCESS | 3305 | stateChangeProcessEnqueue: Error transitioning state 15:52:15 INFO - PROCESS | 3305 | UnknownError 15:52:16 INFO - TEST-INFO | 3305: exit 0 15:52:16 INFO - Browser initialized. 15:52:16 INFO - Running cycle 1/1 for dromaeo_css test... 15:52:16 INFO - TEST-INFO | started process 3388 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsuArGo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:52:28 INFO - PROCESS | 3388 | RSS: Main: 174981120 15:52:28 INFO - PROCESS | 3388 | 15:52:47 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:52:54 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 15:53:06 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 15:53:14 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 15:53:20 INFO - PROCESS | 3388 | 1474066400069 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] 15:53:33 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 15:53:35 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 15:53:37 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 15:53:55 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.11673 seconds. 15:54:05 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 15:54:09 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 15:54:11 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 15:54:13 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.05005 seconds. 15:54:38 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.05004 seconds. 15:54:42 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 15:54:46 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 15:54:47 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 2.00007 seconds. 15:54:48 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:54:51 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.16671 seconds. 15:55:00 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 15:55:05 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 15:55:12 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 15:55:25 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 15:55:29 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.01661 seconds. 15:55:32 INFO - PROCESS | 3388 | Cycle 1(1): loaded http://localhost:54807/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54807/tests/dromaeo/cssquery-ext.html) 15:55:34 INFO - PROCESS | 3388 | RSS: Main: 352575488 15:55:34 INFO - PROCESS | 3388 | 15:55:45 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.06676 seconds. 15:55:55 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 15:56:24 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 15:56:28 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 15:56:29 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 15:56:36 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.01666 seconds. 15:56:44 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00399 seconds. 15:56:50 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:56:58 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:57:02 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 15:57:35 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:57:37 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 15:57:39 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 15:57:42 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 15:57:54 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:57:56 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 15:57:59 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 15:58:00 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 2.0001 seconds. 15:58:05 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 15:58:07 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:58:13 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 15:58:15 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00008 seconds. 15:58:17 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 15:58:19 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 15:58:25 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:58:35 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 15:58:37 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 15:58:37 INFO - PROCESS | 3388 | Cycle 1(1): loaded http://localhost:54807/tests/dromaeo/cssquery-ext.html (next: http://localhost:54807/tests/dromaeo/cssquery-jquery.html) 15:58:38 INFO - PROCESS | 3388 | RSS: Main: 274661376 15:58:38 INFO - PROCESS | 3388 | 15:58:42 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 15:58:43 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 2.0334 seconds. 15:58:52 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:58:56 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:58:58 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:59:21 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:59:23 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 15:59:33 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:59:40 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:59:41 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 15:59:55 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:59:57 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 16:00:09 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 16:00:11 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 16:00:21 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 16:00:29 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 16:00:33 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 16:00:35 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 16:00:49 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 16:00:51 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 16:00:59 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00008 seconds. 16:01:03 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 16:01:06 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 16:01:07 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 16:01:09 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 16:01:14 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 16:01:18 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 16:01:20 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 16:01:22 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 16:01:34 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 16:01:37 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 16:01:40 INFO - PROCESS | 3388 | Cycle 1(1): loaded http://localhost:54807/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54807/tests/dromaeo/cssquery-mootools.html) 16:01:40 INFO - PROCESS | 3388 | RSS: Main: 221650944 16:01:40 INFO - PROCESS | 3388 | 16:01:44 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.03356 seconds. 16:02:03 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.01677 seconds. 16:02:05 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 16:02:15 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 16:02:27 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 16:02:31 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 16:02:37 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.03341 seconds. 16:02:48 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 16:02:58 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 16:03:07 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 16:03:10 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 16:03:12 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 16:03:16 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 16:03:36 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 16:03:58 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 16:04:06 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 16:04:22 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 16:04:24 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 16:04:37 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 16:04:43 INFO - PROCESS | 3388 | Cycle 1(1): loaded http://localhost:54807/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54807/tests/dromaeo/cssquery-prototype.html) 16:04:43 INFO - PROCESS | 3388 | RSS: Main: 259010560 16:04:43 INFO - PROCESS | 3388 | 16:04:57 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 16:05:12 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.05007 seconds. 16:05:14 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 16:05:26 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 16:05:45 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 16:05:48 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 16:05:51 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 16:06:22 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 16:06:37 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 16:06:38 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 16:06:39 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 2.00006 seconds. 16:06:42 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 16:06:56 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00008 seconds. 16:06:58 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 16:07:09 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00009 seconds. 16:07:18 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 16:07:24 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 16:07:28 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 16:07:46 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 16:07:46 INFO - PROCESS | 3388 | Cycle 1(1): loaded http://localhost:54807/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54807/tests/dromaeo/cssquery-yui.html) 16:07:47 INFO - PROCESS | 3388 | RSS: Main: 220950528 16:07:47 INFO - PROCESS | 3388 | 16:07:50 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 16:08:10 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 16:08:30 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 16:08:32 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 16:08:34 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 16:08:36 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 16:08:38 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 16:08:40 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 16:09:03 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 16:09:05 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 16:09:09 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 16:09:16 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 16:09:25 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 16:09:27 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 16:09:30 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 16:09:37 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 16:09:39 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 16:09:59 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 16:10:01 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 16:10:03 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 16:10:05 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 16:10:11 INFO - PROCESS | 3388 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 16:10:50 INFO - PROCESS | 3388 | Cycle 1(1): loaded http://localhost:54807/tests/dromaeo/cssquery-yui.html (next: http://localhost:54807/tests/dromaeo/cssquery-dojo.html) 16:10:50 INFO - PROCESS | 3388 | RSS: Main: 315637760 16:10:50 INFO - PROCESS | 3388 | 16:10:50 INFO - PROCESS | 3388 | __start_tp_report 16:10:50 INFO - PROCESS | 3388 | _x_x_mozilla_page_load 16:10:50 INFO - PROCESS | 3388 | _x_x_mozilla_page_load_details 16:10:50 INFO - PROCESS | 3388 | |i|pagename|runs| 16:10:50 INFO - PROCESS | 3388 | |0;dojo.html;2586.741325867413;2611.556035473969;2637.8021648376366;2647.0602935957736;2658.574628059512;4573.176828170928;4634.2353511670535;4761.095391875546;4772.117158325704;4840.491748366419;4638.188317044521;4675.906481870361;4692.460367057789;4723.8819029524275;4724.62203023759;4323.286657701476;4338.305871060631;4343.543927887574;4366.56334366564;4439.311906654474;4367;4394.165108629366;4421.093675796454;4427.933580996288;4479.126570318789;4218.514870789868;4267.871963841089;4330.13397320534;4401;4405.82376704933;4298.806553705091;4319.611234988834;4321.719088259253;4325.653947684178;4326.632236259922;4281.48622165339;4284.121755040209;4284.828606855722;4286.314189729659;4286.5284818669925;4653.976730116362;4654.069186162747;4669.136209801164;4677.158111539956;4681.368015317923;4925;4979.751012449363;4981.427135879384;4982.103221420143;4982.103221420143;4991.051700176955;5023.120953833065;5039.042581909425;5042.294078828967;5100.974495127538;4676.485586585472;4690.742009189493;4697.812087516461;4701.905961880778;4735.455422626374;4978.701277923336;5135.589152867837;5136.20388839723;5138.075146473596;5191.740412979336;4820.3492528508905;4909.607231421478;4953.23224900141;4960.404751429779;5015.623828212899;4994.525520075587;4996.7001979881325;4997.725125118153;5000.1249781288125;5058.671186372948;4950.851474455772;4956.4547899731;4960.379952505937;4960.404751429852;5010.624203184776;4786.712797232177;4842.903141937141;4891.364122664031;4930.605551555867;4964.106460837084;4236.788160591958;4238.8728338149285;4241.278982572909;4290.549492303326;4291.54938731435;3707.2029513654693;3714.2757162353623;3716.0338312038525;3754.6808521276;3766;4235.343521754072;4284.421603083606;4307.65538756905;4337.414449049401;4353.34699795027;5062.898742025139;5069.898602028013;5074.695518268915;5115.769790359443;5136.409312928986;4348.608625223682;4349.586789255079;4353.1293741251875;4403.779811009436;4417.558244175557;4415.962248871512;4463.709858859163;4516.751578663205;4531.184386810406;4532.501424843264;4777.570018698334;4808.3989501312335;4809.3026511154985;4842.394700662418;4866.099771542276;5104.69371837691;5114.309568208244;5123.538881500683;5126.359205099363;5176.197689358156;4620.745858977789;4656.068786242698;4791.065742180242;4795.760211989317;4864.586510146536;4966.850994470172;4996.125678006407;5011.348524691767;5058.443571207238;5061.924071138862;5047.848564543069;5052.797888084436;5055.974720126376;5056.418511871035;5109.412417572027;4887.682300650446;4940.505949405031;4942.184539551077;4942.925856112233;4983.152864013054;4813.855584332476;4822;4848.515148485123;4862.902741945212;4991.875203119951;4740.573348398522;4771.976140119277;4775.068861572099;4827.179379505422;4843.346148269939;4853.733044682435;4878.56092951636;4906;4911.140550403737;4935.629827763004;4819.277108433763;4821.397325334333;4824.179889418878;4825.5174482551465;4828.686135401188 16:10:50 INFO - PROCESS | 3388 | |1;ext.html;569.7891780041384;578.3695771608946;584.1529781816366;584.3542885111951;590.6957916672911;28160.29599260017;30486;39196.80401597991;40424.59575404245;40434.59565404345;40600.79699601505;41057.38413923786;41077.178456430855;41082;41108.177836443254;7164.85670286594;7416.925830741705;7602.2397760224085;7603.885941710879;7607.049118860143;7036.472264580151;7231.6745746441275;7236.457265705067;9516.476593787338;9783.651923538335;9910.752231194207;9956.60173593059;9963.25275604329;9968.40189588627;10103.747406314864;9914.504274786232;9938.354006989559;9984.850227246598;9986.900130998669;9987.600495980152;7134.143902731654;7336.266373362674;9549.570269337826;9777.706668799947;9914;37901.43147852785;37969.430458543146;38303.808480957414;38315.425268620995;38371;2639.1818536253822;2709.647745793054;2718.184544636621;2798.6501687289087;3328.234506063619;28392.432151357065;28643.85678071617;28647.856760716273;29002.41995160106;29320;2994.281372470613;3034.1049390429876;3050.847457627132;3113.097201811472;3119.672434394401;268434;269478;270461.64769176225;270496;271037;44839.327410088874;45723.31415028777;45858.54141458609;53273.73363133237;53585;47852.521474785506;54096.45903540914;55196.448035519934;55517.722411388495;60226.096608550906;13552.254625995665;13723.794143087862;13756.86243137576;13758.587242382744;13761.31193440324;9571.760705982406;9610.66362677303;9621.89348224949;9626.855597166048;9628.75928101803;14995.2502374883;15397.769033464283;15429.768553471707;15801.288941997638;15802.60493487649;6516.869662606816;6954.200266969363;7065.2228254891925;7129.821754456077;7197.280271972865;752.9924700752922;810.525842382213;884.5886662701874;924.9676261330821;955.9426434413957;25885.611715824278;27686.824924630997;27761;27907.581386279224;27980.720192798217;880.7313769300425;901.1439132823713;925.8009527951523;935.3919952031098;972.8346181149221;14140.151590904374;14572.41710331596;14691.502444584446;14788.260586970824;15074.608213151952;369302;376212;376425;376574;378006;42887.57112428898;43574.128517429475;47683.76158119187;48937.26594101091;49080;1141.7944769941491;1145.204083162211;1161.1929708852267;1204.3556697166973;1208.9093318001185;27596.586051209648;28738.85630571834;29391.853040735514;29900.700992990664;29954.85022574873;43096.56903430926;43696.344554832336;43697.126057479305;43736.34395484134;43764.12471750611;334161;335264;335554;337468;338206;37744.62255377521;38029.42955855609;38635.42046869243;38709.6129038706;38861.22277554376;41109.794451027556;41338.1732365345;41677.791611042965;41873.16253674849;42546;35529.644703552636;35702;35983.82008089943;36037.81981090183;36110.819445902605;35508.64491355053;35629.28741425209;35694.82152589221;36016;36339.45490817693;35074.82462587671;35183.472247915786;35284.47073293851;35323;35539.46690799692;30683.693163068085;32380.676193237767;32769;32786.50820237746;33379.329653628134;25261.109309114352;25581;25694;25869.377463195113;25875.353116172246 16:10:50 INFO - PROCESS | 3388 | |2;jquery.html;322.50334484893267;328.3171004311033;346.1122221501848;348.39553374894564;384.42144572418505;10491.16070714343;11327.32036077836;11339.206255562114;12532.686682832933;16323.183840807971;15503.147326897017;15613.375464981416;15622.14078225697;16169.191540422991;16616.169191540404;4919.499552636445;4928.4825093365125;4962.305277261186;5047.0915235257635;5079.6952182869145;3509.087637214317;4956.826511072113;5043.243513472972;5048.394192696863;5048.873778155539;3664.3037822813712;5559.416261292567;5799.884002319951;5806.390329015456;5876.7355469004;5672.234248376458;5842.620229685057;5853.092770620539;5859.531237501011;5864.736086876101;5118.539331460149;5120.206368012964;5121.948780512184;5122.590192784568;5123.129068058439;18918.3378581749;19115.80884191154;19596.902015489974;19648;19789.703154452694;1773.5832079461316;1875.9718604220948;1967.36060780247;2118.650422680256;2383.713954325475;12263.754724905451;12629.557965471256;12834.229946203257;12840.422181001784;13355.198688078746;1659.2284587666672;1659.2533359988054;1660.4686500319901;1682.411156095369;1756.7189249719984;68856.96714549286;69485.30514694839;69546.99999999949;69701.65149174273;71202.64398678025;8479;8557.529335886462;8569.614367353484;8632;9269.119433653792;11776.175667703179;11793.823092653616;11794.941025294818;11851.88148118525;11859.881401185878;6689.264180940093;6911.89632155518;6931.341522555349;7131.714731410685;7162.856742865113;6658.4340331071435;6690.698918548677;6860.451163906875;6890.2076261229595;6915.308469153045;5480.479354461319;5644.774209031675;5652.830415087554;5684.8010319638615;5721.5136713379;1588.610790356347;1594.4738236381966;1611.6696077304125;1641.942532011374;1691.382645334444;809.2433574607761;835.1231207232503;841.5750046226616;874.9781255468537;880.8724832214775;16327.755083673754;16466.259018344204;16727.832721672872;16919.323227071025;17451.73822392665;811.2982270336234;816.7182322098867;827.4084029918516;836.8744688296682;873.4540911930044;8925.107489251153;9314.394564353295;9722.951385243028;9736.221102311798;9812.810836478031;364575;405297;427239;428739;430166;49797.75101124471;70516;71214.64392678003;72612.27387726161;73711;909.1363204955319;923.3767207135157;949.7245798718433;952.1430712358932;954.117441366733;13942.930285348508;14824.184669843262;15036.323365448363;15971.938088157403;16368.508944731677;29496.852515737282;37744;38681.613183868925;38762.6123738759;40292.1941561173;45796;47483.52516474929;50800;51004;52182.47817521928;6448.935510644834;6505.186851643544;6546.738130474728;6574.539782215199;6802.251752307341;8298.419110662437;10182.898171018194;10486.947565262124;11084.944575277072;11828.40857957116;9562.139407453362;9670.854937175807;9705.708828735149;9902.653407130716;9980.151687106678;10211.489425528545;10387.896121038693;10423.061924426838;10493.527791249564;10628.734281643021;10053.899461005296;10157.721163330447;10212.591496340361;10311.896881031395;10553.894461055292;6669.266380698023;6708.597484150967;6804.727810887509;6906.93093069083;7127.396335824606;9260.444373337621;9540.666076687283;10010.499475026365;10027.197824174194;10418.228151701454 16:10:50 INFO - PROCESS | 3388 | |3;mootools.html;1214.4656351205474;1294.2622705058118;1350.6623344163959;1367.3231750283612;1641.967160656787;6375.5218358623115;7277.454190935681;7556.055493063374;7566.772996810091;7752.573608451533;7536.660850261737;7598.0502437195355;7618.0096587443695;7621.314081732643;7652.273034061769;2101.317071951618;2124.7875212478784;2126.1495401839234;2129.276046144311;2129.893505324735;1987.9683335946013;2037.623039737646;2129.5315030693223;2131.424515380846;2134.3703607435846;2071.6167509010806;2084.8227900628463;2100.0339843671927;2101.7267755191806;2121.8514703970727;2067.204126411335;2069.3378119001927;2070.2133189387937;2071.761747399046;2073.284716772711;2037.2564014134882;2052.72288241087;2070;2080.854340196187;2083.5520363122;7429.591372474566;7512.060992375953;7536.397088232929;7542.321191092773;7545.283198096227;4135.152293779768;4312.180685669744;4318.85550329163;4324.6972711910175;4328.480582330109;2622.2264431992417;2755.5728862026417;2802.8598570071617;2804.102687140116;2804.803663743539;899.0604817965177;909.4316052467208;925.4169872980046;928.3223247029706;938.3197182043033;10016.79966400668;10111.797764044752;10147.492625368703;10152.847707284396;10181.643642472478;3607.6572725591027;3679.4296883983025;3777.112378591029;3815.923681526354;3832.5400951885395;7924.643391047417;8003.719869804529;8004.0395152580895;8009.919900801034;8012.879806802903;4155.168966206771;4157.459530261047;4160.292750232467;4164.3961625564125;4164.583541645811;3403.5745531808525;3532.0993146747414;3794.0894185395305;3894.7857867816974;3901.785401802871;3218.3885061838178;3219.742420606346;3309.7517686173633;3350.9329813404083;3358.0429577570762;995.4375777685584;998.2662742883974;1000.5147503460815;1007.0684616729496;1021.8824835143905;693.2512886083018;694.4548529404393;715.0204220218334;720.4632548751214;730.3609341825902;4790.281457781361;4961.50384961501;4966.553010229024;5026.4973502649445;5068.56917162038;367.9760815546981;384.18169299392116;385.56238669110763;385.9421086836997;389.07982621101456;3297.3570153819774;3326.8336583170744;3338.1487720631085;3351.7151042161204;3449.120474279043;8956.596953137125;9245.768855778659;9390.67132650354;9395.671151509665;9416.858747118798;9374.109459601326;9375.39059961114;9379.437233765995;9385.15533601979;9385.90614093864;7125.35871771543;7193.568385896863;7194.676239569337;7201.459890508233;7209.495335326582;4833.10587541306;4973.701577905337;5002.374703162105;5065.7720402581235;5229.607779416635;8647.437916535406;8695.782605434915;8710.82578348442;8722.345824063348;8727.214550690469;7972.641231144728;8319.292860106958;8323.458975166595;8782.516961567175;8784.472931623868;6312;6439.935600643934;6444.871102578016;6455.709493072729;6463.741450341934;5898.1152827076285;6113.480354169946;6665.466762659073;6770.729170833309;6849.24658287578;6463.224413070461;6475.838104047437;6516.283208847118;6520.054592084027;6522;6210.378962103753;6262.06069089622;6264.436200741955;6365.586236894587;6368.044793281045;6085.786997455068;6085.786997455245;6125.908111378422;6126.6017708848785;6130.754769809158;4322.330038843984;4338.219120558204;4358.978205108954;4368.759718215529;4518.977405112953;4313.6980411370905;4393.34099885007;4402.3616575597;4531;4623.329617205553 16:10:50 INFO - PROCESS | 3388 | |4;prototype.html;281.7914743090113;285.21423478317234;293.67988892107604;296.1293796239057;296.7344226916908;4615.261558150688;4720.150372932871;4806.83176088837;4836.830710925118;4842.636802239828;4591.747453890035;4691.718496890189;4732;4762.404699412574;4764.26153946138;3748.8875333739893;3759.4924685167493;3760.6991440684815;3761.2101458693705;3772.868139558135;3560.7329450291204;3676.246369494247;3745.8688945886897;3760.7743535387826;3767.5290588676417;3633.200695846923;3720.2373513429684;3727.7204209684246;3743.850245990157;3746.3818469952425;3711.2206436648066;3713.9944430007763;3730.440433934918;3732.8133593320226;3734.533183352081;3283.868645254187;3285.211549228192;3289.243474000993;3289.3750187464307;3290.555774970372;4489.304157855538;4588.518205588399;4600.332951721982;4616.991608650248;4660.370849935283;4218.110594242953;4228.450301460791;4238.533761286256;4239.7668128252935;4251.638610718092;4950.504949505057;4950.8762280943265;4953.254225098868;4958.132326842824;4975.676581022222;3286.6713328667183;3480.269143479872;3524.5770507539005;3560.2345495718287;3573.9456860226296;5099.889067668719;5140.871478213037;5146.253793200002;5157.149070403379;5169.92245116316;3575.175611267122;3582.4268117101137;3588.515550400715;3589.1874603325155;3592.4911537154458;3633.927321453609;3636.29092326999;3637.618050104754;3640.6541378568995;3646.890593282206;3932.9016774580864;4132.91734165315;4168.291390463568;4170.812313445902;4179.4357761701785;4958.628102892298;4964.230544265644;4964.815481188338;4966.652334336562;4978.153713868651;1163.6392718257198;1195.9940200299118;1211.8303437518755;1225.2342286071205;1227.3617718786359;840.4159109418895;858.2833334166041;861.1431625532636;861.7543999959981;863.468966585558;4390.868273951786;4396.648268138541;4413.977930110329;4417.668674849399;4426.513083560806;5103.18349064148;5103.744812759347;5108.463611320833;5109.029284435946;5114.567920982131;4243.451140333756;4391.912161756747;4414.624756895636;4420.055175722672;4423.6903416760515;2385.61830107182;2401.2796161151587;2409.2410890569413;2419.600765873646;2428.247243354566;4298.806553705091;4302.806373713191;4313.61177494027;4316.417283666665;4320.827166913351;4407.537208593116;4557.7466196795485;4557.906102535368;4615.907681846412;4637.121965603936;4297.431437525281;4301;4303.806328715215;4319.460067491564;4324.243257429937;4477.962527050119;4508.977455112704;4518.344839998247;4533.821206486271;4543.227651299353;4214.325707886724;4377.43093397869;4386.5174830767955;4436.955630443655;4450.553570089669;4231.174920890397;4266.53068162508;4266.765327906871;4274.786260687016;4278.315469524861;3244.6268679102204;3246.561714168557;3262.233375156789;3266.330402267482;3285.195127193853;4481.574250446202;4498.865034048984;4500.234960056864;4516.751578663205;4524.457065152203;4183.372494125905;4398.47218333802;4424.911501769882;4450.243458611979;4461.196984542814;4429.82280708768;4431.853384097198;4437.980718772886;4445.5776701213335;4449.911001779881;4333.548261332489;4350.869473915787;4383.356241409471;4707.552782485658;4839.685420447659;4894.14352488306;5002.549770520671;5004.247663784977;5005.274235235797;5018.1218286800395;4860.246661767432;4876.804927802848;4895.902081958412;4895.951040489549;4900.534449227318 16:10:50 INFO - PROCESS | 3388 | |5;yui.html;574.9338826035005;578.0491092153408;611.73083843109;659.8152517295159;660.8480049588593;11135.610253641124;11144.269653934787;11465.983884627012;11603.593874214404;11610.419479026046;12065.33640649764;12089.153759236857;12095.516179352837;12096.455659495321;12136.817947730791;723.5550136665952;742.5581778841581;745.0984308986107;745.2696357569585;745.6868115391549;588.8410129265096;605.1860247966478;721.7041013184595;741.9962900185492;744.702119152338;798.9880151797728;799.1728560939429;800.1678254615193;800.4196957206037;801.4029547986758;793.5476778236408;794.972175973841;795.1174196641723;795.2246559604397;795.6300320351006;453.2702349217757;461.57304493343526;550.9421510741395;567.2342337843919;581.3517927510795;12771.233725976379;12868.227906325557;12924.935375323157;12956.157849739737;12963.351832408342;12149.271043737404;12525.812112818225;12679;12716.872831271754;12796.744065118739;11701.414929253504;11747.588834390755;11959;12084.154109212359;12326.568570100004;3898.9805050974564;3956.307646161939;4008.4346018548154;4166.583341665809;4166.854160104382;69723;69917.65041174812;70422;70730.29269707289;70875;10062.798744025078;10191.898081019242;10251.948740256401;10252.333598316085;10421.739456513647;19348.903255483634;19434.12546435414;19438.4168474946;19444.638875278877;19447.61104777925;6515.967420162869;6524.412802847766;6529.510286728514;6669.333066693389;6804.55770374924;3582.158192824684;3610.8014059226994;3613.8915832525067;3640.7269454791;3645.744797864124;2612.42526644138;2704.459108178333;2705.810593258492;2707.0525316139588;2719.170652950829;453.64388954670426;455.1920341393986;459.7379493688632;461.3794446469493;476.24752890433035;478.4569513602087;481.0565463696055;487.2763945540869;490.2866329490583;509.60760214634524;7263.237360077116;7447.590382529013;7486.139094004259;7565.243475652391;7675.117361503387;451.9909601808011;452.710265430125;461.10545541649094;494.94308154561963;498.71573203273783;3300.702936735657;3395.8471868765964;3463.8787642432394;3472.3055388922317;3537.9469307960403;100836.49581752044;104910;108657;109723;110368;91647;91901;93960.53019734994;94540.52729736308;96397.51801241089;62292.37707622866;63023.369766303585;78575.60712196516;82535.587322063;85895;7845.960770196112;8348.666053357798;8463.23830855226;8490.702825401082;8490.915090849012;41710.791446042575;42901.570984289756;44236.336454953846;44354.77822610996;44624.10751785011;37014.629853702194;37881.24237515179;37951;47260.76369618267;60335.69832150811;10662.520186591466;10674.46627668629;10677.839832402366;11251.774964500828;11591.710207244887;11737.47181376823;11963.940180299043;11968.222065565711;12007.159498834997;12325.876741232833;10958;10969.83545246806;12290.569830055905;12297.754044919231;12298.631041068782;11027.779444411228;11349.262297950938;11678.124140689313;11725.88274117282;11731.472083756422;10919.727006824893;11222.66332010041;11507.873389533812;11622.6513204604;11985.400729963292;9519.428834269966;10203.795924081329;10212.387256764618;10250.231232657432;10585.52365143555;9168.770780730536;9382.386750998956;10075.697729068139;10210.744731381477;10257.84613230817 16:10:50 INFO - PROCESS | 3388 | __end_tp_report 16:10:50 INFO - PROCESS | 3388 | __start_cc_report 16:10:50 INFO - PROCESS | 3388 | _x_x_mozilla_cycle_collect,1890 16:10:50 INFO - PROCESS | 3388 | __end_cc_report 16:10:50 INFO - PROCESS | 3388 | __startTimestamp1474067450816__endTimestamp 16:10:50 INFO - PROCESS | 3388 | 16:10:50 INFO - PROCESS | 3388 | ------- Summary: start ------- 16:10:50 INFO - PROCESS | 3388 | Number of tests: 6 16:10:50 INFO - PROCESS | 3388 | 16:10:50 INFO - PROCESS | 3388 | [#0] dojo.html Cycles:165 Average:4647.90 Median:4795.76 stddev:484.81 (10.1%) stddev-sans-first:458.54 16:10:50 INFO - PROCESS | 3388 | Values: 2586.7 2611.6 2637.8 2647.1 2658.6 4573.2 4634.2 4761.1 4772.1 4840.5 4638.2 4675.9 4692.5 4723.9 4724.6 4323.3 4338.3 4343.5 4366.6 4439.3 4367.0 4394.2 4421.1 4427.9 4479.1 4218.5 4267.9 4330.1 4401.0 4405.8 4298.8 4319.6 4321.7 4325.7 4326.6 4281.5 4284.1 4284.8 4286.3 4286.5 4654.0 4654.1 4669.1 4677.2 4681.4 4925.0 4979.8 4981.4 4982.1 4982.1 4991.1 5023.1 5039.0 5042.3 5101.0 4676.5 4690.7 4697.8 4701.9 4735.5 4978.7 5135.6 5136.2 5138.1 5191.7 4820.3 4909.6 4953.2 4960.4 5015.6 4994.5 4996.7 4997.7 5000.1 5058.7 4950.9 4956.5 4960.4 4960.4 5010.6 4786.7 4842.9 4891.4 4930.6 4964.1 4236.8 4238.9 4241.3 4290.5 4291.5 3707.2 3714.3 3716.0 3754.7 3766.0 4235.3 4284.4 4307.7 4337.4 4353.3 5062.9 5069.9 5074.7 5115.8 5136.4 4348.6 4349.6 4353.1 4403.8 4417.6 4416.0 4463.7 4516.8 4531.2 4532.5 4777.6 4808.4 4809.3 4842.4 4866.1 5104.7 5114.3 5123.5 5126.4 5176.2 4620.7 4656.1 4791.1 4795.8 4864.6 4966.9 4996.1 5011.3 5058.4 5061.9 5047.8 5052.8 5056.0 5056.4 5109.4 4887.7 4940.5 4942.2 4942.9 4983.2 4813.9 4822.0 4848.5 4862.9 4991.9 4740.6 4772.0 4775.1 4827.2 4843.3 4853.7 4878.6 4906.0 4911.1 4935.6 4819.3 4821.4 4824.2 4825.5 4828.7 16:10:50 INFO - PROCESS | 3388 | 16:10:50 INFO - PROCESS | 3388 | [#1] ext.html Cycles:180 Average:48174.89 Median:37999.43 stddev:86755.62 (228.3%) stddev-sans-first:86925.36 16:10:50 INFO - PROCESS | 3388 | Values: 569.8 578.4 584.2 584.4 590.7 28160.3 30486.0 39196.8 40424.6 40434.6 40600.8 41057.4 41077.2 41082.0 41108.2 7164.9 7416.9 7602.2 7603.9 7607.0 7036.5 7231.7 7236.5 9516.5 9783.7 9910.8 9956.6 9963.3 9968.4 10103.7 9914.5 9938.4 9984.9 9986.9 9987.6 7134.1 7336.3 9549.6 9777.7 9914.0 37901.4 37969.4 38303.8 38315.4 38371.0 2639.2 2709.6 2718.2 2798.7 3328.2 28392.4 28643.9 28647.9 29002.4 29320.0 2994.3 3034.1 3050.8 3113.1 3119.7 268434.0 269478.0 270461.6 270496.0 271037.0 44839.3 45723.3 45858.5 53273.7 53585.0 47852.5 54096.5 55196.4 55517.7 60226.1 13552.3 13723.8 13756.9 13758.6 13761.3 9571.8 9610.7 9621.9 9626.9 9628.8 14995.3 15397.8 15429.8 15801.3 15802.6 6516.9 6954.2 7065.2 7129.8 7197.3 753.0 810.5 884.6 925.0 955.9 25885.6 27686.8 27761.0 27907.6 27980.7 880.7 901.1 925.8 935.4 972.8 14140.2 14572.4 14691.5 14788.3 15074.6 369302.0 376212.0 376425.0 376574.0 378006.0 42887.6 43574.1 47683.8 48937.3 49080.0 1141.8 1145.2 1161.2 1204.4 1208.9 27596.6 28738.9 29391.9 29900.7 29954.9 43096.6 43696.3 43697.1 43736.3 43764.1 334161.0 335264.0 335554.0 337468.0 338206.0 37744.6 38029.4 38635.4 38709.6 38861.2 41109.8 41338.2 41677.8 41873.2 42546.0 35529.6 35702.0 35983.8 36037.8 36110.8 35508.6 35629.3 35694.8 36016.0 36339.5 35074.8 35183.5 35284.5 35323.0 35539.5 30683.7 32380.7 32769.0 32786.5 33379.3 25261.1 25581.0 25694.0 25869.4 25875.4 16:10:50 INFO - PROCESS | 3388 | 16:10:50 INFO - PROCESS | 3388 | [#2] jquery.html Cycles:180 Average:24314.01 Median:27420.50 stddev:67772.51 (247.2%) stddev-sans-first:67938.69 16:10:50 INFO - PROCESS | 3388 | Values: 322.5 328.3 346.1 348.4 384.4 10491.2 11327.3 11339.2 12532.7 16323.2 15503.1 15613.4 15622.1 16169.2 16616.2 4919.5 4928.5 4962.3 5047.1 5079.7 3509.1 4956.8 5043.2 5048.4 5048.9 3664.3 5559.4 5799.9 5806.4 5876.7 5672.2 5842.6 5853.1 5859.5 5864.7 5118.5 5120.2 5121.9 5122.6 5123.1 18918.3 19115.8 19596.9 19648.0 19789.7 1773.6 1876.0 1967.4 2118.7 2383.7 12263.8 12629.6 12834.2 12840.4 13355.2 1659.2 1659.3 1660.5 1682.4 1756.7 68857.0 69485.3 69547.0 69701.7 71202.6 8479.0 8557.5 8569.6 8632.0 9269.1 11776.2 11793.8 11794.9 11851.9 11859.9 6689.3 6911.9 6931.3 7131.7 7162.9 6658.4 6690.7 6860.5 6890.2 6915.3 5480.5 5644.8 5652.8 5684.8 5721.5 1588.6 1594.5 1611.7 1641.9 1691.4 809.2 835.1 841.6 875.0 880.9 16327.8 16466.3 16727.8 16919.3 17451.7 811.3 816.7 827.4 836.9 873.5 8925.1 9314.4 9723.0 9736.2 9812.8 364575.0 405297.0 427239.0 428739.0 430166.0 49797.8 70516.0 71214.6 72612.3 73711.0 909.1 923.4 949.7 952.1 954.1 13942.9 14824.2 15036.3 15971.9 16368.5 29496.9 37744.0 38681.6 38762.6 40292.2 45796.0 47483.5 50800.0 51004.0 52182.5 6448.9 6505.2 6546.7 6574.5 6802.3 8298.4 10182.9 10486.9 11084.9 11828.4 9562.1 9670.9 9705.7 9902.7 9980.2 10211.5 10387.9 10423.1 10493.5 10628.7 10053.9 10157.7 10212.6 10311.9 10553.9 6669.3 6708.6 6804.7 6906.9 7127.4 9260.4 9540.7 10010.5 10027.2 10418.2 16:10:50 INFO - PROCESS | 3388 | 16:10:50 INFO - PROCESS | 3388 | [#3] mootools.html Cycles:180 Average:4840.25 Median:4964.03 stddev:2767.33 (55.7%) stddev-sans-first:2761.67 16:10:50 INFO - PROCESS | 3388 | Values: 1214.5 1294.3 1350.7 1367.3 1642.0 6375.5 7277.5 7556.1 7566.8 7752.6 7536.7 7598.1 7618.0 7621.3 7652.3 2101.3 2124.8 2126.1 2129.3 2129.9 1988.0 2037.6 2129.5 2131.4 2134.4 2071.6 2084.8 2100.0 2101.7 2121.9 2067.2 2069.3 2070.2 2071.8 2073.3 2037.3 2052.7 2070.0 2080.9 2083.6 7429.6 7512.1 7536.4 7542.3 7545.3 4135.2 4312.2 4318.9 4324.7 4328.5 2622.2 2755.6 2802.9 2804.1 2804.8 899.1 909.4 925.4 928.3 938.3 10016.8 10111.8 10147.5 10152.8 10181.6 3607.7 3679.4 3777.1 3815.9 3832.5 7924.6 8003.7 8004.0 8009.9 8012.9 4155.2 4157.5 4160.3 4164.4 4164.6 3403.6 3532.1 3794.1 3894.8 3901.8 3218.4 3219.7 3309.8 3350.9 3358.0 995.4 998.3 1000.5 1007.1 1021.9 693.3 694.5 715.0 720.5 730.4 4790.3 4961.5 4966.6 5026.5 5068.6 368.0 384.2 385.6 385.9 389.1 3297.4 3326.8 3338.1 3351.7 3449.1 8956.6 9245.8 9390.7 9395.7 9416.9 9374.1 9375.4 9379.4 9385.2 9385.9 7125.4 7193.6 7194.7 7201.5 7209.5 4833.1 4973.7 5002.4 5065.8 5229.6 8647.4 8695.8 8710.8 8722.3 8727.2 7972.6 8319.3 8323.5 8782.5 8784.5 6312.0 6439.9 6444.9 6455.7 6463.7 5898.1 6113.5 6665.5 6770.7 6849.2 6463.2 6475.8 6516.3 6520.1 6522.0 6210.4 6262.1 6264.4 6365.6 6368.0 6085.8 6085.8 6125.9 6126.6 6130.8 4322.3 4338.2 4359.0 4368.8 4519.0 4313.7 4393.3 4402.4 4531.0 4623.3 16:10:50 INFO - PROCESS | 3388 | 16:10:50 INFO - PROCESS | 3388 | [#4] prototype.html Cycles:180 Average:3954.14 Median:4380.39 stddev:1130.45 (25.8%) stddev-sans-first:1099.50 16:10:50 INFO - PROCESS | 3388 | Values: 281.8 285.2 293.7 296.1 296.7 4615.3 4720.2 4806.8 4836.8 4842.6 4591.7 4691.7 4732.0 4762.4 4764.3 3748.9 3759.5 3760.7 3761.2 3772.9 3560.7 3676.2 3745.9 3760.8 3767.5 3633.2 3720.2 3727.7 3743.9 3746.4 3711.2 3714.0 3730.4 3732.8 3734.5 3283.9 3285.2 3289.2 3289.4 3290.6 4489.3 4588.5 4600.3 4617.0 4660.4 4218.1 4228.5 4238.5 4239.8 4251.6 4950.5 4950.9 4953.3 4958.1 4975.7 3286.7 3480.3 3524.6 3560.2 3573.9 5099.9 5140.9 5146.3 5157.1 5169.9 3575.2 3582.4 3588.5 3589.2 3592.5 3633.9 3636.3 3637.6 3640.7 3646.9 3932.9 4132.9 4168.3 4170.8 4179.4 4958.6 4964.2 4964.8 4966.7 4978.2 1163.6 1196.0 1211.8 1225.2 1227.4 840.4 858.3 861.1 861.8 863.5 4390.9 4396.6 4414.0 4417.7 4426.5 5103.2 5103.7 5108.5 5109.0 5114.6 4243.5 4391.9 4414.6 4420.1 4423.7 2385.6 2401.3 2409.2 2419.6 2428.2 4298.8 4302.8 4313.6 4316.4 4320.8 4407.5 4557.7 4557.9 4615.9 4637.1 4297.4 4301.0 4303.8 4319.5 4324.2 4478.0 4509.0 4518.3 4533.8 4543.2 4214.3 4377.4 4386.5 4437.0 4450.6 4231.2 4266.5 4266.8 4274.8 4278.3 3244.6 3246.6 3262.2 3266.3 3285.2 4481.6 4498.9 4500.2 4516.8 4524.5 4183.4 4398.5 4424.9 4450.2 4461.2 4429.8 4431.9 4438.0 4445.6 4449.9 4333.5 4350.9 4383.4 4707.6 4839.7 4894.1 5002.5 5004.2 5005.3 5018.1 4860.2 4876.8 4895.9 4896.0 4900.5 16:10:50 INFO - PROCESS | 3388 | 16:10:50 INFO - PROCESS | 3388 | [#5] yui.html Cycles:180 Average:17863.03 Median:3927.64 stddev:26720.72 (680.3%) stddev-sans-first:26764.15 16:10:50 INFO - PROCESS | 3388 | Values: 574.9 578.0 611.7 659.8 660.8 11135.6 11144.3 11466.0 11603.6 11610.4 12065.3 12089.2 12095.5 12096.5 12136.8 723.6 742.6 745.1 745.3 745.7 588.8 605.2 721.7 742.0 744.7 799.0 799.2 800.2 800.4 801.4 793.5 795.0 795.1 795.2 795.6 453.3 461.6 550.9 567.2 581.4 12771.2 12868.2 12924.9 12956.2 12963.4 12149.3 12525.8 12679.0 12716.9 12796.7 11701.4 11747.6 11959.0 12084.2 12326.6 3899.0 3956.3 4008.4 4166.6 4166.9 69723.0 69917.7 70422.0 70730.3 70875.0 10062.8 10191.9 10251.9 10252.3 10421.7 19348.9 19434.1 19438.4 19444.6 19447.6 6516.0 6524.4 6529.5 6669.3 6804.6 3582.2 3610.8 3613.9 3640.7 3645.7 2612.4 2704.5 2705.8 2707.1 2719.2 453.6 455.2 459.7 461.4 476.2 478.5 481.1 487.3 490.3 509.6 7263.2 7447.6 7486.1 7565.2 7675.1 452.0 452.7 461.1 494.9 498.7 3300.7 3395.8 3463.9 3472.3 3537.9 100836.5 104910.0 108657.0 109723.0 110368.0 91647.0 91901.0 93960.5 94540.5 96397.5 62292.4 63023.4 78575.6 82535.6 85895.0 7846.0 8348.7 8463.2 8490.7 8490.9 41710.8 42901.6 44236.3 44354.8 44624.1 37014.6 37881.2 37951.0 47260.8 60335.7 10662.5 10674.5 10677.8 11251.8 11591.7 11737.5 11963.9 11968.2 12007.2 12325.9 10958.0 10969.8 12290.6 12297.8 12298.6 11027.8 11349.3 11678.1 11725.9 11731.5 10919.7 11222.7 11507.9 11622.7 11985.4 9519.4 10203.8 10212.4 10250.2 10585.5 9168.8 9382.4 10075.7 10210.7 10257.8 16:10:50 INFO - PROCESS | 3388 | -------- Summary: end -------- 16:10:50 INFO - PROCESS | 3388 | 16:10:51 INFO - TEST-INFO | 3388: exit 0 16:10:51 INFO - TEST-OK | dromaeo_css | took 1124172ms 16:10:51 INFO - TEST-START | kraken 16:10:51 INFO - operating with platform_type : linux_ 16:10:51 INFO - Initialising browser for kraken test... 16:10:51 INFO - TEST-INFO | started process 3694 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoCTPGW/profile http://localhost:54807/getInfo.html) 16:10:56 INFO - PROCESS | 3694 | __metrics Screen width/height:1600/1200 16:10:56 INFO - PROCESS | 3694 | colorDepth:24 16:10:56 INFO - PROCESS | 3694 | Browser inner width/height: 1024/697 16:10:56 INFO - PROCESS | 3694 | __metrics 16:10:57 INFO - PROCESS | 3694 | 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" 16:10:57 INFO - PROCESS | 3694 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:10:57 INFO - PROCESS | 3694 | console.error: PushService: 16:10:57 INFO - PROCESS | 3694 | stateChangeProcessEnqueue: Error transitioning state 16:10:57 INFO - PROCESS | 3694 | UnknownError 16:10:58 INFO - TEST-INFO | 3694: exit 0 16:10:58 INFO - Browser initialized. 16:10:58 INFO - Running cycle 1/1 for kraken test... 16:10:58 INFO - TEST-INFO | started process 3777 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoCTPGW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:11:10 INFO - PROCESS | 3777 | RSS: Main: 174358528 16:11:10 INFO - PROCESS | 3777 | 16:11:13 INFO - PROCESS | 3777 | Cycle 1(1): loaded http://localhost:54807/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54807/tests/kraken/driver.html?testName=audio-beat-detection) 16:11:14 INFO - PROCESS | 3777 | RSS: Main: 233820160 16:11:14 INFO - PROCESS | 3777 | 16:11:17 INFO - PROCESS | 3777 | Cycle 1(1): loaded http://localhost:54807/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54807/tests/kraken/driver.html?testName=audio-dft) 16:11:17 INFO - PROCESS | 3777 | RSS: Main: 217600000 16:11:17 INFO - PROCESS | 3777 | 16:11:21 INFO - PROCESS | 3777 | Cycle 1(1): loaded http://localhost:54807/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54807/tests/kraken/driver.html?testName=audio-fft) 16:11:21 INFO - PROCESS | 3777 | RSS: Main: 300122112 16:11:21 INFO - PROCESS | 3777 | 16:11:23 INFO - PROCESS | 3777 | Cycle 1(1): loaded http://localhost:54807/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54807/tests/kraken/driver.html?testName=audio-oscillator) 16:11:24 INFO - PROCESS | 3777 | RSS: Main: 209637376 16:11:24 INFO - PROCESS | 3777 | 16:11:26 INFO - PROCESS | 3777 | Cycle 1(1): loaded http://localhost:54807/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54807/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:11:27 INFO - PROCESS | 3777 | RSS: Main: 213405696 16:11:27 INFO - PROCESS | 3777 | 16:11:30 INFO - PROCESS | 3777 | Cycle 1(1): loaded http://localhost:54807/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54807/tests/kraken/driver.html?testName=imaging-darkroom) 16:11:31 INFO - PROCESS | 3777 | RSS: Main: 249470976 16:11:31 INFO - PROCESS | 3777 | 16:11:34 INFO - PROCESS | 3777 | Cycle 1(1): loaded http://localhost:54807/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54807/tests/kraken/driver.html?testName=imaging-desaturate) 16:11:35 INFO - PROCESS | 3777 | RSS: Main: 248750080 16:11:35 INFO - PROCESS | 3777 | 16:11:38 INFO - PROCESS | 3777 | Cycle 1(1): loaded http://localhost:54807/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54807/tests/kraken/driver.html?testName=json-parse-financial) 16:11:38 INFO - PROCESS | 3777 | RSS: Main: 249282560 16:11:38 INFO - PROCESS | 3777 | 16:11:40 INFO - PROCESS | 3777 | Cycle 1(1): loaded http://localhost:54807/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54807/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:11:41 INFO - PROCESS | 3777 | RSS: Main: 211251200 16:11:41 INFO - PROCESS | 3777 | 16:11:43 INFO - PROCESS | 3777 | Cycle 1(1): loaded http://localhost:54807/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54807/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:11:43 INFO - PROCESS | 3777 | RSS: Main: 218898432 16:11:43 INFO - PROCESS | 3777 | 16:11:45 INFO - PROCESS | 3777 | Cycle 1(1): loaded http://localhost:54807/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54807/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:11:46 INFO - PROCESS | 3777 | RSS: Main: 222507008 16:11:46 INFO - PROCESS | 3777 | 16:11:49 INFO - PROCESS | 3777 | Cycle 1(1): loaded http://localhost:54807/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54807/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:11:49 INFO - PROCESS | 3777 | RSS: Main: 223297536 16:11:49 INFO - PROCESS | 3777 | 16:11:52 INFO - PROCESS | 3777 | Cycle 1(1): loaded http://localhost:54807/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54807/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:11:53 INFO - PROCESS | 3777 | RSS: Main: 221954048 16:11:53 INFO - PROCESS | 3777 | 16:11:55 INFO - PROCESS | 3777 | Cycle 1(1): loaded http://localhost:54807/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54807/tests/kraken/driver.html?testName=ai-astar) 16:11:55 INFO - PROCESS | 3777 | RSS: Main: 272695296 16:11:55 INFO - PROCESS | 3777 | 16:11:55 INFO - PROCESS | 3777 | __start_tp_report 16:11:55 INFO - PROCESS | 3777 | _x_x_mozilla_page_load 16:11:55 INFO - PROCESS | 3777 | _x_x_mozilla_page_load_details 16:11:55 INFO - PROCESS | 3777 | |i|pagename|runs| 16:11:55 INFO - PROCESS | 3777 | |0;ai-astar;114;118;118;117;117;116;115;114;115;116 16:11:55 INFO - PROCESS | 3777 | |1;audio-beat-detection;154;126;124;167;129;130;130;123;165;124 16:11:55 INFO - PROCESS | 3777 | |2;audio-dft;161;161;162;162;162;163;161;162;161;160 16:11:55 INFO - PROCESS | 3777 | |3;audio-fft;76;77;78;108;77;78;78;77;100;77 16:11:55 INFO - PROCESS | 3777 | |4;audio-oscillator;84;83;121;83;83;84;118;83;84;120 16:11:55 INFO - PROCESS | 3777 | |5;imaging-gaussian-blur;117;118;118;118;118;117;118;118;118;118 16:11:55 INFO - PROCESS | 3777 | |6;imaging-darkroom;149;146;146;146;146;146;146;146;146;146 16:11:55 INFO - PROCESS | 3777 | |7;imaging-desaturate;95;95;95;96;96;96;96;97;96;96 16:11:55 INFO - PROCESS | 3777 | |8;json-parse-financial;65;64;64;65;66;66;67;66;66;66 16:11:55 INFO - PROCESS | 3777 | |9;json-stringify-tinderbox;66;66;68;66;66;66;65;65;66;66 16:11:55 INFO - PROCESS | 3777 | |10;stanford-crypto-aes;83;81;81;81;82;82;88;82;82;95 16:11:55 INFO - PROCESS | 3777 | |11;stanford-crypto-ccm;132;135;132;140;137;141;132;130;171;140 16:11:55 INFO - PROCESS | 3777 | |12;stanford-crypto-pbkdf2;176;169;178;174;168;171;172;175;169;167 16:11:55 INFO - PROCESS | 3777 | |13;stanford-crypto-sha256-iterative;68;68;68;68;68;73;69;67;68;66 16:11:55 INFO - PROCESS | 3777 | __end_tp_report 16:11:55 INFO - PROCESS | 3777 | __start_cc_report 16:11:55 INFO - PROCESS | 3777 | _x_x_mozilla_cycle_collect,1923 16:11:55 INFO - PROCESS | 3777 | __end_cc_report 16:11:55 INFO - PROCESS | 3777 | __startTimestamp1474067515566__endTimestamp 16:11:55 INFO - PROCESS | 3777 | 16:11:55 INFO - PROCESS | 3777 | ------- Summary: start ------- 16:11:55 INFO - PROCESS | 3777 | Number of tests: 14 16:11:55 INFO - PROCESS | 3777 | 16:11:55 INFO - PROCESS | 3777 | [#0] ai-astar Cycles:10 Average:116.00 Median:116.50 stddev:1.49 (1.3%) stddev-sans-first:1.39 16:11:55 INFO - PROCESS | 3777 | Values: 114.0 118.0 118.0 117.0 117.0 116.0 115.0 114.0 115.0 116.0 16:11:55 INFO - PROCESS | 3777 | 16:11:55 INFO - PROCESS | 3777 | [#1] audio-beat-detection Cycles:10 Average:137.20 Median:130.00 stddev:17.61 (13.5%) stddev-sans-first:17.59 16:11:55 INFO - PROCESS | 3777 | Values: 154.0 126.0 124.0 167.0 129.0 130.0 130.0 123.0 165.0 124.0 16:11:55 INFO - PROCESS | 3777 | 16:11:55 INFO - PROCESS | 3777 | [#2] audio-dft Cycles:10 Average:161.50 Median:162.00 stddev:0.85 (0.5%) stddev-sans-first:0.88 16:11:55 INFO - PROCESS | 3777 | Values: 161.0 161.0 162.0 162.0 162.0 163.0 161.0 162.0 161.0 160.0 16:11:55 INFO - PROCESS | 3777 | 16:11:55 INFO - PROCESS | 3777 | [#3] audio-fft Cycles:10 Average:82.60 Median:77.00 stddev:11.45 (14.9%) stddev-sans-first:11.90 16:11:55 INFO - PROCESS | 3777 | Values: 76.0 77.0 78.0 108.0 77.0 78.0 78.0 77.0 100.0 77.0 16:11:55 INFO - PROCESS | 3777 | 16:11:55 INFO - PROCESS | 3777 | [#4] audio-oscillator Cycles:10 Average:94.30 Median:83.00 stddev:17.52 (21.1%) stddev-sans-first:18.19 16:11:55 INFO - PROCESS | 3777 | Values: 84.0 83.0 121.0 83.0 83.0 84.0 118.0 83.0 84.0 120.0 16:11:55 INFO - PROCESS | 3777 | 16:11:55 INFO - PROCESS | 3777 | [#5] imaging-gaussian-blur Cycles:10 Average:117.80 Median:118.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 16:11:55 INFO - PROCESS | 3777 | Values: 117.0 118.0 118.0 118.0 118.0 117.0 118.0 118.0 118.0 118.0 16:11:55 INFO - PROCESS | 3777 | 16:11:55 INFO - PROCESS | 3777 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.00 stddev:0.95 (0.6%) stddev-sans-first:0.00 16:11:55 INFO - PROCESS | 3777 | Values: 149.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 16:11:55 INFO - PROCESS | 3777 | 16:11:55 INFO - PROCESS | 3777 | [#7] imaging-desaturate Cycles:10 Average:95.80 Median:96.00 stddev:0.63 (0.7%) stddev-sans-first:0.60 16:11:55 INFO - PROCESS | 3777 | Values: 95.0 95.0 95.0 96.0 96.0 96.0 96.0 97.0 96.0 96.0 16:11:55 INFO - PROCESS | 3777 | 16:11:55 INFO - PROCESS | 3777 | [#8] json-parse-financial Cycles:10 Average:65.50 Median:66.00 stddev:0.97 (1.5%) stddev-sans-first:1.01 16:11:55 INFO - PROCESS | 3777 | Values: 65.0 64.0 64.0 65.0 66.0 66.0 67.0 66.0 66.0 66.0 16:11:55 INFO - PROCESS | 3777 | 16:11:55 INFO - PROCESS | 3777 | [#9] json-stringify-tinderbox Cycles:10 Average:66.00 Median:66.00 stddev:0.82 (1.2%) stddev-sans-first:0.87 16:11:55 INFO - PROCESS | 3777 | Values: 66.0 66.0 68.0 66.0 66.0 66.0 65.0 65.0 66.0 66.0 16:11:55 INFO - PROCESS | 3777 | 16:11:55 INFO - PROCESS | 3777 | [#10] stanford-crypto-aes Cycles:10 Average:83.70 Median:82.00 stddev:4.47 (5.5%) stddev-sans-first:4.74 16:11:55 INFO - PROCESS | 3777 | Values: 83.0 81.0 81.0 81.0 82.0 82.0 88.0 82.0 82.0 95.0 16:11:55 INFO - PROCESS | 3777 | 16:11:55 INFO - PROCESS | 3777 | [#11] stanford-crypto-ccm Cycles:10 Average:139.00 Median:138.50 stddev:11.92 (8.6%) stddev-sans-first:12.37 16:11:55 INFO - PROCESS | 3777 | Values: 132.0 135.0 132.0 140.0 137.0 141.0 132.0 130.0 171.0 140.0 16:11:55 INFO - PROCESS | 3777 | 16:11:55 INFO - PROCESS | 3777 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.90 Median:173.00 stddev:3.73 (2.2%) stddev-sans-first:3.64 16:11:55 INFO - PROCESS | 3777 | Values: 176.0 169.0 178.0 174.0 168.0 171.0 172.0 175.0 169.0 167.0 16:11:55 INFO - PROCESS | 3777 | 16:11:55 INFO - PROCESS | 3777 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.30 Median:68.00 stddev:1.83 (2.7%) stddev-sans-first:1.94 16:11:55 INFO - PROCESS | 3777 | Values: 68.0 68.0 68.0 68.0 68.0 73.0 69.0 67.0 68.0 66.0 16:11:55 INFO - PROCESS | 3777 | -------- Summary: end -------- 16:11:55 INFO - PROCESS | 3777 | 16:11:56 INFO - TEST-INFO | 3777: exit 0 16:11:56 INFO - TEST-OK | kraken | took 65018ms 16:11:56 INFO - SUITE-END | took 1189s 16:11:57 INFO - Completed test suite (00:19:49) 16:11:57 INFO - javascript benchmark 16:11:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2586.741325867413, 2611.556035473969, 2637.8021648376366, 2647.0602935957736, 2658.574628059512, 4573.176828170928, 4634.2353511670535, 4761.095391875546, 4772.117158325704, 4840.491748366419, 4638.188317044521, 4675.906481870361, 4692.460367057789, 4723.8819029524275, 4724.62203023759, 4323.286657701476, 4338.305871060631, 4343.543927887574, 4366.56334366564, 4439.311906654474, 4367.0, 4394.165108629366, 4421.093675796454, 4427.933580996288, 4479.126570318789, 4218.514870789868, 4267.871963841089, 4330.13397320534, 4401.0, 4405.82376704933, 4298.806553705091, 4319.611234988834, 4321.719088259253, 4325.653947684178, 4326.632236259922, 4281.48622165339, 4284.121755040209, 4284.828606855722, 4286.314189729659, 4286.5284818669925, 4653.976730116362, 4654.069186162747, 4669.136209801164, 4677.158111539956, 4681.368015317923, 4925.0, 4979.751012449363, 4981.427135879384, 4982.103221420143, 4982.103221420143, 4991.051700176955, 5023.120953833065, 5039.042581909425, 5042.294078828967, 5100.974495127538, 4676.485586585472, 4690.742009189493, 4697.812087516461, 4701.905961880778, 4735.455422626374, 4978.701277923336, 5135.589152867837, 5136.20388839723, 5138.075146473596, 5191.740412979336, 4820.3492528508905, 4909.607231421478, 4953.23224900141, 4960.404751429779, 5015.623828212899, 4994.525520075587, 4996.7001979881325, 4997.725125118153, 5000.1249781288125, 5058.671186372948, 4950.851474455772, 4956.4547899731, 4960.379952505937, 4960.404751429852, 5010.624203184776, 4786.712797232177, 4842.903141937141, 4891.364122664031, 4930.605551555867, 4964.106460837084, 4236.788160591958, 4238.8728338149285, 4241.278982572909, 4290.549492303326, 4291.54938731435, 3707.2029513654693, 3714.2757162353623, 3716.0338312038525, 3754.6808521276, 3766.0, 4235.343521754072, 4284.421603083606, 4307.65538756905, 4337.414449049401, 4353.34699795027, 5062.898742025139, 5069.898602028013, 5074.695518268915, 5115.769790359443, 5136.409312928986, 4348.608625223682, 4349.586789255079, 4353.1293741251875, 4403.779811009436, 4417.558244175557, 4415.962248871512, 4463.709858859163, 4516.751578663205, 4531.184386810406, 4532.501424843264, 4777.570018698334, 4808.3989501312335, 4809.3026511154985, 4842.394700662418, 4866.099771542276, 5104.69371837691, 5114.309568208244, 5123.538881500683, 5126.359205099363, 5176.197689358156, 4620.745858977789, 4656.068786242698, 4791.065742180242, 4795.760211989317, 4864.586510146536, 4966.850994470172, 4996.125678006407, 5011.348524691767, 5058.443571207238, 5061.924071138862, 5047.848564543069, 5052.797888084436, 5055.974720126376, 5056.418511871035, 5109.412417572027, 4887.682300650446, 4940.505949405031, 4942.184539551077, 4942.925856112233, 4983.152864013054, 4813.855584332476, 4822.0, 4848.515148485123, 4862.902741945212, 4991.875203119951, 4740.573348398522, 4771.976140119277, 4775.068861572099, 4827.179379505422, 4843.346148269939, 4853.733044682435, 4878.56092951636, 4906.0, 4911.140550403737, 4935.629827763004, 4819.277108433763, 4821.397325334333, 4824.179889418878, 4825.5174482551465, 4828.686135401188], "value": 4617.038369253269, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [569.7891780041384, 578.3695771608946, 584.1529781816366, 584.3542885111951, 590.6957916672911, 28160.29599260017, 30486.0, 39196.80401597991, 40424.59575404245, 40434.59565404345, 40600.79699601505, 41057.38413923786, 41077.178456430855, 41082.0, 41108.177836443254, 7164.85670286594, 7416.925830741705, 7602.2397760224085, 7603.885941710879, 7607.049118860143, 7036.472264580151, 7231.6745746441275, 7236.457265705067, 9516.476593787338, 9783.651923538335, 9910.752231194207, 9956.60173593059, 9963.25275604329, 9968.40189588627, 10103.747406314864, 9914.504274786232, 9938.354006989559, 9984.850227246598, 9986.900130998669, 9987.600495980152, 7134.143902731654, 7336.266373362674, 9549.570269337826, 9777.706668799947, 9914.0, 37901.43147852785, 37969.430458543146, 38303.808480957414, 38315.425268620995, 38371.0, 2639.1818536253822, 2709.647745793054, 2718.184544636621, 2798.6501687289087, 3328.234506063619, 28392.432151357065, 28643.85678071617, 28647.856760716273, 29002.41995160106, 29320.0, 2994.281372470613, 3034.1049390429876, 3050.847457627132, 3113.097201811472, 3119.672434394401, 268434.0, 269478.0, 270461.64769176225, 270496.0, 271037.0, 44839.327410088874, 45723.31415028777, 45858.54141458609, 53273.73363133237, 53585.0, 47852.521474785506, 54096.45903540914, 55196.448035519934, 55517.722411388495, 60226.096608550906, 13552.254625995665, 13723.794143087862, 13756.86243137576, 13758.587242382744, 13761.31193440324, 9571.760705982406, 9610.66362677303, 9621.89348224949, 9626.855597166048, 9628.75928101803, 14995.2502374883, 15397.769033464283, 15429.768553471707, 15801.288941997638, 15802.60493487649, 6516.869662606816, 6954.200266969363, 7065.2228254891925, 7129.821754456077, 7197.280271972865, 752.9924700752922, 810.525842382213, 884.5886662701874, 924.9676261330821, 955.9426434413957, 25885.611715824278, 27686.824924630997, 27761.0, 27907.581386279224, 27980.720192798217, 880.7313769300425, 901.1439132823713, 925.8009527951523, 935.3919952031098, 972.8346181149221, 14140.151590904374, 14572.41710331596, 14691.502444584446, 14788.260586970824, 15074.608213151952, 369302.0, 376212.0, 376425.0, 376574.0, 378006.0, 42887.57112428898, 43574.128517429475, 47683.76158119187, 48937.26594101091, 49080.0, 1141.7944769941491, 1145.204083162211, 1161.1929708852267, 1204.3556697166973, 1208.9093318001185, 27596.586051209648, 28738.85630571834, 29391.853040735514, 29900.700992990664, 29954.85022574873, 43096.56903430926, 43696.344554832336, 43697.126057479305, 43736.34395484134, 43764.12471750611, 334161.0, 335264.0, 335554.0, 337468.0, 338206.0, 37744.62255377521, 38029.42955855609, 38635.42046869243, 38709.6129038706, 38861.22277554376, 41109.794451027556, 41338.1732365345, 41677.791611042965, 41873.16253674849, 42546.0, 35529.644703552636, 35702.0, 35983.82008089943, 36037.81981090183, 36110.819445902605, 35508.64491355053, 35629.28741425209, 35694.82152589221, 36016.0, 36339.45490817693, 35074.82462587671, 35183.472247915786, 35284.47073293851, 35323.0, 35539.46690799692, 30683.693163068085, 32380.676193237767, 32769.0, 32786.50820237746, 33379.329653628134, 25261.109309114352, 25581.0, 25694.0, 25869.377463195113, 25875.353116172246], "value": 17640.708249766034, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [322.50334484893267, 328.3171004311033, 346.1122221501848, 348.39553374894564, 384.42144572418505, 10491.16070714343, 11327.32036077836, 11339.206255562114, 12532.686682832933, 16323.183840807971, 15503.147326897017, 15613.375464981416, 15622.14078225697, 16169.191540422991, 16616.169191540404, 4919.499552636445, 4928.4825093365125, 4962.305277261186, 5047.0915235257635, 5079.6952182869145, 3509.087637214317, 4956.826511072113, 5043.243513472972, 5048.394192696863, 5048.873778155539, 3664.3037822813712, 5559.416261292567, 5799.884002319951, 5806.390329015456, 5876.7355469004, 5672.234248376458, 5842.620229685057, 5853.092770620539, 5859.531237501011, 5864.736086876101, 5118.539331460149, 5120.206368012964, 5121.948780512184, 5122.590192784568, 5123.129068058439, 18918.3378581749, 19115.80884191154, 19596.902015489974, 19648.0, 19789.703154452694, 1773.5832079461316, 1875.9718604220948, 1967.36060780247, 2118.650422680256, 2383.713954325475, 12263.754724905451, 12629.557965471256, 12834.229946203257, 12840.422181001784, 13355.198688078746, 1659.2284587666672, 1659.2533359988054, 1660.4686500319901, 1682.411156095369, 1756.7189249719984, 68856.96714549286, 69485.30514694839, 69546.99999999949, 69701.65149174273, 71202.64398678025, 8479.0, 8557.529335886462, 8569.614367353484, 8632.0, 9269.119433653792, 11776.175667703179, 11793.823092653616, 11794.941025294818, 11851.88148118525, 11859.881401185878, 6689.264180940093, 6911.89632155518, 6931.341522555349, 7131.714731410685, 7162.856742865113, 6658.4340331071435, 6690.698918548677, 6860.451163906875, 6890.2076261229595, 6915.308469153045, 5480.479354461319, 5644.774209031675, 5652.830415087554, 5684.8010319638615, 5721.5136713379, 1588.610790356347, 1594.4738236381966, 1611.6696077304125, 1641.942532011374, 1691.382645334444, 809.2433574607761, 835.1231207232503, 841.5750046226616, 874.9781255468537, 880.8724832214775, 16327.755083673754, 16466.259018344204, 16727.832721672872, 16919.323227071025, 17451.73822392665, 811.2982270336234, 816.7182322098867, 827.4084029918516, 836.8744688296682, 873.4540911930044, 8925.107489251153, 9314.394564353295, 9722.951385243028, 9736.221102311798, 9812.810836478031, 364575.0, 405297.0, 427239.0, 428739.0, 430166.0, 49797.75101124471, 70516.0, 71214.64392678003, 72612.27387726161, 73711.0, 909.1363204955319, 923.3767207135157, 949.7245798718433, 952.1430712358932, 954.117441366733, 13942.930285348508, 14824.184669843262, 15036.323365448363, 15971.938088157403, 16368.508944731677, 29496.852515737282, 37744.0, 38681.613183868925, 38762.6123738759, 40292.1941561173, 45796.0, 47483.52516474929, 50800.0, 51004.0, 52182.47817521928, 6448.935510644834, 6505.186851643544, 6546.738130474728, 6574.539782215199, 6802.251752307341, 8298.419110662437, 10182.898171018194, 10486.947565262124, 11084.944575277072, 11828.40857957116, 9562.139407453362, 9670.854937175807, 9705.708828735149, 9902.653407130716, 9980.151687106678, 10211.489425528545, 10387.896121038693, 10423.061924426838, 10493.527791249564, 10628.734281643021, 10053.899461005296, 10157.721163330447, 10212.591496340361, 10311.896881031395, 10553.894461055292, 6669.266380698023, 6708.597484150967, 6804.727810887509, 6906.93093069083, 7127.396335824606, 9260.444373337621, 9540.666076687283, 10010.499475026365, 10027.197824174194, 10418.228151701454], "value": 7956.340553013786, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1214.4656351205474, 1294.2622705058118, 1350.6623344163959, 1367.3231750283612, 1641.967160656787, 6375.5218358623115, 7277.454190935681, 7556.055493063374, 7566.772996810091, 7752.573608451533, 7536.660850261737, 7598.0502437195355, 7618.0096587443695, 7621.314081732643, 7652.273034061769, 2101.317071951618, 2124.7875212478784, 2126.1495401839234, 2129.276046144311, 2129.893505324735, 1987.9683335946013, 2037.623039737646, 2129.5315030693223, 2131.424515380846, 2134.3703607435846, 2071.6167509010806, 2084.8227900628463, 2100.0339843671927, 2101.7267755191806, 2121.8514703970727, 2067.204126411335, 2069.3378119001927, 2070.2133189387937, 2071.761747399046, 2073.284716772711, 2037.2564014134882, 2052.72288241087, 2070.0, 2080.854340196187, 2083.5520363122, 7429.591372474566, 7512.060992375953, 7536.397088232929, 7542.321191092773, 7545.283198096227, 4135.152293779768, 4312.180685669744, 4318.85550329163, 4324.6972711910175, 4328.480582330109, 2622.2264431992417, 2755.5728862026417, 2802.8598570071617, 2804.102687140116, 2804.803663743539, 899.0604817965177, 909.4316052467208, 925.4169872980046, 928.3223247029706, 938.3197182043033, 10016.79966400668, 10111.797764044752, 10147.492625368703, 10152.847707284396, 10181.643642472478, 3607.6572725591027, 3679.4296883983025, 3777.112378591029, 3815.923681526354, 3832.5400951885395, 7924.643391047417, 8003.719869804529, 8004.0395152580895, 8009.919900801034, 8012.879806802903, 4155.168966206771, 4157.459530261047, 4160.292750232467, 4164.3961625564125, 4164.583541645811, 3403.5745531808525, 3532.0993146747414, 3794.0894185395305, 3894.7857867816974, 3901.785401802871, 3218.3885061838178, 3219.742420606346, 3309.7517686173633, 3350.9329813404083, 3358.0429577570762, 995.4375777685584, 998.2662742883974, 1000.5147503460815, 1007.0684616729496, 1021.8824835143905, 693.2512886083018, 694.4548529404393, 715.0204220218334, 720.4632548751214, 730.3609341825902, 4790.281457781361, 4961.50384961501, 4966.553010229024, 5026.4973502649445, 5068.56917162038, 367.9760815546981, 384.18169299392116, 385.56238669110763, 385.9421086836997, 389.07982621101456, 3297.3570153819774, 3326.8336583170744, 3338.1487720631085, 3351.7151042161204, 3449.120474279043, 8956.596953137125, 9245.768855778659, 9390.67132650354, 9395.671151509665, 9416.858747118798, 9374.109459601326, 9375.39059961114, 9379.437233765995, 9385.15533601979, 9385.90614093864, 7125.35871771543, 7193.568385896863, 7194.676239569337, 7201.459890508233, 7209.495335326582, 4833.10587541306, 4973.701577905337, 5002.374703162105, 5065.7720402581235, 5229.607779416635, 8647.437916535406, 8695.782605434915, 8710.82578348442, 8722.345824063348, 8727.214550690469, 7972.641231144728, 8319.292860106958, 8323.458975166595, 8782.516961567175, 8784.472931623868, 6312.0, 6439.935600643934, 6444.871102578016, 6455.709493072729, 6463.741450341934, 5898.1152827076285, 6113.480354169946, 6665.466762659073, 6770.729170833309, 6849.24658287578, 6463.224413070461, 6475.838104047437, 6516.283208847118, 6520.054592084027, 6522.0, 6210.378962103753, 6262.06069089622, 6264.436200741955, 6365.586236894587, 6368.044793281045, 6085.786997455068, 6085.786997455245, 6125.908111378422, 6126.6017708848785, 6130.754769809158, 4322.330038843984, 4338.219120558204, 4358.978205108954, 4368.759718215529, 4518.977405112953, 4313.6980411370905, 4393.34099885007, 4402.3616575597, 4531.0, 4623.329617205553], "value": 3808.5929210574022, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [281.7914743090113, 285.21423478317234, 293.67988892107604, 296.1293796239057, 296.7344226916908, 4615.261558150688, 4720.150372932871, 4806.83176088837, 4836.830710925118, 4842.636802239828, 4591.747453890035, 4691.718496890189, 4732.0, 4762.404699412574, 4764.26153946138, 3748.8875333739893, 3759.4924685167493, 3760.6991440684815, 3761.2101458693705, 3772.868139558135, 3560.7329450291204, 3676.246369494247, 3745.8688945886897, 3760.7743535387826, 3767.5290588676417, 3633.200695846923, 3720.2373513429684, 3727.7204209684246, 3743.850245990157, 3746.3818469952425, 3711.2206436648066, 3713.9944430007763, 3730.440433934918, 3732.8133593320226, 3734.533183352081, 3283.868645254187, 3285.211549228192, 3289.243474000993, 3289.3750187464307, 3290.555774970372, 4489.304157855538, 4588.518205588399, 4600.332951721982, 4616.991608650248, 4660.370849935283, 4218.110594242953, 4228.450301460791, 4238.533761286256, 4239.7668128252935, 4251.638610718092, 4950.504949505057, 4950.8762280943265, 4953.254225098868, 4958.132326842824, 4975.676581022222, 3286.6713328667183, 3480.269143479872, 3524.5770507539005, 3560.2345495718287, 3573.9456860226296, 5099.889067668719, 5140.871478213037, 5146.253793200002, 5157.149070403379, 5169.92245116316, 3575.175611267122, 3582.4268117101137, 3588.515550400715, 3589.1874603325155, 3592.4911537154458, 3633.927321453609, 3636.29092326999, 3637.618050104754, 3640.6541378568995, 3646.890593282206, 3932.9016774580864, 4132.91734165315, 4168.291390463568, 4170.812313445902, 4179.4357761701785, 4958.628102892298, 4964.230544265644, 4964.815481188338, 4966.652334336562, 4978.153713868651, 1163.6392718257198, 1195.9940200299118, 1211.8303437518755, 1225.2342286071205, 1227.3617718786359, 840.4159109418895, 858.2833334166041, 861.1431625532636, 861.7543999959981, 863.468966585558, 4390.868273951786, 4396.648268138541, 4413.977930110329, 4417.668674849399, 4426.513083560806, 5103.18349064148, 5103.744812759347, 5108.463611320833, 5109.029284435946, 5114.567920982131, 4243.451140333756, 4391.912161756747, 4414.624756895636, 4420.055175722672, 4423.6903416760515, 2385.61830107182, 2401.2796161151587, 2409.2410890569413, 2419.600765873646, 2428.247243354566, 4298.806553705091, 4302.806373713191, 4313.61177494027, 4316.417283666665, 4320.827166913351, 4407.537208593116, 4557.7466196795485, 4557.906102535368, 4615.907681846412, 4637.121965603936, 4297.431437525281, 4301.0, 4303.806328715215, 4319.460067491564, 4324.243257429937, 4477.962527050119, 4508.977455112704, 4518.344839998247, 4533.821206486271, 4543.227651299353, 4214.325707886724, 4377.43093397869, 4386.5174830767955, 4436.955630443655, 4450.553570089669, 4231.174920890397, 4266.53068162508, 4266.765327906871, 4274.786260687016, 4278.315469524861, 3244.6268679102204, 3246.561714168557, 3262.233375156789, 3266.330402267482, 3285.195127193853, 4481.574250446202, 4498.865034048984, 4500.234960056864, 4516.751578663205, 4524.457065152203, 4183.372494125905, 4398.47218333802, 4424.911501769882, 4450.243458611979, 4461.196984542814, 4429.82280708768, 4431.853384097198, 4437.980718772886, 4445.5776701213335, 4449.911001779881, 4333.548261332489, 4350.869473915787, 4383.356241409471, 4707.552782485658, 4839.685420447659, 4894.14352488306, 5002.549770520671, 5004.247663784977, 5005.274235235797, 5018.1218286800395, 4860.246661767432, 4876.804927802848, 4895.902081958412, 4895.951040489549, 4900.534449227318], "value": 3598.7864996931203, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [574.9338826035005, 578.0491092153408, 611.73083843109, 659.8152517295159, 660.8480049588593, 11135.610253641124, 11144.269653934787, 11465.983884627012, 11603.593874214404, 11610.419479026046, 12065.33640649764, 12089.153759236857, 12095.516179352837, 12096.455659495321, 12136.817947730791, 723.5550136665952, 742.5581778841581, 745.0984308986107, 745.2696357569585, 745.6868115391549, 588.8410129265096, 605.1860247966478, 721.7041013184595, 741.9962900185492, 744.702119152338, 798.9880151797728, 799.1728560939429, 800.1678254615193, 800.4196957206037, 801.4029547986758, 793.5476778236408, 794.972175973841, 795.1174196641723, 795.2246559604397, 795.6300320351006, 453.2702349217757, 461.57304493343526, 550.9421510741395, 567.2342337843919, 581.3517927510795, 12771.233725976379, 12868.227906325557, 12924.935375323157, 12956.157849739737, 12963.351832408342, 12149.271043737404, 12525.812112818225, 12679.0, 12716.872831271754, 12796.744065118739, 11701.414929253504, 11747.588834390755, 11959.0, 12084.154109212359, 12326.568570100004, 3898.9805050974564, 3956.307646161939, 4008.4346018548154, 4166.583341665809, 4166.854160104382, 69723.0, 69917.65041174812, 70422.0, 70730.29269707289, 70875.0, 10062.798744025078, 10191.898081019242, 10251.948740256401, 10252.333598316085, 10421.739456513647, 19348.903255483634, 19434.12546435414, 19438.4168474946, 19444.638875278877, 19447.61104777925, 6515.967420162869, 6524.412802847766, 6529.510286728514, 6669.333066693389, 6804.55770374924, 3582.158192824684, 3610.8014059226994, 3613.8915832525067, 3640.7269454791, 3645.744797864124, 2612.42526644138, 2704.459108178333, 2705.810593258492, 2707.0525316139588, 2719.170652950829, 453.64388954670426, 455.1920341393986, 459.7379493688632, 461.3794446469493, 476.24752890433035, 478.4569513602087, 481.0565463696055, 487.2763945540869, 490.2866329490583, 509.60760214634524, 7263.237360077116, 7447.590382529013, 7486.139094004259, 7565.243475652391, 7675.117361503387, 451.9909601808011, 452.710265430125, 461.10545541649094, 494.94308154561963, 498.71573203273783, 3300.702936735657, 3395.8471868765964, 3463.8787642432394, 3472.3055388922317, 3537.9469307960403, 100836.49581752044, 104910.0, 108657.0, 109723.0, 110368.0, 91647.0, 91901.0, 93960.53019734994, 94540.52729736308, 96397.51801241089, 62292.37707622866, 63023.369766303585, 78575.60712196516, 82535.587322063, 85895.0, 7845.960770196112, 8348.666053357798, 8463.23830855226, 8490.702825401082, 8490.915090849012, 41710.791446042575, 42901.570984289756, 44236.336454953846, 44354.77822610996, 44624.10751785011, 37014.629853702194, 37881.24237515179, 37951.0, 47260.76369618267, 60335.69832150811, 10662.520186591466, 10674.46627668629, 10677.839832402366, 11251.774964500828, 11591.710207244887, 11737.47181376823, 11963.940180299043, 11968.222065565711, 12007.159498834997, 12325.876741232833, 10958.0, 10969.83545246806, 12290.569830055905, 12297.754044919231, 12298.631041068782, 11027.779444411228, 11349.262297950938, 11678.124140689313, 11725.88274117282, 11731.472083756422, 10919.727006824893, 11222.66332010041, 11507.873389533812, 11622.6513204604, 11985.400729963292, 9519.428834269966, 10203.795924081329, 10212.387256764618, 10250.231232657432, 10585.52365143555, 9168.770780730536, 9382.386750998956, 10075.697729068139, 10210.744731381477, 10257.84613230817], "value": 6321.5839128616935, "unit": "score"}], "name": "dromaeo_css", "value": 6188.221218704562, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 118.0, 118.0, 117.0, 117.0, 116.0, 115.0, 114.0, 115.0, 116.0], "value": 116.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 126.0, 124.0, 167.0, 129.0, 130.0, 130.0, 123.0, 165.0, 124.0], "value": 137.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 162.0, 162.0, 162.0, 163.0, 161.0, 162.0, 161.0, 160.0], "value": 161.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 78.0, 108.0, 77.0, 78.0, 78.0, 77.0, 100.0, 77.0], "value": 82.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 121.0, 83.0, 83.0, 84.0, 118.0, 83.0, 84.0, 120.0], "value": 94.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 118.0, 118.0, 118.0, 117.0, 118.0, 118.0, 118.0, 118.0], "value": 117.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 96.0, 96.0, 96.0, 96.0, 97.0, 96.0, 96.0], "value": 95.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 64.0, 65.0, 66.0, 66.0, 67.0, 66.0, 66.0, 66.0], "value": 65.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 68.0, 66.0, 66.0, 66.0, 65.0, 65.0, 66.0, 66.0], "value": 66.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 81.0, 81.0, 81.0, 82.0, 82.0, 88.0, 82.0, 82.0, 95.0], "value": 83.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 135.0, 132.0, 140.0, 137.0, 141.0, 132.0, 130.0, 171.0, 140.0], "value": 139.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 169.0, 178.0, 174.0, 168.0, 171.0, 172.0, 175.0, 169.0, 167.0], "value": 171.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 68.0, 68.0, 68.0, 73.0, 69.0, 67.0, 68.0, 66.0], "value": 68.3, "unit": "score"}], "name": "kraken", "value": 1545.8999999999999, "alertThreshold": 2.0}]} 16:11:57 INFO - Return code: 0 16:11:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:11:57 INFO - # TBPL SUCCESS # 16:11:57 INFO - Running post-action listener: _resource_record_post_action 16:11:57 INFO - [mozharness: 2016-09-16 23:11:57.381503Z] Finished run-tests step (success) 16:11:57 INFO - Running post-run listener: _resource_record_post_run 16:11:57 INFO - Total resource usage - Wall time: 1206s; CPU: 13.0%; Read bytes: 7712768; Write bytes: 421302272; Read time: 1276; Write time: 379308 16:11:57 INFO - TinderboxPrint: CPU usage
13.1% 16:11:57 INFO - TinderboxPrint: I/O read bytes / time
7,712,768 / 1,276 16:11:57 INFO - TinderboxPrint: I/O write bytes / time
421,302,272 / 379,308 16:11:57 INFO - TinderboxPrint: CPU idle
8,356.8 (86.8%) 16:11:57 INFO - TinderboxPrint: CPU user
1,208.2 (12.6%) 16:11:57 INFO - TinderboxPrint: Swap in / out
0 / 0 16:11:57 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 4096; Write bytes: 95666176; Read time: 20; Write time: 67384 16:11:57 INFO - run-tests - Wall time: 1190s; CPU: 13.0%; Read bytes: 5472256; Write bytes: 325636096; Read time: 1056; Write time: 311924 16:11:58 INFO - Running post-run listener: _upload_blobber_files 16:11:58 INFO - Blob upload gear active. 16:11:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:11:58 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 16:11:58 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'] 16:11:58 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 16:11:58 INFO - (blobuploader) - INFO - Open directory for files ... 16:11:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:11:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:11:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:11:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:11:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:11:59 INFO - (blobuploader) - INFO - Done attempting. 16:11:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:11:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:11:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:12:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:12:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:12:00 INFO - (blobuploader) - INFO - Done attempting. 16:12:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:12:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:12:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:12:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:12:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:12:05 INFO - (blobuploader) - INFO - Done attempting. 16:12:05 INFO - (blobuploader) - INFO - Iteration through files over. 16:12:05 INFO - Return code: 0 16:12:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:12:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:12:05 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b40f107055b030d855908cca34b1dd07841a13ed00d5392100d29ca95f6dccd46e8938e8e764e21205ba37b10471c49d3a707796d06f8749f363be2240b0a2b7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e0bbc824b957aa450b143e713159fd3a146a15439522af54351c9e3aa9d77e5be9a9a6715c50eecdd8be7ea69476199e0ecc5875e124cba99b0cb68144bb619e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/81f34841e2df24bd24e93d550e30addc22d6a1091b6267f7ac2c68ff06267e70ffccfbbeb9f2e1242f0733d31f3f9f49f53183f85ea311dc8bd3e5603e27c05b"} 16:12:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:12:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:12:05 INFO - Contents: 16:12:05 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b40f107055b030d855908cca34b1dd07841a13ed00d5392100d29ca95f6dccd46e8938e8e764e21205ba37b10471c49d3a707796d06f8749f363be2240b0a2b7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e0bbc824b957aa450b143e713159fd3a146a15439522af54351c9e3aa9d77e5be9a9a6715c50eecdd8be7ea69476199e0ecc5875e124cba99b0cb68144bb619e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/81f34841e2df24bd24e93d550e30addc22d6a1091b6267f7ac2c68ff06267e70ffccfbbeb9f2e1242f0733d31f3f9f49f53183f85ea311dc8bd3e5603e27c05b"} 16:12:05 INFO - Running post-run listener: copy_logs_to_upload_dir 16:12:05 INFO - Copying logs to upload dir... 16:12:05 INFO - mkdir: /builds/slave/test/build/upload/logs 16:12:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1254.144503 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2016-09-16 16:12:06.019743) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-16 16:12:06.027114) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=d6bbd613908ccac0c6c9cb47000001f7-1474065853.992838-1013234262 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/b40f107055b030d855908cca34b1dd07841a13ed00d5392100d29ca95f6dccd46e8938e8e764e21205ba37b10471c49d3a707796d06f8749f363be2240b0a2b7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e0bbc824b957aa450b143e713159fd3a146a15439522af54351c9e3aa9d77e5be9a9a6715c50eecdd8be7ea69476199e0ecc5875e124cba99b0cb68144bb619e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/81f34841e2df24bd24e93d550e30addc22d6a1091b6267f7ac2c68ff06267e70ffccfbbeb9f2e1242f0733d31f3f9f49f53183f85ea311dc8bd3e5603e27c05b"} build_url:https://queue.taskcluster.net/v1/task/A3vR-t0sQlecNA8-Bm1aJw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011186 build_url: 'https://queue.taskcluster.net/v1/task/A3vR-t0sQlecNA8-Bm1aJw/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/b40f107055b030d855908cca34b1dd07841a13ed00d5392100d29ca95f6dccd46e8938e8e764e21205ba37b10471c49d3a707796d06f8749f363be2240b0a2b7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e0bbc824b957aa450b143e713159fd3a146a15439522af54351c9e3aa9d77e5be9a9a6715c50eecdd8be7ea69476199e0ecc5875e124cba99b0cb68144bb619e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/81f34841e2df24bd24e93d550e30addc22d6a1091b6267f7ac2c68ff06267e70ffccfbbeb9f2e1242f0733d31f3f9f49f53183f85ea311dc8bd3e5603e27c05b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-16 16:12:06.055322) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 16:12:06.055676) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=d6bbd613908ccac0c6c9cb47000001f7-1474065853.992838-1013234262 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004465 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 16:12:06.140644) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-16 16:12:06.141006) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-16 16:12:06.141345) ========= ========= Total master_lag: 0.25 =========